Project

General

Profile

Bug #4958

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

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

Status:
New
Priority:
Normal
Assignee:
-
Category:
-
Target version:
-
Start date:
2018-02-20
Due date:
% Done:

0%

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

Associated revisions

Revision d2e55e40 (diff)
Added by Jaroslav Kysela over 3 years ago

DVR: try to fix the late running flag update, issue #4958

Revision 1b87e81b (diff)
Added by Jaroslav Kysela over 3 years ago

DVR: try to fix the late running flag update, issue #4958

History

#1

Updated by Jaroslav Kysela over 3 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?

#2

Updated by dj bloc over 3 years ago

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...

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

Yes, also uploaded the John Worboys recording.

#3

Updated by Jaroslav Kysela over 3 years ago

Thanks. Could you identify from the recodings what event was before 'John Worboys'?

#4

Updated by Jaroslav Kysela over 3 years ago

I mean the previous event on the same channel as 'John Worboys'...

#5

Updated by Jaroslav Kysela over 3 years ago

I found it in the log - "Coronation Street on ITV HD".

#6

Updated by Jaroslav Kysela over 3 years ago

Please, try v4.2.5-41-gd42706d08 .

#7

Updated by dj bloc over 3 years ago

Jaroslav Kysela wrote:

Please, try v4.2.5-41-gd42706d08 .

Thanks. Installed last night and will run for a few days with trace. First signs are promising - no recordings last night failed.

Will report back.

#8

Updated by dj bloc over 3 years ago

dj bloc wrote:

Will report back.

A few days later and still all recordings are working correctly. Consider this as fixed. Thanks very much.

Also available in: Atom PDF