[mythtv-commits] Ticket #12123: Myth attempts to playback "too fast" on arm based frontend

MythTV noreply at mythtv.org
Mon Apr 21 14:37:25 UTC 2014


#12123: Myth attempts to playback "too fast" on arm based frontend
----------------------------------------+------------------------
     Reporter:  Tim Krantz <tkrantz@…>  |      Owner:
         Type:  Bug Report - General    |     Status:  new
     Priority:  minor                   |  Milestone:  unknown
    Component:  MythTV - General        |    Version:  0.27-fixes
     Severity:  medium                  |   Keywords:
Ticket locked:  0                       |
----------------------------------------+------------------------
 Playing a recording on my "hackberry" arm based frontend results in what
 seems to be an attempt to play back at higher then expected speed.

 It is an allwinner a10 SOC with a mali 400 GPU using VDPAU (thin profile).

 Mplayer and mpv play back the same recording without a problem. (again
 using vdpau).

 ||
 MythTV Version : v0.27-212-gb93fb14-dirty
 MythTV Branch : fixes/0.27
 Network Protocol : 77
 Library API : 0.27.20140323-1
 QT Version : 4.8.5
 Options compiled in:
  linux profile use_hidesyms using_alsa using_jack using_oss using_pulse
 using_pulseoutput using_backend using_bindings_php using_crystalhd
 using_dvb using_frontend using_hdhomerun using_ceton using_hdpvr
 using_ivtv using_joystick_menu using_libcrypto using_libxml2 using_libudf
 using_lirc using_mheg using_opengl using_opengl_video using_qtwebkit
 using_qtscript using_qtdbus using_taglib using_v4l2 using_x11 using_xrandr
 using_xv using_profiletype using_mythlogserver using_bindings_php
 using_mythtranscode using_opengl using_vaapi using_vdpau
 using_ffmpeg_threads using_mheg using_libass using_libxml2 using_libudf
 ||

 mythfrontend -vplayback

 ||
 2014-04-21 10:24:07.455635 I  Setup Interrupt handler
 2014-04-21 10:24:07.455919 I  Setup Terminated handler
 2014-04-21 10:24:07.456007 I  Setup Segmentation fault handler
 2014-04-21 10:24:07.456070 I  Setup Aborted handler
 2014-04-21 10:24:07.456146 I  Setup Bus error handler
 2014-04-21 10:24:07.456208 I  Setup Floating point exception handler
 2014-04-21 10:24:07.456278 I  Setup Illegal instruction handler
 2014-04-21 10:24:07.456354 I  Setup Real-time signal 0 handler
 2014-04-21 10:24:07.456445 I  Setup User defined signal 1 handler
 2014-04-21 10:24:07.456507 I  Setup User defined signal 2 handler
 2014-04-21 10:24:07.457480 C  mythfrontend version: fixes/0.27
 [v0.27-212-gb93fb14-dirty] www.mythtv.org
 2014-04-21 10:24:07.457554 C  Qt version: compile: 4.8.5, runtime: 4.8.5
 2014-04-21 10:24:07.457605 N  Enabled verbose msgs:  general playback
 2014-04-21 10:24:07.457732 N  Setting Log Level to LOG_INFO
 2014-04-21 10:24:07.459164 N  Using runtime prefix = /usr/local
 2014-04-21 10:24:07.459304 N  Using configuration directory =
 /home/krantz/.mythtv
 2014-04-21 10:24:07.460064 I  Assumed character encoding: en_US.UTF-8
 2014-04-21 10:24:07.474957 I  Added logging to the console
 2014-04-21 10:24:07.486076 N  Empty LocalHostName.
 2014-04-21 10:24:07.486213 I  Using localhost value of localhost
 2014-04-21 10:24:07.666376 I  Added logging to mythlogserver at TCP:35327
 2014-04-21 10:24:07.727177 N  Setting QT default locale to en_US
 2014-04-21 10:24:07.727542 I  Current locale en_US
 2014-04-21 10:24:07.727950 N  Reading locale defaults from
 /usr/local/share/mythtv//locales/en_us.xml
 2014-04-21 10:24:07.824741 I  Starting IO manager (read)
 2014-04-21 10:24:07.825408 I  Starting IO manager (write)
 2014-04-21 10:24:07.834406 I  Starting process signal handler
 2014-04-21 10:24:07.835015 I  Starting process manager
 2014-04-21 10:24:07.985778 I  ScreenSaverX11Private: XScreenSaver support
 enabled
 2014-04-21 10:24:07.989914 I  ScreenSaverX11Private: DPMS is active.
 2014-04-21 10:24:08.149282 N  Desktop video mode: 1360x768 60.014 Hz
 2014-04-21 10:24:08.251431 I  max_width: 1360 max_height: 768
 2014-04-21 10:24:08.312836 I  Listening on TCP 127.0.0.1:6547
 2014-04-21 10:24:08.313642 I  Listening on TCP 192.168.188.3:6547
 2014-04-21 10:24:08.321563 I  Listening on TCP 192.168.188.189:6547
 2014-04-21 10:24:08.322287 I  Listening on TCP [::1]:6547
 2014-04-21 10:24:08.322926 I  Listening on TCP
 [fe80::6:8ff:fe41:ff98%eth0]:6547
 2014-04-21 10:24:08.323507 I  Listening on TCP
 [fe80::20c:e7ff:fe01:86d4%wlan0]:6547
 2014-04-21 10:24:09.919607 I  Loading en_us translation for module
 mythfrontend
 2014-04-21 10:24:10.018712 E  LIRC: Failed to connect to Unix socket
 '/var/run/lirc/lircd'
                         eno: No such file or directory (2)
 2014-04-21 10:24:10.019106 E  JoystickMenuThread: Joystick disabled -
 Failed to read /home/krantz/.mythtv/joystickmenurc
 2014-04-21 10:24:10.019168 I  UDPListener: Enabling
 2014-04-21 10:24:10.029553 I  Binding to UDP 127.0.0.1:6948
 2014-04-21 10:24:10.029992 I  Binding to UDP 192.168.188.3:6948
 2014-04-21 10:24:10.030309 I  Binding to UDP 192.168.188.189:6948
 2014-04-21 10:24:10.030730 I  Binding to UDP [::1]:6948
 2014-04-21 10:24:10.031233 I  Binding to UDP
 [fe80::6:8ff:fe41:ff98%eth0]:6948
 2014-04-21 10:24:10.031731 I  Binding to UDP
 [fe80::20c:e7ff:fe01:86d4%wlan0]:6948
 2014-04-21 10:24:10.032090 I  Binding to UDP 192.168.188.255:6948
 2014-04-21 10:24:10.383164 I  Using Frameless Window
 2014-04-21 10:24:10.383641 I  Using Full Screen Window
 2014-04-21 10:24:14.485423 I  Using the Qt painter
 2014-04-21 10:24:18.012830 I  MythUIWebBrowser: Loading css from -
 file:///usr/local/share/mythtv/themes/default/htmls/mythbrowser.css
 2014-04-21 10:24:18.024220 E  MythUIWebBrowser: failed to find our parent
 screen
 2014-04-21 10:24:18.037877 I  MythUIWebBrowser: enabling plugins
 2014-04-21 10:24:18.516822 I  MythCoreContext: Connecting to backend
 server: 192.168.188.231:6543 (try 1 of 1)
 2014-04-21 10:24:18.557393 I  Using protocol version 77
 2014-04-21 10:24:19.861535 I  Current MythTV Schema Version (DBSchemaVer):
 1317
 2014-04-21 10:24:19.874744 I  VDP: decoder<->render support: ffmpeg
 null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi
 openglvaapi
 2014-04-21 10:24:19.874835 I  VDP: decoder<->render support: vdpau
 vdpau nullvdpau
 2014-04-21 10:24:19.874887 I  VDP: decoder<->render support: vaapi
 nullvaapi openglvaapi
 2014-04-21 10:24:19.874942 I  VDP: decoder<->render support: crystalhd
 null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi
 2014-04-21 10:24:19.934027 N  VDP: Ignoring profile item 382 (renderer
 quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm
 ,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
 2014-04-21 10:24:19.934612 N  VDP: Ignoring profile item 383 (renderer
 quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm
 ,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
 2014-04-21 10:24:19.984042 N  VDP: Ignoring profile item 386 (renderer
 quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm
 ,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
 2014-04-21 10:24:19.984406 N  VDP: Ignoring profile item 387 (renderer
 quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm
 ,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
 2014-04-21 10:24:20.046635 N  VDP: Ignoring profile item 390 (renderer
 quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm
 ,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
 2014-04-21 10:24:20.046989 N  VDP: Ignoring profile item 391 (renderer
 quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm
 ,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
 2014-04-21 10:24:21.867609 N  Registering Internal as a media playback
 plugin.
 2014-04-21 10:24:22.254876 W  No plugins directory
 /usr/local/lib/mythtv/plugins
 2014-04-21 10:24:22.474263 E  Failed to mount /dev/mmcblk0p2.
 2014-04-21 10:24:22.794272 E  Failed to mount /dev/mmcblk0p3.
 2014-04-21 10:24:23.830925 N  Found mainmenu.xml for theme 'MythCenter-
 wide'
 2014-04-21 10:24:24.077990 I  Registering HouseKeeperTask
 'HardwareProfiler'.
 2014-04-21 10:24:24.101372 I  Starting HouseKeeper.
 2014-04-21 10:24:30.729368 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2599_20140421063000.mpg' file not found
 2014-04-21 10:24:31.144481 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2599_20140421063000.mpg' file not found
 2014-04-21 10:24:31.534276 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2106_20140421040100.mpg' file not found
 2014-04-21 10:24:31.654450 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2106_20140421020000.mpg' file not found
 2014-04-21 10:24:32.324617 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2106_20140421040100.mpg' file not found
 2014-04-21 10:24:32.372870 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2106_20140421020000.mpg' file not found
 2014-04-21 10:24:33.054446 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2599_20140421063000.mpg' file not found
 2014-04-21 10:24:35.082807 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2106_20140421040100.mpg' file not found
 2014-04-21 10:24:36.610943 E  PlaybackBoxHelper: CHECK_AVAILABILITY
 'myth://192.168.188.231:6543/2106_20140421020000.mpg' file not found
 2014-04-21 10:24:44.306947 I  TV: Creating TV object
 2014-04-21 10:24:46.164577 I  TV: Finished creating TV object
 2014-04-21 10:24:46.164640 I  TV: StartTV() -- begin
 2014-04-21 10:24:46.164741 N  Suspending idle timer
 2014-04-21 10:24:46.164853 I  TV: Init -- begin
 2014-04-21 10:24:46.201777 I  TV: Created TvPlayWindow.
 2014-04-21 10:24:46.207877 I  TV: DrawUnusedRects() -- begin
 2014-04-21 10:24:46.207968 I  TV: DrawUnusedRects() -- end
 2014-04-21 10:24:46.222919 I  TV: DrawUnusedRects() -- begin
 2014-04-21 10:24:46.222995 I  TV: DrawUnusedRects() -- end
 2014-04-21 10:24:46.314547 I  TV: Init -- end
 2014-04-21 10:24:46.314701 I  TV: tv->Playback() -- begin
 2014-04-21 10:24:46.352061 I  TV: HandleStateChange(0) -- begin
 2014-04-21 10:24:46.352231 I  TV: Attempting to change from None to
 WatchingPreRecorded
 2014-04-21 10:24:46.362124 I
 FileRingBuf(/storage/recordings/4462_20140419120000.mpg):
 OpenFile(/storage/recordings/4462_20140419120000.mpg, 2000 ms)
 2014-04-21 10:24:46.744817 I  TV: StartPlayer(0, WatchingPreRecorded,
 main) -- begin
 2014-04-21 10:24:46.745047 I  TV: Elapsed time since TV constructor was
 called: 2437 ms
 2014-04-21 10:24:47.463311 N  AudioPlayer: Enabling Audio
 2014-04-21 10:24:47.494382 I  Player(0): Stretch Factor 1, allow passthru
 2014-04-21 10:24:47.509299 I  AFD: Buffer size: 32768, streamed 0
 2014-04-21 10:24:47.696661 I  AFD: Stream #0, has id 0x16f9 codec id
 MPEG2VIDEO, type Video, bitrate 0 at 0x21b4610
 2014-04-21 10:24:47.696784 I  AFD: Stream #1, has id 0x16fa codec id AC3,
 type Audio, bitrate 192000 at 0x21b4e50
 2014-04-21 10:24:47.696838 I  AFD: codec AC3 has 2 channels
 2014-04-21 10:24:47.696874 I  AFD: Looking for decoder for AC3
 2014-04-21 10:24:47.699622 I  AFD: Opened codec 0x21b50c0, id(AC3)
 type(Audio)
 2014-04-21 10:24:47.713517 I  AFD: Trying to select best video track
 2014-04-21 10:24:47.714020 I  AFD: Selected track #0 (id 0x16f9 codec id
 MPEG2VIDEO, type Video, bitrate 500000 at 0x21b4610)
 2014-04-21 10:24:47.734216 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(none,none) filt(vdpauskipchroma,vdpaucolorspace=auto)
 2014-04-21 10:24:47.734393 I  VDP: LoadBestPreferences(2048x2048, 0)
 2014-04-21 10:24:47.734551 I  VDP: LoadBestPreferences(2048x2048, 60)
 2014-04-21 10:24:47.734667 I  VDP: LoadBestPreferences(640x480, 60)
 2014-04-21 10:24:47.735054 I  AFD: Using 1 CPUs for decoding
 2014-04-21 10:24:47.735137 I  AFD: InitVideoCodec() 0x21b4890
 id(MPEG2VIDEO) type (Video).
 2014-04-21 10:24:47.735360 I  AFD: Selected FPS is 29.97 (avg 29.97 codec
 29.97 container 90000 estimated 29.97)
 2014-04-21 10:24:47.735542 I  Player(0): SetFrameInterval ps:1 scan:3
 2014-04-21 10:24:47.735700 I  Player(0): detectInterlace(Detect Scan,
 Interlaced Scan, 29.97, 480) ->Interlaced Scan
 2014-04-21 10:24:47.736060 I  AFD: Using vdpau for video decoding
 2014-04-21 10:24:47.736229 I  AFD: Opened codec 0x21b4890, id(MPEG2VIDEO)
 type(Video)
 2014-04-21 10:24:47.736744 I  AOBase: Opening audio device
 'sysdefault:CARD=sunxisndhdmi' ch 2(2) sr 48000 sf signed 32 bit reenc 1
 2014-04-21 10:24:47.760625 E  ALSA: Requested 500000us got 341333 buffer
 time
 2014-04-21 10:24:47.760960 E  ALSA: Error opening
 /proc/asound/card1/pcm0p/sub0/prealloc. Fix reading permissions.
 2014-04-21 10:24:47.805032 I  Dec: Selected track #1 (type 2) in the
 Unknown language(0)
 2014-04-21 10:24:47.805164 I  Dec: Resyncing position map. posmapStarted =
 0 livetv(0) watchingRec(0)
 2014-04-21 10:24:48.102951 I  Dec: Position map filled from DB to: 107865
 2014-04-21 10:24:48.122557 I  Dec: Duration map filled from DB to: 107865
 2014-04-21 10:24:48.139173 I  Dec: SyncPositionMap prerecorded, from DB:
 2135 entries
 2014-04-21 10:24:48.139339 I  Dec: SyncPositionMap, new totframes: 107865,
 new length: 3599, posMap size: 2135
 2014-04-21 10:24:48.143173 I  AFD: Position map found
 2014-04-21 10:24:48.143260 I  AFD: Successfully opened decoder for file:
 "/storage/recordings/4462_20140419120000.mpg". novideo(0)
 2014-04-21 10:24:48.171945 I  VideoOutput: Allowed renderers: vdpau
 2014-04-21 10:24:48.172064 I  VideoOutput: Allowed renderers (filt:
 vdpau): vdpau
 2014-04-21 10:24:48.203396 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(none,none) filt(vdpauskipchroma,vdpaucolorspace=auto)
 2014-04-21 10:24:48.203498 I  VDP: LoadBestPreferences(2048x2048, 0)
 2014-04-21 10:24:48.203639 I  VDP: LoadBestPreferences(2048x2048, 60)
 2014-04-21 10:24:48.203829 I  VDP: LoadBestPreferences(640x480, 60)
 2014-04-21 10:24:48.203964 I  VideoOutput: Preferred renderer: vdpau
 2014-04-21 10:24:48.204032 I  VideoOutput: Trying video renderer: 'vdpau'
 2014-04-21 10:24:48.346488 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(none,none) filt(vdpauskipchroma,vdpaucolorspace=auto)
 2014-04-21 10:24:48.346597 I  VDP: LoadBestPreferences(2048x2048, 0)
 2014-04-21 10:24:48.346737 I  VDP: LoadBestPreferences(2048x2048, 60)
 2014-04-21 10:24:48.347013 I  VideoOutWindow::SetPIPState. pip_state: 0]
 2014-04-21 10:24:48.347098 I  VDP: LoadBestPreferences(2048x2048, 29.97)
 2014-04-21 10:24:48.347309 I  Display Rect  left: 0, top: 0, width: 1360,
 height: 768, aspect: 1.33333
 2014-04-21 10:24:48.347383 I  Video Rect    left: 0, top: 0, width: 640,
 height: 480, aspect: 1.33333
 2014-04-21 10:24:48.347441 I  VDP: LoadBestPreferences(640x480, 29.97)
 2014-04-21 10:24:48.347578 I  Display Rect  left: 0, top: 0, width: 1360,
 height: 768, aspect: 1.33333
 2014-04-21 10:24:48.347643 I  Video Rect    left: 0, top: 0, width: 640,
 height: 480, aspect: 1.33333
 2014-04-21 10:24:48.347692 I  VDP: SetVideoRenderer(vdpau)
 2014-04-21 10:24:48.347740 I  VDP: SetVideoRender(vdpau) ==
 GetVideoRenderer()
 2014-04-21 10:24:48.355818 I  VideoOutput: Pixel dimensions: Screen
 1360x768, window 1360x768
 2014-04-21 10:24:48.355934 I  VideoOutput: Actual display dimensions:
 360x203 mm  Aspect: 1.7734
 2014-04-21 10:24:48.356100 I  VideoOutput: Estimated window dimensions:
 360x203 mm  Aspect: 1.7734
 2014-04-21 10:24:48.356240 I  VidOutVDPAU: Enabling SkipChromaDeinterlace.
 2014-04-21 10:24:48.458955 I  VDPAU: Created 2 output surfaces.
 2014-04-21 10:24:48.459111 I  VDPAU: Set colorkey to 0x20202
 2014-04-21 10:24:48.459214 I  VDPAU: Version 1
 2014-04-21 10:24:48.459253 I  VDPAU: Information sunxi VDPAU Driver
 2014-04-21 10:24:48.459301 I  VDPAU: HQ Scaling not supported.
 2014-04-21 10:24:48.459337 I  VDPAU: MPEG4 hardware acceleration
 supported.
 2014-04-21 10:24:48.459390 I  VDPAU: Created VDPAU render device 1360x768
 2014-04-21 10:24:48.459579 I  VidOutVDPAU: Created VDPAU osd (1360x768)
 2014-04-21 10:24:48.597370 I  ColourSpace: PictureAttributes: Brightness,
 Contrast, Colour, Hue, Studio Levels,
 2014-04-21 10:24:48.597439 I  VidOutVDPAU: Using ITU BT.601 colorspace
 2014-04-21 10:24:48.597627 I  Display Rect  left: 170, top: 0, width:
 1020, height: 768, aspect: 1.77778
 2014-04-21 10:24:48.597702 I  Video Rect    left: 0, top: 0, width: 640,
 height: 480, aspect: 1.33333
 2014-04-21 10:24:48.597743 I  VidOutVDPAU: Created VDPAU context (GPU
 decode)
 2014-04-21 10:24:48.615336 I  Over/underscan. V: 0, H: 0
 2014-04-21 10:24:48.615483 I  Display Rect  left: 170, top: 0, width:
 1020, height: 768, aspect: 1.77778
 2014-04-21 10:24:48.615560 I  Video Rect    left: 0, top: 0, width: 640,
 height: 480, aspect: 1.33333
 2014-04-21 10:24:48.615637 N  Player(0): Forcing decode extra audio option
 on (Video method requires it).
 2014-04-21 10:24:48.615819 I  FilterManager: GetFilterInfo(convert)
 returning: 0x0
 2014-04-21 10:24:48.615981 I  Player(0):
 LoadFilters('vdpauskipchroma,vdpaucolorspace=auto'..) -> 0x0
 2014-04-21 10:24:48.637070 I  Player(0): Decoder thread starting.
 2014-04-21 10:24:48.687809 I  OSD: Loaded window osd_message
 2014-04-21 10:24:48.811607 I  OSD: Loaded window osd_input
 2014-04-21 10:24:49.042819 I  OSD: Loaded window program_info
 2014-04-21 10:24:49.180977 I  OSD: Loaded window browse_info
 2014-04-21 10:24:49.306080 I  OSD: Loaded window osd_status
 2014-04-21 10:24:49.430456 I  OSD: Loaded window osd_program_editor
 2014-04-21 10:24:49.616038 I  OSD: Loaded window osd_debug
 2014-04-21 10:24:49.616244 I  OSD: Loaded OSD: size 1360x768 offset 0+0
 2014-04-21 10:24:49.623415 I  OSD: Created window aa_OSD_SUBTITLES
 2014-04-21 10:24:49.632012 I  Player(0): ClearAfterSeek(0)
 2014-04-21 10:24:49.650301 I  VDP: GetFilteredDeint() : vdpau -> 'none'
 2014-04-21 10:24:49.650630 E  VSYNC: DRMVideoSync: VBlank ioctl did not
 work, unimplemented in this driver?
 2014-04-21 10:24:49.650864 E  VSYNC: RTCVideoSync: Could not open
 /dev/rtc:
                         eno: Permission denied (13)
 2014-04-21 10:24:49.656248 I  Player(0): Video timing method: USleep with
 busy wait
 2014-04-21 10:24:49.656369 I  Player(0): Display Refresh Rate: 60.013
 Video Frame Rate: 29.970
 2014-04-21 10:24:49.656439 I  Player(0): SetFrameInterval ps:1 scan:1
 2014-04-21 10:24:49.662900 I  VidOutVDPAU: Created VDPAU decoder (2 ref
 frames)
 2014-04-21 10:24:49.674347 I  playCtx: StartPlaying(): took 0 ms to start
 player.
 2014-04-21 10:24:49.674476 I  TV: Created player.
 2014-04-21 10:24:49.674660 I  TV: StartPlayer(0, WatchingPreRecorded,
 main) -- end ok
 2014-04-21 10:24:49.675940 I  TV: Changing from None to
 WatchingPreRecorded
 2014-04-21 10:24:49.701122 I  AFD: EIA-708 caption service #1 is in the
 Undetermined language.
 2014-04-21 10:24:49.732511 I  TV: DrawUnusedRects() -- begin
 2014-04-21 10:24:49.732640 I  TV: DrawUnusedRects() -- end
 2014-04-21 10:24:49.732960 I  TV: Main UI disabled.
 2014-04-21 10:24:49.733028 I  TV: HandleStateChange(0) -- end
 2014-04-21 10:24:49.747163 I  TV: tv->Playback() -- end
 2014-04-21 10:24:49.747249 I  TV: Entering main playback loop.
 2014-04-21 10:24:49.772205 I  Dec: Selected track #1 (type 5) in the
 Undetermined language(7695972)
 2014-04-21 10:24:50.028019 I  ScreenSaverX11Private: DPMS Deactivated 1
 2014-04-21 10:24:50.056352 I  VDP: GetFilteredDeint() : vdpau -> 'none'
 2014-04-21 10:24:50.056467 E  Player(0): Failed to enable deinterlacing
 2014-04-21 10:24:50.085047 I  VDPAU: Added 2 output surfaces (total 4, max
 4)
 2014-04-21 10:24:50.155039 I  Player(0): Video is 4.38868 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:50.156202 I  Player(0): Video is 5.58417 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:50.156680 I  Player(0): Video is 6.24105 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:50.157053 I  Player(0): Video is 6.48644 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:50.169324 I  Player(0): Video is 6.42323 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:50.169828 I  Player(0): Video is 4.66008 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:50.187288 I  Player(0): Video is 3.08296 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:52.901173 I  Player(0): FPS:   42.68 Mean: 23430 Std.Dev:
 10114 CPUs: 101%
 2014-04-21 10:24:55.905622 I  Player(0): FPS:   39.63 Mean: 25235 Std.Dev:
 9078 CPUs: 100%
 2014-04-21 10:24:56.208469 I  AFD: EIA-708 caption service #1 is in the
 Undetermined language.
 2014-04-21 10:24:56.208574 I  AFD: EIA-608 caption service #1 is in the
 Undetermined language.
 2014-04-21 10:24:56.240297 I  Dec: Selected track #1 (type 4) in the
 Undetermined language(7695972)
 2014-04-21 10:24:56.674794 I  Player(0): Video is 3.03345 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:56.675292 I  Player(0): Video is 3.13669 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:56.706299 I  Player(0): Video is 3.05682 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:56.763905 I  Player(0): Video is 3.20763 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:56.764439 I  Player(0): Video is 3.18491 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:57.419582 I  Player(0): Video is 3.12923 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:57.420040 I  Player(0): Video is 4.33239 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:57.420402 I  Player(0): Video is 4.98753 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:57.436083 I  Player(0): Video is 5.23913 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:57.436675 I  Player(0): Video is 3.98927 frames behind
 audio (too slow), dropping frame to catch up.
 2014-04-21 10:24:58.734427 I  Player(0): FPS:   42.09 Mean: 23759 Std.Dev:
 18842 CPUs: 100%
 2014-04-21 10:24:59.736045 I  Player(0): 400 interlaced frames seen.
 2014-04-21 10:25:01.588846 I  Player(0): FPS:   41.71 Mean: 23974 Std.Dev:
 8460 CPUs: 91%
 2014-04-21 10:25:01.872452 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
 2014-04-21 10:25:01.872679 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
 2014-04-21 10:25:01.889388 I  TV: HandleStateChange(0) -- begin
 2014-04-21 10:25:01.889497 I  TV: Attempting to change from
 WatchingPreRecorded to None
 2014-04-21 10:25:01.889620 I  TV: StopStuff() for player ctx 0 -- begin
 2014-04-21 10:25:01.889689 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
 2014-04-21 10:25:01.889829 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
 2014-04-21 10:25:01.889900 I  Player(0): StopPlaying - begin
 2014-04-21 10:25:01.894329 I  Player(0): Decoder thread exiting.
 2014-04-21 10:25:01.894762 I  Player(0): Exited decoder loop.
 2014-04-21 10:25:01.907529 I  VidOutVDPAU: DiscardFrames(1)
 2014-04-21 10:25:01.907754 I  VideoBuffers::DiscardFrames(1):
 AAUUUUUUUUUUUU
 2014-04-21 10:25:01.908039 I  VideoBuffers::DiscardFrames(1):
 AAAAAAAAAAAAAA -- done
 2014-04-21 10:25:01.908157 I  VidOutVDPAU: DiscardFrames() 3:
 AAAAAAAAAAAAAA -- done()
 2014-04-21 10:25:01.913265 W  MythPainter: 16 images not yet de-allocated.
 2014-04-21 10:25:01.913381 I  VDPAU Painter: Clearing VDPAU painter cache.
 2014-04-21 10:25:01.955353 E  VDPAU: Error at mythrender_vdpau.cpp:1702
 (#4, An invalid pointer was provided.)
 2014-04-21 10:25:01.995212 I  Player(0): StopPlaying - end
 2014-04-21 10:25:01.995281 I  TV: StopStuff(): stopping ring buffer
 2014-04-21 10:25:02.045698 I  TV: StopStuff(): stopping player
 2014-04-21 10:25:02.045756 I  TV: StopStuff() -- end
 2014-04-21 10:25:02.045841 I  TV: Changing from WatchingPreRecorded to
 None
 2014-04-21 10:25:02.046120 I  TV: HandleStateChange(0) -- end
 2014-04-21 10:25:02.046206 I  TV: Exiting main playback loop.
 2014-04-21 10:25:02.046254 I  TV: StartTV -- process events 2 begin
 2014-04-21 10:25:02.047264 I  ScreenSaverX11Private: DPMS Reactivated 1
 2014-04-21 10:25:02.049360 I  TV: StartTV -- process events 2 end
 2014-04-21 10:25:02.049465 I  TV::~TV() -- begin
 2014-04-21 10:25:02.159338 I  TV::~TV() -- lock
 2014-04-21 10:25:02.179307 I  Player(0): StopPlaying - begin
 2014-04-21 10:25:02.179449 I  Player(0): Exited decoder loop.
 2014-04-21 10:25:02.179514 I  Player(0): StopPlaying - end
 2014-04-21 10:25:02.199667 I  TV::~TV() -- end
 2014-04-21 10:25:02.200405 N  Resuming idle timer
 2014-04-21 10:25:02.200475 I  TV: StartTV -- end
 2014-04-21 10:25:07.831313 I  Shutting down UPnP client...
 2014-04-21 10:25:08.840674 I  Waiting for threads to exit.
 [VDPAU SUNXI] VE version 0x1623 opened.

 ||

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


More information about the mythtv-commits mailing list