Ticket #12440: playbackcrash.txt

File playbackcrash.txt, 12.1 KB (added by Justin Alcorn <justin@…>, 9 years ago)

Output from mythfrontend -v playback at time of crash

Line 
1
2
3
42015-04-24 20:03:04.372269 I  TV::TV(): Creating TV object
52015-04-24 20:03:04.392334 I  TV::TV(): Finished creating TV object
62015-04-24 20:03:04.392359 N  Suspending idle timer
72015-04-24 20:03:04.394731 I  TV::Init(): Created TvPlayWindow.
82015-04-24 20:03:04.467456 I  TV::HandleStateChange(): Attempting to change from None to WatchingPreRecorded
92015-04-24 20:03:04.468589 I  FileRingBuf(/var/lib/mythtv/recordings/2207_20150424050300.mp4): OpenFile(/var/lib/mythtv/recordings/2207_20150424050300.mp4, 2000 ms)
102015-04-24 20:03:04.504298 I  TV::StartPlayer(): Elapsed time since TV constructor was called: 132 ms
112015-04-24 20:03:04.533992 E  ALSA: snd_pcm_info_get_card: Operation not permitted
122015-04-24 20:03:04.548942 N  AudioPlayer: Enabling Audio
132015-04-24 20:03:04.553509 I  Player(0): Stretch Factor 1, allow passthru
142015-04-24 20:03:04.553710 I  AFD: Buffer size: 32768 streamed 0 seekable 1
152015-04-24 20:03:04.655414 I  AFD: Stream #0, has id 0x1 codec id H264, type Video, bitrate 1145714 at 0x5fe7a40
162015-04-24 20:03:04.655443 I  AFD: Stream #1, has id 0x2 codec id AAC, type Audio, bitrate 160521 at 0x5fe8840
172015-04-24 20:03:04.655452 I  AFD: codec AAC has 2 channels
182015-04-24 20:03:04.655462 I  AFD: Looking for decoder for AAC
192015-04-24 20:03:04.658057 I  AFD: Opened codec 0x5fe8c20, id(AAC) type(Audio)
202015-04-24 20:03:04.658139 I  AFD: Stream #2, has id 0x3 codec id AC3, type Audio, bitrate 384000 at 0x5feca60
212015-04-24 20:03:04.658148 I  AFD: codec AC3 has 2 channels
222015-04-24 20:03:04.658154 I  AFD: Looking for decoder for AC3
232015-04-24 20:03:04.658628 I  AFD: Opened codec 0x5fece40, id(AC3) type(Audio)
242015-04-24 20:03:04.658652 I  AFD: Trying to select best video track
252015-04-24 20:03:04.658677 I  AFD: Selected track #0 (id 0x1 codec id H264, type Video, bitrate 1145714 at 0x5fe7a40)
262015-04-24 20:03:04.659828 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) skiploop(enabled) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdoubleprocessdeint,greedyhdeint) filt()
272015-04-24 20:03:04.659848 I  VDP: LoadBestPreferences(2048x2048, 0)
282015-04-24 20:03:04.659879 I  VDP: LoadBestPreferences(2048x2048, 60)
292015-04-24 20:03:04.659902 I  VDP: LoadBestPreferences(720x404, 60)
302015-04-24 20:03:04.659969 I  AFD: Using 2 CPUs for decoding
312015-04-24 20:03:04.659981 I  AFD: InitVideoCodec() 0x5fe7e40 id(H264) type (Video).
322015-04-24 20:03:04.660020 I  AFD: Selected FPS is 30 (avg 30 codec 179.82 container 90000 estimated 60)
332015-04-24 20:03:04.660049 I  Player(0): SetFrameInterval ps:1 scan:3
342015-04-24 20:03:04.660077 I  Player(0): detectInterlace(Detect Scan, Interlaced Scan, 30, 404) ->Interlaced Scan
352015-04-24 20:03:04.660110 I  AFD: Using ffmpeg for video decoding
362015-04-24 20:03:04.662242 I  AFD: Opened codec 0x5fe7e40, id(H264) type(Video)
372015-04-24 20:03:04.662458 I  AOBase: Opening audio device 'default' ch 2(2) sr 48000 sf 32 bit floating point reenc 0
382015-04-24 20:03:04.683416 E  ALSA: no playback control PCM found on mixer device default
392015-04-24 20:03:04.683429 E  ALSA: Unable to open audio mixer. Volume control disabled
402015-04-24 20:03:04.683523 I  Dec: Selected track #1 (type 2) in the Unknown language(0)
412015-04-24 20:03:04.683541 I  Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
422015-04-24 20:03:04.684917 I  Dec: SyncPositionMap prerecorded, from DB: 0 entries
432015-04-24 20:03:04.685642 I  AFD: Recording has no position -- using libavformat seeking.
442015-04-24 20:03:04.685789 I  AFD: Successfully opened decoder for file: "/var/lib/mythtv/recordings/2207_20150424050300.mp4". novideo(0)
452015-04-24 20:03:04.687997 I  VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,opengl-lite,vdpau,openglvaapi
462015-04-24 20:03:04.688045 I  VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,openglvaapi
472015-04-24 20:03:04.688933 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) skiploop(enabled) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdoubleprocessdeint,greedyhdeint) filt()
482015-04-24 20:03:04.688953 I  VDP: LoadBestPreferences(2048x2048, 0)
492015-04-24 20:03:04.688981 I  VDP: LoadBestPreferences(2048x2048, 60)
502015-04-24 20:03:04.689004 I  VDP: LoadBestPreferences(720x404, 60)
512015-04-24 20:03:04.689033 I  VideoOutput: Preferred renderer: opengl
522015-04-24 20:03:04.689047 I  VideoOutput: Trying video renderer: 'opengl'
532015-04-24 20:03:04.698912 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) skiploop(enabled) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdoubleprocessdeint,greedyhdeint) filt()
542015-04-24 20:03:04.698935 I  VDP: LoadBestPreferences(2048x2048, 0)
552015-04-24 20:03:04.698964 I  VDP: LoadBestPreferences(2048x2048, 60)
562015-04-24 20:03:04.699008 I  VideoOutWindow::SetPIPState. pip_state: 0]
572015-04-24 20:03:04.699021 I  VDP: LoadBestPreferences(2048x2048, 30)
582015-04-24 20:03:04.699062 I  Display Rect  left: -323, top: 0, width: 2566, height: 1080, aspect: 1.33333
592015-04-24 20:03:04.699077 I  Video Rect    left: 0, top: 0, width: 720, height: 404, aspect: 1.78218
602015-04-24 20:03:04.699089 I  VDP: LoadBestPreferences(720x416, 30)
612015-04-24 20:03:04.699117 I  Display Rect  left: -323, top: 0, width: 2566, height: 1080, aspect: 1.33333
622015-04-24 20:03:04.699131 I  Video Rect    left: 0, top: 0, width: 720, height: 404, aspect: 1.78218
632015-04-24 20:03:04.699143 I  VDP: SetVideoRenderer(opengl)
642015-04-24 20:03:04.699153 I  VDP: SetVideoRender(opengl) == GetVideoRenderer()
652015-04-24 20:03:04.699164 I  ColourSpace: PictureAttributes: Brightness, Contrast, Colour, Hue, Studio Levels,
662015-04-24 20:03:04.713828 W  OpenGL: Could not determine whether Sync to VBlank is enabled.
672015-04-24 20:03:04.722065 I  Trying the OpenGL 2.0 render
682015-04-24 20:03:04.729198 I  OpenGL2: GLSL supported
692015-04-24 20:03:04.729289 I  OpenGL: OpenGL vendor  : X.Org
702015-04-24 20:03:04.729298 I  OpenGL: OpenGL renderer: Gallium 0.4 on AMD ARUBA
712015-04-24 20:03:04.729304 I  OpenGL: OpenGL version : 3.0 Mesa 10.1.3
722015-04-24 20:03:04.729317 I  OpenGL: Max texture size: 16384 x 16384
732015-04-24 20:03:04.729324 I  OpenGL: Max texture units: 8
742015-04-24 20:03:04.729342 I  OpenGL: Direct rendering: Yes
752015-04-24 20:03:04.729349 I  OpenGL: PixelBufferObject support available
76greedyhdeint: size changed from 0 x 0 -> 720 x 416
77greedyhdeint: size changed from 0 x 0 -> 720 x 416
782015-04-24 20:03:04.759816 I  OpenGL: Initialised MythRenderOpenGL
792015-04-24 20:03:04.759828 I  VidOutGL: Created MythRenderOpenGL device.
802015-04-24 20:03:04.760826 I  VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
812015-04-24 20:03:04.760857 I  VideoOutput: Xinerama display dimensions: 381x286 mm  Aspect: 1.33217
822015-04-24 20:03:04.760866 I  VideoOutput: Estimated window dimensions: 381x286 mm  Aspect: 1.33217
832015-04-24 20:03:04.760903 I  OpenGL painter using existing OpenGL context.
842015-04-24 20:03:04.760905 I  OpenGL painter using existing QGLWidget.
852015-04-24 20:03:04.760941 I  GLVid: Viewport: 1920x1080
862015-04-24 20:03:04.762179 I  GLVid: Creating master filter.
872015-04-24 20:03:04.765087 I  GLVid: Using custom UYVY input textures.
882015-04-24 20:03:04.765105 I  GLVid: Creating resize filter.
892015-04-24 20:03:04.770511 I  OpenGL: Created frame buffer object (720x404).
902015-04-24 20:03:04.770572 I  GLVid: MMX: 1 PBO: 1
912015-04-24 20:03:04.770606 I  VDP: GetFilteredDeint() : opengl -> 'greedyhdoubleprocessdeint'
922015-04-24 20:03:04.771373 I  FilterManager: GetFilterInfo(convert) returning: 0x0
932015-04-24 20:03:04.771392 I  FilterManager: GetFilterInfo(greedyhdoubleprocessdeint) returning: 0x7d5bfd0
942015-04-24 20:03:04.772097 I  VideoOutput: Using deinterlace method greedyhdoubleprocessdeint
952015-04-24 20:03:04.772148 I  Display Rect  left: -323, top: 0, width: 2566, height: 1080, aspect: 1.33333
962015-04-24 20:03:04.772158 I  Video Rect    left: 0, top: 0, width: 720, height: 404, aspect: 1.78218
972015-04-24 20:03:04.772199 I  Display Rect  left: -323, top: 0, width: 2566, height: 1080, aspect: 1.33333
982015-04-24 20:03:04.772210 I  Video Rect    left: 0, top: 0, width: 720, height: 404, aspect: 1.78218
992015-04-24 20:03:04.773871 I  Over/underscan. V: 0, H: 0
1002015-04-24 20:03:04.773891 I  Display Rect  left: -323, top: 0, width: 2566, height: 1080, aspect: 1.33333
1012015-04-24 20:03:04.773901 I  Video Rect    left: 0, top: 0, width: 720, height: 404, aspect: 1.78218
1022015-04-24 20:03:04.773946 I  Player(0): LoadFilters(''..) -> 0x0
1032015-04-24 20:03:04.775302 I  Player(0): Decoder thread starting.
1042015-04-24 20:03:04.779239 I  OSD: Loaded window osd_message
1052015-04-24 20:03:04.784500 I  OSD: Loaded window osd_input
1062015-04-24 20:03:04.793683 I  OSD: Loaded window program_info
1072015-04-24 20:03:04.799546 I  OSD: Loaded window browse_info
1082015-04-24 20:03:04.804495 I  OSD: Loaded window osd_status
1092015-04-24 20:03:04.807964 I  OSD: Loaded window osd_program_editor
1102015-04-24 20:03:04.818569 I  OSD: Loaded window osd_debug
1112015-04-24 20:03:04.818585 I  OSD: Loaded OSD: size 1920x1080 offset 0+0
1122015-04-24 20:03:04.818695 I  OSD: Created window aa_OSD_SUBTITLES
1132015-04-24 20:03:04.818740 I  Player(0): ClearAfterSeek(0)
1142015-04-24 20:03:04.819523 I  VDP: GetFilteredDeint() : opengl -> 'greedyhdoubleprocessdeint'
1152015-04-24 20:03:04.819670 I  VDP: GetFilteredDeint() : opengl -> 'greedyhdoubleprocessdeint'
1162015-04-24 20:03:04.820361 I  FilterManager: GetFilterInfo(convert) returning: 0x0
1172015-04-24 20:03:04.820377 I  FilterManager: GetFilterInfo(greedyhdoubleprocessdeint) returning: 0x7d51180
1182015-04-24 20:03:04.820864 I  VideoOutput: Using deinterlace method greedyhdoubleprocessdeint
119QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
120QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
1212015-04-24 20:03:04.851193 I  Player(0): Video timing method: DRM
1222015-04-24 20:03:04.851210 I  Player(0): Display Refresh Rate: 60.020 Video Frame Rate: 30.000
1232015-04-24 20:03:04.851222 I  Player(0): SetFrameInterval ps:1 scan:1
1242015-04-24 20:03:04.865182 I  playCtx: StartPlaying(): took 0 ms to start player.
1252015-04-24 20:03:04.865229 I  TV::StartPlayer(): Created player.
1262015-04-24 20:03:04.865326 I  TV::HandleStateChange(): Changing from None to WatchingPreRecorded
1272015-04-24 20:03:04.867210 I  TV::HandleStateChange(): Main UI disabled.
1282015-04-24 20:03:04.867317 I  TV::StartTV(): Entering main playback loop.
1292015-04-24 20:03:04.879371 I  ScreenSaverDBus: Successfully inhibited screensaver via org.freedesktop.ScreenSaver. cookie 1168825301. nom nom
1302015-04-24 20:03:04.879886 E  ScreenSaverDBus: Failed to disable screensaver: Method "Inhibit" with signature "ss" on interface "org.gnome.SessionManager" doesn't exist
131
1322015-04-24 20:03:04.880006 I  Player(0): Waiting for video buffers...
1332015-04-24 20:03:04.897885 I  Player(0): Video Aspect ratio changed from 1.78218 to 1.78212
1342015-04-24 20:03:04.897914 I  Display Rect  left: -323, top: 0, width: 2566, height: 1080, aspect: 1.33333
135QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
1362015-04-24 20:03:04.897928 I  Video Rect    left: 0, top: 0, width: 720, height: 404, aspect: 1.78212
1372015-04-24 20:03:04.897944 I  Player(0): progressive frame seen after 2 interlaced frames
138QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
139QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
140QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
141QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
142QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
143QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
1442015-04-24 20:03:04.970343 I  Display Rect  left: -323, top: 0, width: 2566, height: 1080, aspect: 1.33333
1452015-04-24 20:03:04.970369 I  Video Rect    left: 0, top: 0, width: 720, height: 404, aspect: 1.78212
1462015-04-24 20:03:04.970410 I  Player(0): Disabled deinterlacing
147QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
148QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
149QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
150QOpenGLContext::swapBuffers() called with non-exposed window, behavior is undefined
151