[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