Bug #4958: TVH commences recording too soon with pre-padding set and using EIT p/f - Tvheadend

Bug #4958

TVH commences recording too soon with pre-padding set and using EIT p/f

Added by dj bloc over 6 years ago. Updated over 6 years ago.

Status:

New

Priority:

Normal

Assignee:

Category:

Target version:

Start date:

2018-02-20

Due date:

% Done:

Estimated time:

Found in version:

4.2.5-31~gfc63a8a

Affected Versions:

Description

Taking advice from #4927 I tried tonight setting pre-padding recording to 10 mins and reduce warm up time to 30s. However the recording fails with file no created.

In the debug logs I note it tries to start the recording to HDD twice? Once at the pre-padding time and tries again at the EIT p/f start mark.

Take tonight's programme of "Marcella" on ITV (in the UK) that was scheduled to start at 22:00.

Feb 19 20:45:37 haribo tvheadend[9579]: dvr: entry ebfa0b30516624819dfa766ca4059f0d "New: Marcella" on "ITV HD" starting at 2018-02-19 20:49:30, scheduled for recording by "127.0.0.1" 
Feb 19 20:45:40 haribo tvheadend[9579]: settings: saving to /home/hts/.hts/tvheadend/dvr/log/ebfa0b30516624819dfa766ca4059f0d
Feb 19 20:49:30 haribo tvheadend[9579]: dvr: "New: Marcella" on "ITV HD" recorder starting
Feb 19 20:49:30 haribo tvheadend[9579]: dvr: About to set stop timer for "New: Marcella" on "ITV HD" at start 1519074000 and original stop 1519077600 and overall stop at 1519077600
Feb 19 20:49:30 haribo tvheadend[9579]: subscription: 0037: "DVR: New: Marcella" subscribing on channel "ITV HD", weight: 200, adapter: "HDHomeRun DVB-T Tuner #0 (192.168.88.192)", network: "Hannington", mux: "618.167MHz", service: "ITV HD", profile="pass" 
Feb 19 20:50:00 haribo tvheadend[9579]: dvr: File "/mnt/personalcloud/ultimatetv/media/ultimatetv/.inprogress/ITV HD/2018-02-19-21-00-New_ Marcella.ts" -- No such file or directory -- Using for recording
Feb 19 20:50:00 haribo tvheadend[9579]: dvr: /mnt/personalcloud/ultimatetv/media/ultimatetv/.inprogress/ITV HD/2018-02-19-21-00-New_ Marcella.ts from adapter: "HDHomeRun DVB-T Tuner #0 (192.168.88.192)", network: "Hannington", mux: "618.167MHz", provider: "<N/A>", service: "ITV HD" 
Feb 19 20:50:00 haribo tvheadend[9579]: dvr:  #  type              lang  resolution  aspect ratio  sample rate  channels
Feb 19 20:50:00 haribo tvheadend[9579]: dvr:  1  H264                    1920x1080   ?                                    
Feb 19 20:50:00 haribo tvheadend[9579]: dvr:  2  AAC               eng                             ?            ?         
Feb 19 20:50:00 haribo tvheadend[9579]: dvr:  3  AAC               eng                             ?            ?         
Feb 19 20:50:00 haribo tvheadend[9579]: dvr:  4  DVBSUB            eng                                            
Feb 19 20:55:04 haribo tvheadend[9579]: dvr: dvr entry ebfa0b30516624819dfa766ca4059f0d other running event Coronation Street on ITV HD - EPG marking stop        
Feb 19 20:59:44 haribo tvheadend[9579]: dvr: dvr entry ebfa0b30516624819dfa766ca4059f0d event New: Marcella on ITV HD - EPG marking start
Feb 19 20:59:44 haribo tvheadend[9579]: dvr: Overwrite protection, file "/mnt/personalcloud/ultimatetv/media/ultimatetv/.inprogress/ITV HD/2018-02-19-21-00-New_ Marcella.ts" exists
Feb 19 20:59:44 haribo tvheadend[9579]: dvr: unable to create unique name (missing $n in format string?)
Feb 19 20:59:44 haribo tvheadend[9579]: dvr: Recording error: "/mnt/personalcloud/ultimatetv/media/ultimatetv/.inprogress/ITV HD/2018-02-19-21-00-New_ Marcella.ts": Unable to create file
Feb 19 20:59:44 haribo tvheadend[9579]: subscription: 0037: "DVR: New: Marcella" unsubscribing from "ITV HD" 
Feb 19 20:59:44 haribo tvheadend[9579]: mpegts: 618.167MHz in Hannington - close PID 00C8 (200) [16/0x7f0e6000ab70]
Feb 19 20:59:44 haribo tvheadend[9579]: mpegts: 618.167MHz in Hannington - close PID 00C8 (200) [8/0x55c5da903e00]
Feb 19 20:59:44 haribo tvheadend[9579]: mpegts: 618.167MHz in Hannington - close PID 00C9 (201) [8/0x55c5da903e00]
Feb 19 20:59:44 haribo tvheadend[9579]: mpegts: 618.167MHz in Hannington - close PID 00CA (202) [8/0x55c5da903e00]
Feb 19 20:59:44 haribo tvheadend[9579]: mpegts: 618.167MHz in Hannington - close PID 00CE (206) [8/0x55c5da903e00]
Feb 19 20:59:44 haribo tvheadend[9579]: mpegts: 618.167MHz in Hannington - close PID 00CD (205) [8/0x55c5da903e00]
Feb 19 20:59:44 haribo tvheadend[9579]: dvr: "New: Marcella" on "ITV HD": End of program: File not created
Feb 19 20:59:47 haribo tvheadend[9579]: settings: saving to /home/hts/.hts/tvheadend/dvr/log/ebfa0b30516624819dfa766ca4059f0d
Feb 19 21:55:03 haribo tvheadend[9579]: dvr: dvr entry 6b06e5dc5fb03945b041db320c4bde90 other running event New: Marcella on ITV HD - EPG marking stop
Feb 19 22:02:02 haribo tvheadend[9579]: dvr: dvr entry ebfa0b30516624819dfa766ca4059f0d event New: Marcella on ITV HD - EPG marking stop

