[mythtv-commits] Ticket #2563: LiveTV freeze when show ends

MythTV mythtv at cvs.mythtv.org
Mon Oct 16 15:23:44 UTC 2006


#2563: LiveTV freeze when show ends
------------------------------+---------------------------------------------
 Reporter:  geoffp at gmail.com  |        Owner:  ijr    
     Type:  defect            |       Status:  new    
 Priority:  minor             |    Milestone:  unknown
Component:  mythtv            |      Version:  0.20   
 Severity:  medium            |   Resolution:         
------------------------------+---------------------------------------------
Comment (by richard_runds at yahoo.co.uk):

 Hi,

 I'm experiencing exactly the same problem. I am running FC4 with the
 latest 0.20 from ATrpms (0.20-140.fc4) and ivtv-
 kmdl-2.6.17-1.2142_FC4smp-0.7.1-118.

 The frontend log:


 {{{
 [...]

 2006-10-09 22:30:00.641 NVP: A/V Divergence: -0.00015, Rate: 5e-05,
 Warpfactor: 0.999271, warpfactor_avg: 0.999271
 2006-10-09 22:30:00.641 AO: audio waiting for space on soundcard: have
 2104 need 6144
 2006-10-09 22:30:00.649 AO: audio waiting for space on soundcard: have
 3684 need 6144
 2006-10-09 22:30:00.656 MythSocket(b7c1d338:23): socket is readable
 2006-10-09 22:30:00.657 MythSocket(b7c1d338:23): cb->readyRead()
 2006-10-09 22:30:00.657 read  <- 23 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
 2006-10-09 22:30:00.661 AO: audio waiting for space on soundcard: have
 5948 need 6144
 2006-10-09 22:30:00.665 MythSocket(b7c1d338:23): socket is readable
 2006-10-09 22:30:00.665 MythSocket(b7c1d338:23): cb->readyRead()
 2006-10-09 22:30:00.665 read  <- 23 78
 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-
 pvr-2006-10-09T22:27:30[]:[]empty
 2006-10-09 22:30:00.669 AO: Broadcasting free space avail
 2006-10-09 22:30:00.670 WriteAudio: Preparing 6144 bytes (1536 frames)
 2006-10-09 22:30:00.670 AO: audio waiting for space on soundcard: have
 1416 need 6144
 2006-10-09 22:30:00.674 MSqlQuery: SELECT chanid, starttime, endtime,
 discontinuity, chainpos, hostprefix, cardtype, channame, input FROM
 tvchain WHERE chainid = 'live-p
 vr-2006-10-09T22:27:30' ORDER BY chainpos;
 2006-10-09 22:30:00.681 NVP: A/V Divergence: -0.0001, Rate: 5e-05,
 Warpfactor: 0.999271, warpfactor_avg: 0.999271
 2006-10-09 22:30:00.681 AO: audio waiting for space on soundcard: have
 3656 need 6144
 2006-10-09 22:30:00.689 MythSocket(b7c1d338:23): socket is readable
 2006-10-09 22:30:00.689 MythSocket(b7c1d338:23): cb->readyRead()
 2006-10-09 22:30:00.689 read  <- 23 78
 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-
 pvr-2006-10-09T22:27:30[]:[]empty
 2006-10-09 22:30:00.693 AO: audio waiting for space on soundcard: have
 5932 need 6144
 2006-10-09 22:30:00.701 AO: Broadcasting free space avail
 2006-10-09 22:30:00.702 WriteAudio: Preparing 6144 bytes (1536 frames)
 2006-10-09 22:30:00.702 AO: audio waiting for space on soundcard: have
 1424 need 6144
 2006-10-09 22:30:00.703 MSqlQuery: SELECT chanid, starttime, endtime,
 discontinuity, chainpos, hostprefix, cardtype, channame, input FROM
 tvchain WHERE chainid = 'live-p
 vr-2006-10-09T22:27:30' ORDER BY chainpos;
 2006-10-09 22:30:00.704 LiveTVChain(live-pvr-2006-10-09T22:27:30):
 ReloadAll(): Added new recording
 2006-10-09 22:30:00.704 Resyncing position map. posmapStarted = 0
 livetv(1) watchingRec(0)
 2006-10-09 22:30:00.705 MSqlQuery: SELECT mark, offset FROM recordedseek
 WHERE chanid = '1045' AND starttime = '2006-10-09T22:29:25' AND type = 6 ;
 2006-10-09 22:30:00.706 Position map filled from DB to: 76
 2006-10-09 22:30:00.706 SyncPositionMap watchingrecording, from DB: 76
 entries
 2006-10-09 22:30:00.706 SyncPositionMap watchingrecording no entries from
 encoder, try DB
 2006-10-09 22:30:00.707 MSqlQuery: SELECT mark, offset FROM recordedseek
 WHERE chanid = '1045' AND starttime = '2006-10-09T22:29:25' AND type = 6 ;
 2006-10-09 22:30:00.708 Position map filled from DB to: 76
 2006-10-09 22:30:00.709 SyncPositionMap watchingrecording total: 76
 entries
 2006-10-09 22:30:00.709 SyncPositionMap, new totframes: 912, new length:
 36, posMap size: 76
 2006-10-09 22:30:00.713 AO: audio waiting for space on soundcard: have
 3632 need 6144
 2006-10-09 22:30:00.721 AO: audio waiting for space on soundcard: have
 5176 need 6144
 2006-10-09 22:30:00.722 NVP: A/V Divergence: -0.012575, Rate: -0.012475,
 Warpfactor: 0.999071, warpfactor_avg: 0.999271
 2006-10-09 22:30:00.733 AO: Broadcasting free space avail
 2006-10-09 22:30:00.733 WriteAudio: Preparing 6144 bytes (1536 frames)
 2006-10-09 22:30:00.733 AO: audio waiting for space on soundcard: have
 1392 need 6144
 2006-10-09 22:30:00.745 AO: audio waiting for space on soundcard: have
 3664 need 6144
 2006-10-09 22:30:00.746 AFD: video timecode 13352436 13341636 148240
 148140
 2006-10-09 22:30:00.747 AFD: audio timecode 13332612 13332612 148140
 148164

 [...cut audio messages...]

 2006-10-09 22:30:00.842 AFD: audio timecode 13349892 13349892 148332
 148356
 2006-10-09 22:30:00.843 AO: _AddSamples bytes=4608, used=139885,
 free=628115, timecode=148332
 2006-10-09 22:30:00.843 AO: audio waiting for space on soundcard: have
 3936 need 6144
 2006-10-09 22:30:00.853 AO: audio waiting for space on soundcard: have
 5984 need 6144
 2006-10-09 22:30:00.859 MythSocket(b7c1d338:23): socket is readable
 2006-10-09 22:30:00.859 MythSocket(b7c1d338:23): cb->readyRead()
 2006-10-09 22:30:00.859 read  <- 23 46
 BACKEND_MESSAGE[]:[]LIVETV_WATCH 1 0[]:[]empty
 2006-10-09 22:30:00.865 AO: Broadcasting free space avail
 2006-10-09 22:30:00.865 WriteAudio: Preparing 6144 bytes (1536 frames)
 2006-10-09 22:30:00.865 AO: audio waiting for space on soundcard: have
 2144 need 6144
 2006-10-09 22:30:00.877 AO: audio waiting for space on soundcard: have
 4404 need 6144
 2006-10-09 22:30:00.878 AFD: audio timecode 13352052 13352052 148356
 148380

 [... cut audio messages...]

 2006-10-09 22:30:01.429 AO: audio waiting for space on soundcard: have
 5992 need 6144
 2006-10-09 22:30:01.437 AO: Broadcasting free space avail
 2006-10-09 22:30:01.437 WriteAudio: Preparing 6144 bytes (1536 frames)
 2006-10-09 22:30:01.437 AO: audio waiting for space on soundcard: have
 1380 need 6144
 2006-10-09 22:30:01.441 NVP: A/V Divergence: -0.00035, Rate: 0.000125,
 Warpfactor: 0.99927, warpfactor_avg: 0.999271
 2006-10-09 22:30:01.446 AO: audio waiting for space on soundcard: have
 3016 need 6144
 2006-10-09 22:30:01.448 MythSocket(b7c1d338:23): socket is readable
 2006-10-09 22:30:01.448 MythSocket(b7c1d338:23): cb->readyRead()
 2006-10-09 22:30:01.448 read  <- 23 78
 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-
 pvr-2006-10-09T22:27:30[]:[]empty
 2006-10-09 22:30:01.457 AO: audio waiting for space on soundcard: have
 5196 need 6144
 2006-10-09 22:30:01.463 MSqlQuery: SELECT chanid, starttime, endtime,
 discontinuity, chainpos, hostprefix, cardtype, channame, input FROM
 tvchain WHERE chainid = 'live-p
 vr-2006-10-09T22:27:30' ORDER BY chainpos;
 2006-10-09 22:30:01.465 AO: Broadcasting free space avail
 2006-10-09 22:30:01.465 WriteAudio: Preparing 6144 bytes (1536 frames)
 2006-10-09 22:30:01.465 AO: audio waiting for space on soundcard: have 632
 need 6144
 2006-10-09 22:30:01.470 AFD: audio timecode 13390932 13390932 148788
 148812
 2006-10-09 22:30:01.470 AO: _AddSamples bytes=4608, used=104557,
 free=663443, timecode=148788
 2006-10-09 22:30:01.475 AFD: video timecode 13402836 13402836 148920
 148812
 2006-10-09 22:30:01.476 AFD: audio timecode 13393092 13393092 148812
 148836
 2006-10-09 22:30:01.476 AO: _AddSamples bytes=4608, used=109165,
 free=658835, timecode=148812
 2006-10-09 22:30:01.476 AFD: audio timecode 13395252 13395252 148836
 148860
 2006-10-09 22:30:01.476 AO: _AddSamples bytes=4608, used=113773,
 free=654227, timecode=148836
 2006-10-09 22:30:01.477 AO: audio waiting for space on soundcard: have
 2900 need 6144
 [... cut audio messages...]
 2006-10-09 22:30:01.841 AO: audio waiting for space on soundcard: have
 5220 need 6144
 2006-10-09 22:30:01.849 AO: Broadcasting free space avail
 2006-10-09 22:30:01.849 WriteAudio: Preparing 6144 bytes (1536 frames)
 2006-10-09 22:30:01.849 AO: audio waiting for space on soundcard: have 628
 need 6144
 2006-10-09 22:30:01.857 LiveTVChain(live-pvr-2006-10-09T22:27:30):
 SwitchTo(2)
 2006-10-09 22:30:01.857 AO: audio waiting for space on soundcard: have
 2136 need 6144
 2006-10-09 22:30:01.857 LiveTVChain(live-pvr-2006-10-09T22:27:30):
 Entry at 2: '1045_20061009223000'
 2006-10-09 22:30:01.865 AO: audio waiting for space on soundcard: have
 3692 need 6144
 2006-10-09 22:30:01.866 AFD: video timecode 13413636 13413636 149040
 148956
 2006-10-09 22:30:01.866 NVP: IsReallyNearEnd() br(500KB) fps(24) sz(0KB)
 vfl(19) frh(0) ne:1
 2006-10-09 22:30:01.866 SwitchToProgram(void)
 2006-10-09 22:30:01.867 MSqlQuery: SELECT
 recorded.chanid,starttime,endtime,title,
 subtitle,description,channel.channum,
 channel.callsign,channel.name,channel.commfree,
 channel.outputfilters,seriesid,programid,filesize,
 lastmodified,stars,previouslyshown,originalairdate,
 hostname,recordid,transcoder,playgroup, recorded.recpriority,progs
 tart,progend,basename,recgroup FROM recorded LEFT JOIN channel ON
 recorded.chanid = channel.chanid WHERE recorded.chanid = '1045' AND
 starttime = '2006-10-09T22:30:00' ;
 2006-10-09 22:30:01.868 MSqlQuery: SELECT commflagged, cutlist,
 autoexpire, editing, bookmark, stereo, closecaptioned, hdtv FROM recorded
 LEFT JOIN recordedprogram ON (r
 ecorded.chanid = recordedprogram.chanid AND recorded.starttime =
 recordedprogram.starttime) WHERE recorded.chanid = '1045' AND
 recorded.starttime = '2006-10-09T22:30:00'
 ;
 2006-10-09 22:30:01.869 MSqlQuery: DELETE FROM inuseprograms WHERE chanid
 = '1045' AND starttime = '2006-10-09T22:29:25' AND hostname = 'pvr' AND
 recusage = 'player' ;
 2006-10-09 22:30:01.869 write -> 26 33
 MESSAGE[]:[]RECORDING_LIST_CHANGE
 2006-10-09 22:30:01.869 read  <- 26 2       OK
 2006-10-09 22:30:01.870 MythSocket(b7c1d338:23): socket is readable
 2006-10-09 22:30:01.870 MythSocket(b7c1d338:23): cb->readyRead()
 2006-10-09 22:30:01.870 read  <- 23 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
 2006-10-09 22:30:01.870 MSqlQuery: DELETE FROM inuseprograms WHERE chanid
 = '1045' AND starttime = '2006-10-09T22:30:00' AND hostname = 'pvr' AND
 recusage = 'player' ;
 2006-10-09 22:30:01.871 MSqlQuery: INSERT INTO inuseprograms  (chanid,
 starttime, recusage, hostname, lastupdatetime)  VALUES  ('1045',
 '2006-10-09T22:30:00', 'player',
 'pvr', '2006-10-09T22:30:01');
 2006-10-09 22:30:01.871 write -> 26 33
 MESSAGE[]:[]RECORDING_LIST_CHANGE
 2006-10-09 22:30:01.871 MythSocket(b7c1d338:23): socket is readable
 2006-10-09 22:30:01.871 MythSocket(b7c1d338:23): cb->readyRead()
 2006-10-09 22:30:01.871 read  <- 23 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
 2006-10-09 22:30:01.872 read  <- 26 2       OK
 2006-10-09 22:30:01.873
 RingBuf(/var/mythtv/recordings/1045_20061009222925.mpg):
 OpenFile(myth://192.168.0.4:6543/1045_20061009223000.mpg, 10)
 2006-10-09 22:30:01.874
 RingBuf(/var/mythtv/recordings/1045_20061009223000.mpg):
 CalcReadAheadThresh(4000 KB)
                          -> threshhold(146 KB) min read(32 KB) blk size(64
 KB)
 2006-10-09 22:30:01.874 Ignoring livetv eof in decoder loop
 2006-10-09 22:30:01.877 AO: audio waiting for space on soundcard: have
 5980 need 6144
 2006-10-09 22:30:01.881 NVP: A/V Divergence: -0.000325, Rate: 0.000125,
 Warpfactor: 0.99927, warpfactor_avg: 0.999271
 2006-10-09 22:30:01.885 AO: Broadcasting free space avail
 2006-10-09 22:30:01.885 WriteAudio: Preparing 6144 bytes (1536 frames)
 2006-10-09 22:30:01.886 AO: audio waiting for space on soundcard: have
 1444 need 6144
 2006-10-09 22:30:01.897 AO: audio waiting for space on soundcard: have
 3684 need 6144
 2006-10-09 22:30:01.909 AO: audio waiting for space on soundcard: have
 5980 need 6144
 2006-10-09 22:30:01.917 AO: Broadcasting free space avail
 [...]
 }}}

 The backend log:


 {{{

 [...]

 2006-10-09 22:30:00.674 Finished recording EastEnders: channel 1045
 0: start_time: 10.076 duration: 3.287
 1: start_time: 10.062 duration: 3.277
 stream: start_time: 111.804 duration: 36.676 bitrate=10166 kb/s
 2006-10-09 22:30:00.722 AFD: Opened codec 0x9946250, id(MPEG2VIDEO)
 type(Video)
 2006-10-09 22:30:00.732 AFD: Opened codec 0x991f480, id(MP2) type(Audio)
 2006-10-09 22:30:00.849 TVRec(1): Enabling Full LiveTV UI.
 2006-10-09 22:30:01.425 TVRec(1): RingBufferChanged()
 2006-10-09 22:30:01.456 Finished recording EastEnders: channel 1045
 2006-10-09 22:30:15.004 TVRec(1): Changing from WatchingLiveTV to None
 2006-10-09 22:30:15.213 Finished recording Spooks: channel 1045
 2006-10-09 22:30:17.214 MainServer::HandleAnnounce Playback
 2006-10-09 22:30:17.235 adding: pvr as a client (events: 0)
 2006-10-09 22:30:17.238 TVRec(1): Changing from None to WatchingLiveTV
 2006-10-09 22:30:17.252 TVRec(1): HW Tuner: 1->1
 2006-10-09 22:30:18.291 ret_pid(5269) child(5269) status(0x0)
 2006-10-09 22:30:18.292 External Tuning program exited with no error
 2006-10-09 22:30:27.022 ret_pid(5277) child(5277) status(0x0)
 2006-10-09 22:30:27.026 External Tuning program exited with no error
 2006-10-09 22:30:27.031 Finished recording Spooks: channel 1045
 2006-10-09 22:30:27.090 Finished recording Spooks: channel 1045
 0: start_time: 0.036 duration: 0.587
 1: start_time: 0.025 duration: 0.588
 stream: start_time: 0.276 duration: 6.644 bitrate=11086 kb/s
 2006-10-09 22:30:27.127 AFD: Opened codec 0xb275b460, id(MPEG2VIDEO)
 type(Video)
 2006-10-09 22:30:27.142 AFD: Opened codec 0xb275b850, id(MP2) type(Audio)
 2006-10-09 22:30:27.184 TVRec(1): RingBufferChanged()
 2006-10-09 22:30:27.203 Finished recording Spooks: channel 1045
 2006-10-09 22:30:39.923 Expiring Russell Brand's 1 Leicester Square from
 Mon Oct 9 22:00:00 2006, 135 MBytes, forced expire (LiveTV recording)
 2006-10-09 22:32:40.067 Expiring EastEnders from Mon Oct 9 22:00:00 2006,
 45 MBytes, forced expire (LiveTV recording)
 2006-10-09 22:32:40.068 Expiring Spooks from Mon Oct 9 22:30:00 2006, 17
 MBytes, forced expire (LiveTV recording)
 2006-10-09 22:32:40.070 Expiring Spooks from Mon Oct 9 22:30:00 2006, 8
 MBytes, forced expire (LiveTV recording)

 [...]

 }}}


 Best Regards,

 Richard

-- 
Ticket URL: <http://svn.mythtv.org/trac/ticket/2563#comment:5>
MythTV <http://www.mythtv.org/>
MythTV


More information about the mythtv-commits mailing list