[mythtv-commits] Re: Ticket #754: PVR-350: LiveTV freezes when show ends and new show begins.

MythTV mythtv at cvs.mythtv.org
Wed Dec 21 20:20:22 EST 2005


#754: PVR-350: LiveTV freezes when show ends and new show begins.
-----------------------+----------------------------------------------------
 Reporter:  anonymous  |        Owner:  ijr
     Type:  defect     |       Status:  new
 Priority:  minor      |    Milestone:     
Component:  mythtv     |      Version:     
 Severity:  medium     |   Resolution:     
-----------------------+----------------------------------------------------
Comment (by jasonmollman at atlanticbb.net):

 Below is some more debug on this item from a newer svn, 8323 I believe. I
 don't know if it helps narrow down this issue any further.

 Mythfrontend -v all,nodatabase,noaudio

 2005-12-21 19:57:35.753 Image size. dispxoff 0, dispyoff: 0, dispwoff: 1,
 dispho
 ff: 1 [[br]]
 2005-12-21 19:57:35.753 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480
 [[br]]
 2005-12-21 19:57:39.178 Image size. dispxoff 0, dispyoff: 0, dispwoff: 1,
 dispho
 ff: 1 [[br]]
 2005-12-21 19:57:39.178 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480
 [[br]]
 2005-12-21 19:58:01.269 Image size. dispxoff 0, dispyoff: 0, dispwoff: 1,
 dispho
 ff: 1 [[br]]
 2005-12-21 19:58:01.269 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480
 [[br]]
 2005-12-21 19:58:01.659 Image size. dispxoff 0, dispyoff: 0, dispwoff: 1,
 dispho
 ff: 1 [[br]]
 2005-12-21 19:58:01.661 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480
 [[br]]
 2005-12-21 20:00:00.910 read  <- 14 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_C
 HANGE[]:[]empty [[br]]
 2005-12-21 20:00:01.235 read  <- 14 87
 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPD
 ATE live-pvr.home.net-2005-12... [[br]]
 2005-12-21 20:00:01.235 LiveTVChain::ReloadAll() [[br]]
 2005-12-21 20:00:01.667 LiveTVChain::SwitchToNext(up) [[br]]
 2005-12-21 20:00:01.667 LiveTVChain::SwitchTo(1) [[br]]
 2005-12-21 20:00:01.667 LiveTVChain: Entry at 1: '1002_20051221200000' [[br]]
 2005-12-21 20:00:01.683 write -> 12 33
 MESSAGE[]:[]RECORDING_LIST_CHANGE [[br]]
 2005-12-21 20:00:01.929 read  <- 12 2       OK [[br]]
 2005-12-21 20:00:01.932 write -> 12 33
 MESSAGE[]:[]RECORDING_LIST_CHANGE [[br]]
 2005-12-21 20:00:02.021 read  <- 14 87
 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPD
 ATE live-pvr.home.net-2005-12... [[br]]
 2005-12-21 20:00:02.021 LiveTVChain::ReloadAll() [[br]]
 2005-12-21 20:00:02.030 LiveTVChain::SwitchToNext(up) [[br]]
 2005-12-21 20:00:02.030 LiveTVChain::SwitchTo(1) [[br]]
 2005-12-21 20:00:02.030 LiveTVChain: Entry at 1: '1002_20051221200000' [[br]]
 2005-12-21 20:00:02.041 read  <- 14 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_C
 HANGE[]:[]empty [[br]]
 2005-12-21 20:00:02.086 read  <- 12 2       OK [[br]]
 2005-12-21 20:00:02.108 read  <- 14 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_C
 HANGE[]:[]empty [[br]]
 2005-12-21 20:00:02.145
 RingBuf(/video/recordings/1002_20051221195643.mpg): Open
 File(myth://127.0.0.1:6543/1002_20051221200000.mpg, 10) [[br]]
 2005-12-21 20:00:02.145 Resyncing position map. posmapStarted = 0
 livetv(1) watc
 hingRec(1) [[br]]
 2005-12-21 20:00:02.148 Position map filled from DB to: 1 [[br]]
 2005-12-21 20:00:02.148 SyncPositionMap watchingrecording, from DB: 1
 entries [[br]]
 2005-12-21 20:00:02.148 write -> 15 39      QUERY_RECORDER
 1[]:[]GET_FRAMES_WRIT
 TEN [[br]]
 2005-12-21 20:00:02.309 read  <- 15 7       0[]:[]0 [[br]]
 2005-12-21 20:00:02.309 PosMapFromEnc: Warning, tried to fetch PositionMap
 from
 Encoder but encoder returned framesWritten == 0 [[br]]
 2005-12-21 20:00:02.309 SyncPositionMap watchingrecording no entries from
 encode
 r, try DB [[br]]
 2005-12-21 20:00:02.312 Position map filled from DB to: 1 [[br]]
 2005-12-21 20:00:02.312 SyncPositionMap watchingrecording total: 1 entries
 [[br]]
 2005-12-21 20:00:02.312 SyncPositionMap, new totframes: 15, new length: 0,
 posMa
 p size: 1 [[br]]
 2005-12-21 20:00:02.389 LiveTVChain::SwitchToNext(up) [[br]]
 2005-12-21 20:00:02.390 LiveTVChain::SwitchToNext(up) [[br]]
 2005-12-21 20:00:02.390 LiveTVChain::SwitchToNext(up) [[br]]

 Mythbackend -v all,nodatabase,noaudio

 2005-12-21 19:57:03.973 JobQueue currently set at 1 job(s) max and to run
 new jobs from 00:00 to 23:59 [[br]]
 2005-12-21 19:57:04.036 JobQueue::GetJobsInQueue: findJobs search bitmask
 4, found 0 total jobs [[br]]
 2005-12-21 19:57:13.881 AutoExpire: Adding Programs to 'Do Not Expire'
 List [[br]]
 2005-12-21 19:57:13.896     1002 @ Wed Dec 21 19:56:43 2005 in use by
 player on pvr.home.net [[br]]
 2005-12-21 19:57:13.898     1002 @ Wed Dec 21 19:56:43 2005 in use by
 recorder on pvr.home.net [[br]]
 2005-12-21 19:58:04.042 JobQueue currently set at 1 job(s) max and to run
 new jobs from 00:00 to 23:59 [[br]]
 2005-12-21 19:58:04.098 JobQueue::GetJobsInQueue: findJobs search bitmask
 4, found 0 total jobs [[br]]
 2005-12-21 19:58:13. 941 AutoExpire: Adding Programs to 'Do Not Expire'
 List [[br]]
 2005-12-21 19:58:13.945     1002 @ Wed Dec 21 19:56:43 2005 in use by
 player on pvr.home.net [[br]]
 2005-12-21 19:58:13.948     1002 @ Wed Dec 21 19:56:43 2005 in use by
 recorder on pvr.home.net [[br]]
 2005-12-21 19:58:13.951 AutoExpire: ExpireLiveTV(10000) [[br]]
 2005-12-21 19:58:13.989 AutoExpire: SendDeleteMessages. Nothing to expire.
 [[br]]
 2005-12-21 19:59:04.103 JobQueue currently set at 1 job(s) max and to run
 new jobs from 00:00 to 23:59 [[br]]
 2005-12-21 19:59:04.183 JobQueue::GetJobsInQueue: findJobs search bitmask
 4, found 0 total jobs [[br]]
 2005-12-21 19:59:14.034 AutoExpire: Adding Programs to 'Do Not Expire'
 List [[br]]
 2005-12-21 19:59:14.039     1002 @ Wed Dec 21 19:56:43 2005 in use by
 player on pvr.home.net [[br]]
 2005-12-21 19:59:14.041     1002 @ Wed Dec 21 19:56:43 2005 in use by
 recorder on pvr.home.net [[br]]
 2005-12-21 20:00:00.327 TVRec(1): SwitchLiveTVRingBuffer(discont 0,
 set_rec 1) [[br]]
 2005-12-21 20:00:00.348 TVRec(1): GetProgramRingBufferForLiveTV() [[br]]
 2005-12-21 20:00:00.868 TVRec(1): StartedRecording(0x8134cb0)
 fn(/video/recordings/1002_20051221200000.mpg)
 2005-12-21 20:00:00.876 write -> 14 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty [[br]]
 2005-12-21 20:00:01.065 Preview:
 'myth://127.0.0.1:6543/1002_20051221195643.mpg' is not local,replacing
 with '/video/recordings/1002_20051221195643.mpg', which is local. [[br]]
 2005-12-21 20:00:01.102
 RingBuf(/video/recordings/1002_20051221195643.mpg):
 OpenFile(/video/recordings/1002_20051221195643.mpg, 0) [[br]]
 2005-12-21 20:00:01.107 Chain: Appended at 1 '1002_20051221200000' [[br]]
 2005-12-21 20:00:01.154 write -> 14 87
 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-pvr.home.net-2005-12...
 [[br]]
 2005-12-21 20:00:01.245 TVRec(1): SetFlags(RingBufferReset,) ->
 FrontendReady,RunMainLoop,AskAllowRecordin
 g,RecorderRunning,RingBufferReset, [[br]]
 2005-12-21 20:00:01.351 RecBase(/dev/video0): SetRingBuffer(0x8160690)
 '/video/recordings/1002_20051221200000.mpg' [[br]]
 2005-12-21 20:00:01.393 TVRec(1): RingBufferChanged() [[br]]
 2005-12-21 20:00:01.681 Chain: Updated endtime for '1002_20051221195643'
 to 20051221200001 [[br]]
 2005-12-21 20:00:01.699 Finished recording Wheel of Fortune "Tis the
 Season": channel 1002 [[br]]
 2005-12-21 20:00:01.691 read  <- 13 33
 MESSAGE[]:[]RECORDING_LIST_CHANGE [[br]]
 2005-12-21 20:00:01.823 write -> 13 2       OK [[br]]
 2005-12-21 20:00:01.933 write -> 14 87
 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-pvr.home.net-2005-12...
 [[br]]
 2005-12-21 20:00:02.005 write -> 14 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty [[br]]
 2005-12-21 20:00:02.041 read  <- 13 33
 MESSAGE[]:[]RECORDING_LIST_CHANGE [[br]]
 2005-12-21 20:00:02.059 write -> 13 2       OK [[br]]
 2005-12-21 20:00:02.087 write -> 14 51
 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty [[br]]
 0: start_time: 0.036 duration: 17.652 [[br]]
 1: start_time: 0.030 duration: 17.623 [[br]]
 stream: start_time: 0.334 duration: 196.196 bitrate=5245 kb/s [[br]]
 2005-12-21 20:00:02.156 read  <- 15 39      QUERY_RECORDER
 1[]:[]GET_FRAMES_WRITTEN [[br]]
 2005-12-21 20:00:02.129 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO,
 type Video at 0x0x8162490 [[br]]
 2005-12-21 20:00:02.302 write -> 15 7       0[]:[]0 [[br]]
 2005-12-21 20:00:02.306 detectInterlace(Detect Scan, Detect Scan, 29.97,
 480) ->Interlaced Scan [[br]]
 2005-12-21 20:00:02.316 Interlaced: Interlaced Scan  video_height: 480
 fps: 29.97 [[br]]
 2005-12-21 20:00:02.318 AFD: Looking for decoder for MPEG2VIDEO [[br]]
 2005-12-21 20:00:02.321 AFD: Opened codec 0x8147710, id(MPEG2VIDEO)
 type(Video) [[br]]
 2005-12-21 20:00:02.339 AFD: Stream #1, has id 0x448 codec id MP2, type
 Audio at 0x0x814bc60 [[br]]
 2005-12-21 20:00:02.342 AFD: Looking for decoder for MP2 [[br]]
 2005-12-21 20:00:02.374 AFD: Opened codec 0x8147c50, id(MP2) type(Audio)
 [[br]]
 2005-12-21 20:00:02.377 AFD: Audio Track #1 is A/V stream #1 and has 2
 channels in the Unknown language(0). [[br]]
 2005-12-21 20:00:02.380 RingBuf:CalcReadAheadThresh(6384 KB) ->
 threshhold(250 KB) readblocksize(125 KB) [[br]]
 2005-12-21 20:00:02.383 AFD: Selected track 1: Unknown MP2 2ch (A/V Stream
 #1) [[br]]
 2005-12-21 20:00:02.386 AFD: Initializing audio parms from audio track #1
 [[br]]
 2005-12-21 20:00:02.389 AFD: Audio format changed [[br]]
 from id(NONE)    -1Hz -1ch   0bps    ; id(NONE)    -1Hz -1ch   0bps [[br]]
 to   id( MP2) 48000Hz  2ch  16bps    ; id( MP2) 48000Hz  2ch  16bps [[br]]
 2005-12-21 20:00:02.424 Resyncing position map. posmapStarted = 0
 livetv(0) watchingRec(0) [[br]]
 2005-12-21 20:00:04.202 JobQueue currently set at 1 job(s) max and to run
 new jobs from 00:00 to 23:59 [[br]]
 2005-12-21 20:00:04.433 Position map filled from DB to: 394 [[br]]
 2005-12-21 20:00:04.447 JobQueue::GetJobsInQueue: findJobs search bitmask
 4, found 0 total jobs [[br]]
 2005-12-21 20:00:04.448 SyncPositionMap prerecorded, from DB: 394 entries
 [[br]]
 2005-12-21 20:00:04.451 SyncPositionMap, new totframes: 5910, new length:
 197, posMap size: 394 [[br]]
 Input #0, mpeg, from '/video/recordings/1002_20051221195643.mpg': [[br]]
 Duration: 00:03:16.1, start: 0.333856, bitrate: 5245 kb/s [[br]]
 Stream #0.0[0x1e0], 1/90000, 29.97 fps: Video: mpeg2video, yuv420p,
 720x480, 1001/30000, 6000 kb/s [[br]]
 Stream #0.1[0x1c0], 1/90000: Audio: mp2, 48000 Hz, stereo, 384 kb/s [[br]]
 2005-12-21 20:00:04.534 AFD: Position map found [[br]]
 2005-12-21 20:00:04.536 AFD: Successfully opened decoder for file:
 "/video/recordings/1002_20051221195643.mpg". novideo(0) [[br]]
 2005-12-21 20:00:04.578 VideoOutputNull() [[br]]
 2005-12-21 20:00:04.594 Image size. dispxoff 0, dispyoff: 0, dispwoff: 0,
 disphoff: 0 [[br]]
 2005-12-21 20:00:04.596 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480
 [[br]]
 2005-12-21 20:00:04.599 Created data @0xabf20138->0xabf9ea3a [[br]]
 2005-12-21 20:00:04.622 Created data @0xac1149a0->0xac1932a2 [[br]]
 2005-12-21 20:00:04.625 Created data @0xac273008->0xac2f190a [[br]]
 2005-12-21 20:00:04.630 Created data @0xac081008->0xac0ff90a [[br]]
 2005-12-21 20:00:04.633 Created data @0xac002008->0xac08090a [[br]]
 2005-12-21 20:00:04.638 Created data @0xabe81008->0xabeff90a [[br]]
 2005-12-21 20:00:04.642 Created data @0xabe02008->0xabe8090a [[br]]
 2005-12-21 20:00:04.646 Created data @0xabd83008->0xabe0190a [[br]]
 2005-12-21 20:00:04.650 Created data @0xabd04008->0xabd8290a [[br]]
 2005-12-21 20:00:04.654 Created data @0xabc85008->0xabd0390a [[br]]
 2005-12-21 20:00:04.658 Created data @0xabc06008->0xabc8490a [[br]]
 2005-12-21 20:00:04.683 Created data @0xabb87008->0xabc0590a [[br]]
 2005-12-21 20:00:04.687 Created data @0xabb08008->0xabb8690a [[br]]
 2005-12-21 20:00:04.691 Created data @0xaba89008->0xabb0790a [[br]]
 2005-12-21 20:00:04.695 Created data @0xaba0a008->0xaba8890a [[br]]
 2005-12-21 20:00:04.699 Created data @0xab98b008->0xaba0990a [[br]]
 2005-12-21 20:00:04.703 Created data @0xab90c008->0xab98a90a [[br]]
 2005-12-21 20:00:04.707 Created data @0xab88d008->0xab90b90a [[br]]
 2005-12-21 20:00:04.711 Created data @0xab80e008->0xab88c90a [[br]]
 2005-12-21 20:00:04.715 Created data @0xab78f008->0xab80d90a [[br]]
 2005-12-21 20:00:04.720 Created data @0xab710008->0xab78e90a [[br]]
 2005-12-21 20:00:04.745 Created data @0xab691008->0xab70f90a [[br]]
 2005-12-21 20:00:04.749 Created data @0xab612008->0xab69090a [[br]]
 2005-12-21 20:00:04.753 Created data @0xab593008->0xab61190a [[br]]
 2005-12-21 20:00:04.757 Created data @0xab514008->0xab59290a [[br]]
 2005-12-21 20:00:04.761 Created data @0xab495008->0xab51390a [[br]]
 2005-12-21 20:00:04.765 Created data @0xab416008->0xab49490a [[br]]
 2005-12-21 20:00:04.770 Created data @0xab397008->0xab41590a [[br]]
 2005-12-21 20:00:04.781 Created data @0xab318008->0xab39690a [[br]]
 2005-12-21 20:00:04.789 Created data @0xab299008->0xab31790a [[br]]
 2005-12-21 20:00:04.812 Created data @0xab21a008->0xab29890a [[br]]
 2005-12-21 20:00:04.818 Created data @0xab19b008->0xab21990a [[br]]
 2005-12-21 20:00:04.821 Image size. dispxoff 0, dispyoff: 0, dispwoff: 0,
 disphoff: 0 [[br]]
 2005-12-21 20:00:04.824 Image size. imgx 0, imgy: 0, imgw: 720, imgh: 480
 [[br]]
 2005-12-21 20:00:04.840 NVP: ClearAfterSeek() [[br]]
 2005-12-21 20:00:04.863 NVP: SetCommBreakIter @ framesPlayed = 0 [[br]]
 2005-12-21 20:00:04.865 NVP: new commBreakIter = 3014772 @ frame 7274611
 [[br]]
 2005-12-21 20:00:04.977 AFD: DoFastForward(2516, do flush) [[br]]
 2005-12-21 20:00:04.998 DecorderBase::DoFastForward(2516, do flush) [[br]]
 2005-12-21 20:00:05.070 AFD: SeekReset(0, do flush) [[br]]
 2005-12-21 20:00:05.074 AFD: SeekReset() flushing [[br]]
 2005-12-21 20:00:05.080 VideoBuffers::DiscardFrames():
 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAL [[br]]
 2005-12-21 20:00:05.088 VideoBuffers::DiscardFrames():
 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() [[br]]
 2005-12-21 20:00:05.095 NVP: ClearAfterSeek() [[br]]
 2005-12-21 20:00:05.100 NVP: SetCommBreakIter @ framesPlayed = 2506 [[br]]
 2005-12-21 20:00:05.102 NVP: new commBreakIter = 3014772 @ frame 7274611
 [[br]]
 2005-12-21 20:00:05.294 ~VideoOutputNull() [[br]]
 2005-12-21 20:00:14.074 AutoExpire: Adding Programs to 'Do Not Expire'
 List [[br]]
 2005-12-21 20:00:14.095     1002 @ Wed Dec 21 20:00:00 2005 in use by
 player on pvr.home.net [[br]]
 2005-12-21 20:00:14.098     1002 @ Wed Dec 21 20:00:00 2005 in use by
 recorder on pvr.home.net [[br]]
 2005-12-21 20:00:14.100 AutoExpire: ExpireLiveTV(10000) [[br]]
 2005-12-21 20:00:14.139 AutoExpire: SendDeleteMessages. Nothing to expire.
 [[br]]
 2005-12-21 20:00:14.144 AutoExpire: ExpireLiveTV(10001) [[br]]
 2005-12-21 20:00:14.163 AutoExpire: SendDeleteMessages. Nothing to expire.
 [[br]]
 2005-12-21 20:00:14.219 read  <- 15 32      QUERY_RECORDER
 1[]:[]STOP_LIVETV [[br]]
 2005-12-21 20:00:14.235 AutoExpire: Checking episode count for each
 recording profile using max episodes [[br]]
 2005-12-21 20:00:14.244 TVRec(1): Changing from WatchingLiveTV to None
 [[br]]
 2005-12-21 20:00:14.248 TVRec(1):
 ClearFlags(FrontendReady,CancelNextRecording,) ->
 RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReset, [[br]]
 2005-12-21 20:00:14.251 TVRec(1): SetFlags(AskAllowRecording,) ->
 RunMainLoop,AskAllowRecording,RecorderRunning,RingBufferReset, [[br]]
 2005-12-21 20:00:14.254 TVRec(1): Request: Program(no) channel() input()
 flags(KillRec,KillRingBuffer,) [[br]]
 2005-12-21 20:00:14.439 TVRec(1): ClearFlags(RecorderRunning,) ->
 RunMainLoop,AskAllowRecording,RingBufferReset, [[br]]
 2005-12-21 20:00:14.499 Chain: Updated endtime for '1002_20051221200000'
 to 20051221200014 [[br]]
 2005-12-21 20:00:14.659 Finished recording George Lopez "A Clear and
 Presentless Danger": channel 1002 [[br]]
 2005-12-21 20:00:14.665 TVRec(1): ClearFlags(RecorderRunning,) ->
 RunMainLoop,AskAllowRecording,RingBufferReset, [[br]]
 2005-12-21 20:00:14.668 TVRec(1): Tearing down RingBuffer [[br]]
 2005-12-21 20:00:14.692 TVRec(1): ClearFlags(PENDINGACTIONS,) ->
 RunMainLoop,AskAllowRecording,RingBufferReset, [[br]]
 2005-12-21 20:00:14.694 write -> 15 2       ok [[br]]

-- 
Ticket URL: <http://cvs.mythtv.org/trac/ticket/754>
MythTV <http://www.mythtv.org/>
MythTV


More information about the mythtv-commits mailing list