Files

prepadding_eit_4958.log.gz (8.83 MB) prepadding_eit_4958.log.gz		dj bloc, 2018-02-24 15:59
2018-02-22-21-00-New_ John Worboys_ The Taxi Cab....ts (12.9 MB) 2018-02-22-21-00-New_ John Worboys_ The Taxi Cab....ts		dj bloc, 2018-02-24 16:00

History

Updated by Jaroslav Kysela over 6 years ago

Update to 4.2.5-33 and show '--trace dvr'. https://tvheadend.org/projects/tvheadend/wiki/Traces

Also, does the first (pre-padding) file contain some data?

Updated by dj bloc over 6 years ago

File prepadding_eit_4958.log.gz prepadding_eit_4958.log.gz added
File 2018-02-22-21-00-New_ John Worboys_ The Taxi Cab....ts 2018-02-22-21-00-New_ John Worboys_ The Taxi Cab....ts added

Jaroslav Kysela wrote:

Update to 4.2.5-33 and show '--trace dvr'. https://tvheadend.org/projects/tvheadend/wiki/Traces

Upgraded to 4.2.5-33~g5170252 and re-set the pre-padding and warm up parameters as above. Been running for a few days with trace. From what I see it fails only occasionally. It is not specific to any one channel. It affects multiple channels. In the attached trace log I've picked out two examples:

Works correctly - entry edaec29b2c998b2540b2e1db7af4e96d - Lethal Weapon...
Fails as above - entry ea1d5a8b80bec76f311de771d0b3c9e3 - John Worboys: The Taxi Cab...