Opened 6 years ago

Last modified 4 years ago

#12123 new Bug Report - General

Allwinner SoC: Myth attempts to playback "too fast" on arm based frontend using sunxi VDPAU

Reported by: Tim Krantz <tkrantz@…> Owned by:
Priority: minor Milestone: unknown
Component: Ports - rPi Version: 0.27-fixes
Severity: medium Keywords: allwinner sunxi vdpau
Cc: Ticket locked: no

Description (last modified by Karl Egly)

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.

Change History (2)

comment:1 Changed 5 years ago by Tim Krantz <tkrantz@…>

Just an update.

If I set the audio output to NULL, this plays at a very consistent 24FPS. If I select any of the ALSA outputs (spidif, hdmi or codec) it tries to play "too fast" again.

comment:2 Changed 4 years ago by Karl Egly

Component: MythTV - GeneralPorts - rPi
Description: modified (diff)
Keywords: allwinner sunxi vdpau added
Summary: Myth attempts to playback "too fast" on arm based frontendAllwinner SoC: Myth attempts to playback "too fast" on arm based frontend using sunxi VDPAU

Marking the log as preformatted text.

It appears that this is all very new https://linux-sunxi.org/Cedrus#Current_status

Moving to Ports - rPi in absence of a generic "ARM Systems" component.

Note: See TracTickets for help on using tickets.