[mythtv-commits] Ticket #13543: Corruption of recordings seems to be linked to system events

Thu Dec 26 12:06:19 UTC 2019

#13543: Corruption of recordings seems to be linked to system events
--------------------------------------+--------------------------
     Reporter:  jpilk                 |      Owner:  (none)
         Type:  Bug Report - General  |     Status:  new
     Priority:  minor                 |  Milestone:  needs_triage
    Component:  MythTV - General      |    Version:  Master Head
     Severity:  medium                |   Keywords:
Ticket locked:  0                     |
--------------------------------------+--------------------------
 New recordings from DVB-T with EIT have packets out of order at times that
 seem to be related to scheduling or perhaps other system events.  When
 processed by Project-X this causes drops of individual GOPs from files
 that otherwise play well.

 http://lists.mythtv.org/pipermail/mythtv-users/2019-December/401922.html

 {{{

 2019-12-25 19:28:39.871170 I  Reschedule requested for MATCH 0 1 1
 2019-12-25T19:35:00Z EITScanner
 2019-12-25 19:28:40.020122 I  Scheduled 81 items in 0.1 = 0.01 match +
 0.01 check + 0.03 place
 2019-12-25 19:29:42.925171 N  AutoExpire: CalcParams(): Max required Free
 Space: 10.0 GB w/freq: 7 min
 2019-12-25 19:33:32.003281 I  Reschedule requested for MATCH 0 1 1
 2019-12-25T21:45:00Z EITScanner
 2019-12-25 19:33:32.157194 I  Scheduled 81 items in 0.1 = 0.01 match +
 0.01 check + 0.03 place
 2019-12-25 19:34:00.200212 I  TVRec[1]: Changing from RecordingOnly to
 None
 2019-12-25 19:34:00.359770 N  Finished Recording: Container: MPEG2-TS
 Video Codec: none (0x0 A/R: 0 25fps) Audio Codec: mp2
 2019-12-25 19:34:00.366041 E  MainServer: PREVIEW_SUCCESS but no
 receivers.
 2019-12-25 19:34:00.366372 I  Finished recording Words and Music: channel
 10703
 2019-12-25 19:34:00.366462 I  Updating status for "Words and Music" on
 cardid [1] (Recording => Recorded)
 2019-12-25 19:34:00.388458 I  Reschedule requested for CHECK -3 26848 0
 UpdateRecStatus2 | Words and Music |  | Texts and music on the theme of
 swans, with readers Anthony Calf and Louise Jameson. Including Yeats,
 Rilke and Tennyson, plus Saint-Saens, Villa-Lobos, Tchaikovsky and
 Sibelius. | fp.bbc.co.uk/b/3yoauw
 2019-12-25 19:34:00.551320 I  Scheduled 80 items in 0.1 = 0.03 match +
 0.00 check + 0.03 place
 2019-12-25 19:34:04.585055 I  MainServer: MainServer::ANN Monitor
 2019-12-25 19:34:04.585074 I  MainServer: adding: hpbox(2353dd0) as a
 client (events: 0)
 2019-12-25 19:34:04.586852 I  MainServer: MainServer::ANN Monitor
 2019-12-25 19:34:04.586874 I  MainServer: adding: hpbox(28d7470) as a
 client (events: 1)
 2019-12-25 19:34:14.297881 I  Monitor sock(2353dd0) 'hpbox' disconnected
 2019-12-25 19:34:14.300736 I  Monitor sock(28d7470) 'hpbox' disconnected
 2019-12-25 19:36:42.932107 N  AutoExpire: CalcParams(): Max required Free
 Space: 10.0 GB w/freq: 14 min
 2019-12-25 19:50:42.939335 N  AutoExpire: CalcParams(): Max required Free
 Space: 10.0 GB w/freq: 14 min
 2019-12-25 19:58:00.000997 I  Reschedule requested for PLACE
 PrepareToRecord
 2019-12-25 19:58:00.131761 I  Scheduled 80 items in 0.0 = 0.00 match +
 0.00 check + 0.03 place
 ============
 Extract from cutting log of recording with scheduled starttime
 201912251900

 $ cat mythGOPdrop2.txt | grep -A1 "dropping GOP"
 !> dropping GOP# 2271 @ orig.PTS 02:45:08.322 (891749060), errorcode: 24
 !> Pics exp/cnt 23/3, inGOP PTS diff. 1920ms, new Timecode 00:35:23.280
 --
 !> dropping GOP# 3862 @ orig.PTS 03:10:07.682 (1026691460), errorcode: 24
 !> Pics exp/cnt 24/19, inGOP PTS diff. 1920ms, new Timecode 01:00:19.680
 --
 !> dropping GOP# 5720 @ orig.PTS 03:39:11.642 (1183647860), errorcode: 24
 !> Pics exp/cnt 27/17, inGOP PTS diff. 1520ms, new Timecode 01:29:20.680
 --
 !> dropping GOP# 6092 @ orig.PTS 03:45:06.082 (1215547460), errorcode: 24
 !> Pics exp/cnt 25/35, inGOP PTS diff. 2880ms, new Timecode 01:35:12.440
 --
 !> dropping GOP# 7466 @ orig.PTS 04:06:34.882 (1331539460), errorcode: 24
 !> Pics exp/cnt 24/8, inGOP PTS diff. 2880ms, new Timecode 01:56:37.320
 --
 !> dropping GOP# 7805 @ orig.PTS 04:11:56.482 (1360483460), errorcode: 20
 !> Pics exp/cnt 12/12, inGOP PTS diff. 3840ms, new Timecode 02:01:55.000
 --
 !> dropping GOP# 7944 @ orig.PTS 04:14:10.642 (1372557860), errorcode: 24
 !> Pics exp/cnt 25/30, inGOP PTS diff. 2400ms, new Timecode 02:04:04.840
 --
 !> dropping GOP# 7958 @ orig.PTS 04:14:25.762 (1373918660), errorcode: 24
 !> Pics exp/cnt 24/23, inGOP PTS diff. 3840ms, new Timecode 02:04:16.480
 --
 !> dropping GOP# 8070 @ orig.PTS 04:16:15.522 (1383797060), errorcode: 24
 !> Pics exp/cnt 23/11, inGOP PTS diff. 960ms, new Timecode 02:06:01.360
 --

 }}}

-- 
Ticket URL: <https://code.mythtv.org/trac/ticket/13543>
MythTV <http://www.mythtv.org>
MythTV Media Center