[mythtv-commits] Ticket #11882: Attempt to play recording freezes frontend

MythTV noreply at mythtv.org
Sat Sep 28 20:10:37 UTC 2013 

#11882: Attempt to play recording freezes frontend
--------------------------------------------+------------------------
     Reporter:  warpme@…                    |      Owner:
         Type:  Bug Report - Hang/Deadlock  |     Status:  new
     Priority:  minor                       |  Milestone:  unknown
    Component:  MythTV - Video Decoding     |    Version:  0.27-fixes
     Severity:  medium                      |   Keywords:
Ticket locked:  0                           |
--------------------------------------------+------------------------
 Today morning I was pointed by my family that some DVB-S2 HD channels are
 freezing mythtv.
 After long investigation (converter positioning check, mplex SID checking,
 etc) I ended with conclusion that root cause probably lays in kind of
 change in DVB video stream from satellite.

 Interesting is that recordings .mpg files plays ok in VLC/mplayer so I
 think issue is about mythtv and H.264 stream bits.

 I tested problematic recordings in mythtv on ION1 (hw decode with vdpau)
 and OSX (software decoder) - both freezes. Again - recordings in question
 are playing OK in VLC/mplayer.

 Exemplary rec. file is on my FTP:
 ftp://mirekp:mirekp@warped.homenet.org/test.mpg

 I'm on current 0.27-fixes.

 Here is log from FE:


 {{{
 2013-09-28 21:37:49.218018 I  Player(0): Video timing method: USleep with
 busy wait
 2013-09-28 21:37:49.218062 I  Player(0): Display Refresh Rate: 70.067
 Video Frame Rate: 25.000
 2013-09-28 21:37:49.218089 I  Player(0): SetFrameInterval ps:1 scan:1
 2013-09-28 21:37:49.219640 I  playCtx: StartPlaying(): took 0 ms to start
 player.
 2013-09-28 21:37:49.219694 I  TV: Created player.
 2013-09-28 21:37:49.219766 I  TV: StartPlayer(0, WatchingVideo, main) --
 end ok
 2013-09-28 21:37:49.219798 I  TV: Changing from None to WatchingVideo
 2013-09-28 21:37:49.229151 I  TV: DrawUnusedRects() -- begin
 2013-09-28 21:37:49.229218 I  TV: DrawUnusedRects() -- end
 2013-09-28 21:37:49.229405 I  TV: Main UI disabled.
 2013-09-28 21:37:49.229461 I  TV: HandleStateChange(0) -- end
 2013-09-28 21:37:49.229693 I  TV: tv->Playback() -- end
 2013-09-28 21:37:49.229748 I  TV: Entering main playback loop.
 2013-09-28 21:37:49.236316 D  ScreenSaverX11Private: ResetTimer -- begin
 2013-09-28 21:37:49.236342 D  ScreenSaverX11Private: StopTimer
 2013-09-28 21:37:49.238667 D  ScreenSaverX11Private: StartTimer
 2013-09-28 21:37:49.238790 D  ScreenSaverX11Private: ResetTimer -- end
 2013-09-28 21:37:49.239450 I  Player(0): Waiting for video buffers...
 2013-09-28 21:37:49.343062 N  Player(0): Waited 103ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:49.453094 N  Player(0): Waited 213ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:49.559715 N  Player(0): Waited 320ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:49.662987 N  Player(0): Waited 423ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:49.773003 N  Player(0): Waited 533ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:49.876352 N  Player(0): Waited 637ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:49.983002 N  Player(0): Waited 743ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.089679 N  Player(0): Waited 850ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.192986 N  Player(0): Waited 953ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.299652 N  Player(0): Waited 1060ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.402979 N  Player(0): Waited 1163ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.509637 N  Player(0): Waited 1270ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.612993 N  Player(0): Waited 1373ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.722991 N  Player(0): Waited 1483ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.829644 N  Player(0): Waited 1590ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:50.936349 N  Player(0): Waited 1697ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.042985 N  Player(0): Waited 1803ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.146311 N  Player(0): Waited 1907ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.252977 N  Player(0): Waited 2013ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.356355 N  Player(0): Waited 2117ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.466302 N  Player(0): Waited 2227ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.572995 N  Player(0): Waited 2333ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.679659 N  Player(0): Waited 2440ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.783041 N  Player(0): Waited 2543ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.886336 N  Player(0): Waited 2647ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:51.992983 N  Player(0): Waited 2753ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.096329 N  Player(0): Waited 2857ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.199633 N  Player(0): Waited 2960ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.306310 N  Player(0): Waited 3067ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.409679 N  Player(0): Waited 3170ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.516322 N  Player(0): Waited 3277ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.622979 N  Player(0): Waited 3383ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.729661 N  Player(0): Waited 3490ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.836324 N  Player(0): Waited 3597ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:52.942987 N  Player(0): Waited 3703ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.046310 N  Player(0): Waited 3807ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.152996 N  Player(0): Waited 3913ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.262989 N  Player(0): Waited 4023ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.369648 N  Player(0): Waited 4130ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.476371 N  Player(0): Waited 4237ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.586314 N  Player(0): Waited 4347ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.693020 N  Player(0): Waited 4453ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.799655 N  Player(0): Waited 4560ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:53.902993 N  Player(0): Waited 4663ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:54.006309 N  Player(0): Waited 4767ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:54.112973 N  Player(0): Waited 4873ms for video buffers
 AAAAAAAAAAAAAA
 2013-09-28 21:37:54.166989 D  [mpegts @ 0x7f9893870fc0] PES packet size
 mismatch
 2013-09-28 21:37:54.167040 D  [mpegts @ 0x7f9893870fc0] PES packet size
 mismatch
 2013-09-28 21:37:54.167596 E  decoding error
                         eno: Unknown error 541478725 (541478725)
 2013-09-28 21:37:54.172967 I  waiting for no video frames 0
 2013-09-28 21:37:54.172995 I  HasReachedEof() at framesPlayed=1
 totalFrames=6950
 2013-09-28 21:37:54.173037 I  AOBase: Pause 1
 2013-09-28 21:37:54.182913 I  AOBase: OutputAudioLoop: audio paused
 2013-09-28 21:37:54.243196 E  decoding error
                         eno: Unknown error 541478725 (541478725)
 2013-09-28 21:37:54.249432 I  TV: HandleStateChange(0) -- begin
 2013-09-28 21:37:54.249489 I  TV: Attempting to change from WatchingVideo
 to None
 2013-09-28 21:37:54.249614 I  TV: StopStuff() for player ctx 0 -- begin
 2013-09-28 21:37:54.249675 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
 2013-09-28 21:37:54.249880 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
 2013-09-28 21:37:54.249936 I  Player(0): StopPlaying - begin
 2013-09-28 21:37:54.259595 I  Player(0): Decoder thread exiting.
 2013-09-28 21:37:54.259785 I  Player(0): Exited decoder loop.
 2013-09-28 21:37:54.260268 D  Closing PAT Filter: pid=0x0
 2013-09-28 21:37:54.260323 D  Closing PMT Filter: pid=0x446
 2013-09-28 21:37:54.274414 I  VidOutVDPAU: DiscardFrames(1)
 2013-09-28 21:37:54.274491 I  VideoBuffers::DiscardFrames(1):
 AAAAAAAAAAAAAA
 2013-09-28 21:37:54.274530 I  VideoBuffers::DiscardFrames(1):
 AAAAAAAAAAAAAA -- done
 2013-09-28 21:37:54.274559 I  VidOutVDPAU: DiscardFrames() 3:
 AAAAAAAAAAAAAA -- done()
 2013-09-28 21:37:54.290938 W  MythPainter: 39 images not yet de-allocated.
 2013-09-28 21:37:54.290994 I  VDPAU Painter: Clearing VDPAU painter cache.
 2013-09-28 21:37:54.337083 I  AOBase: Killing AudioOutputDSP
 2013-09-28 21:37:54.383462 I  AOBase: OutputAudioLoop: Stop Event
 2013-09-28 21:37:54.383485 I  AOBase: kickoffOutputAudioLoop exiting
 2013-09-28 21:37:54.385133 I  Player(0): StopPlaying - end
 }}}

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

More information about the mythtv-commits mailing list