Opened 5 years ago

Closed 16 months ago

Last modified 12 months ago

#12302 closed Bug Report - General (Works for me)

Airplay: issue with playback of iPhone/iPad camera recordings

Reported by: warpme@… Owned by: Peter Bennett
Priority: minor Milestone: unknown
Component: MythTV - Airplay Version: Master Head
Severity: medium Keywords:
Cc: Ticket locked: no

Description

I have problem with playback of iPhone/iPad camera recordings. Issue is the same on iPhone4/iOS6.1.2 and iPad4/iOS8.0.2: 1-2sec of slowed-down playback and playback freeze. Here is FE log with playback,libav:

2014-10-16 20:49:02.911004 I Setup Interrupt handler
2014-10-16 20:49:02.911161 I Setup Terminated handler
2014-10-16 20:49:02.911190 I Setup Segmentation fault handler
2014-10-16 20:49:02.911221 I Setup Aborted handler
2014-10-16 20:49:02.911248 I Setup Bus error handler
2014-10-16 20:49:02.911277 I Setup Floating point exception handler
2014-10-16 20:49:02.911307 I Setup Illegal instruction handler
2014-10-16 20:49:02.911338 I Setup Real-time signal 0 handler
2014-10-16 20:49:02.911374 I Setup User defined signal 1 handler
2014-10-16 20:49:02.911402 I Setup User defined signal 2 handler
2014-10-16 20:49:02.912040 C mythfrontend version: master [v0.28-pre-2307-g68f7fbd-v7.0] www.mythtv.org
2014-10-16 20:49:02.912077 C Qt version: compile: 4.8.5, runtime: 4.8.5
2014-10-16 20:49:02.912091 N Enabled verbose msgs: general media playback audio libav
2014-10-16 20:49:02.912163 N Setting Log Level to LOG_INFO
2014-10-16 20:49:02.913445 N Using runtime prefix = /usr
2014-10-16 20:49:02.913467 N Using configuration directory = /home/minimyth/.mythtv
2014-10-16 20:49:02.913805 I Added logging to the console
2014-10-16 20:49:02.914089 I Assumed character encoding: en_US.UTF-8
2014-10-16 20:49:02.916825 N Empty LocalHostName.
2014-10-16 20:49:02.916889 I Using localhost value of FE-Devel
2014-10-16 20:49:02.965485 I Testing network connectivity to \'192.168.1.221\'
2014-10-16 20:49:02.966475 I Starting IO manager (read)
2014-10-16 20:49:02.966504 I Starting process signal handler
2014-10-16 20:49:02.966367 I Starting process manager
2014-10-16 20:49:02.966789 I Starting IO manager (write)
2014-10-16 20:49:03.080685 N Setting QT default locale to pl_US
2014-10-16 20:49:03.080769 I Current locale pl_US
2014-10-16 20:49:03.080975 E No locale defaults file for pl_US, skipping
2014-10-16 20:49:03.183635 I ScreenSaverX11Private: XScreenSaver support enabled
2014-10-16 20:49:03.185279 I ScreenSaverX11Private: DPMS is disabled.
2014-10-16 20:49:03.209640 N Desktop video mode: 1280x1024 60.020 Hz
2014-10-16 20:49:03.326722 I max_width: 1400 max_height: 1050
2014-10-16 20:49:03.438423 I Listening on TCP 127.0.0.1:6547
2014-10-16 20:49:03.438961 I Listening on TCP 192.168.1.130:6547
2014-10-16 20:49:04.752417 I Loading pl translation for module mythfrontend
2014-10-16 20:49:04.771051 I LIRC: Successfully initialized \'/dev/lircd\' using \'/home/minimyth/.mythtv/lircrc\' config
2014-10-16 20:49:04.771204 I UDPListener: Enabling
2014-10-16 20:49:04.772933 I Binding to UDP 127.0.0.1:6948
2014-10-16 20:49:04.773108 I Binding to UDP 192.168.1.130:6948
2014-10-16 20:49:04.773231 I Binding to UDP 192.168.1.255:6948
2014-10-16 20:49:04.847614 I Using Frameless Window
2014-10-16 20:49:04.847630 I Using Full Screen Window
2014-10-16 20:49:04.855391 I UI Screen Resolution: 1280 x 1024
2014-10-16 20:49:04.957236 I OpenGL: Sync to VBlank is enabled (good!)
2014-10-16 20:49:05.083776 I Trying the OpenGL 1.2 render
2014-10-16 20:49:05.100702 I OpenGL1: Fragment program support available
2014-10-16 20:49:05.100842 I OpenGL: OpenGL vendor : NVIDIA Corporation
2014-10-16 20:49:05.100857 I OpenGL: OpenGL renderer: ION/integrated/SSE2
2014-10-16 20:49:05.100868 I OpenGL: OpenGL version : 3.3.0 NVIDIA 340.46
2014-10-16 20:49:05.100889 I OpenGL: Max texture size: 8192 x 8192
2014-10-16 20:49:05.100902 I OpenGL: Max texture units: 4
2014-10-16 20:49:05.100991 I OpenGL: Direct rendering: Yes
2014-10-16 20:49:05.101002 I OpenGL: PixelBufferObject support available
2014-10-16 20:49:05.101013 I OpenGL: Initialised MythRenderOpenGL
2014-10-16 20:49:07.425025 I MythCoreContext: Connecting to backend server: 192.168.1.221:6543 (try 1 of 1)
2014-10-16 20:49:07.440666 I Using protocol version 83
2014-10-16 20:49:07.837079 I MythUIWebBrowser: Loading css from - file:///usr/share/mythtv/themes/default/htmls/mythbrowser.css
2014-10-16 20:49:07.840159 I MythUIWebBrowser: enabling plugins
2014-10-16 20:49:07.902116 I RAOP Device: Created RAOP device objects.
2014-10-16 20:49:07.902480 I Listening on TCP 127.0.0.1:5000
2014-10-16 20:49:07.902680 I Listening on TCP 192.168.1.130:5000
2014-10-16 20:49:07.902734 I RAOP Device: Listening for connections on port 5000
2014-10-16 20:49:07.903681 I AirPlay: Created airplay objects.
2014-10-16 20:49:07.904008 I Listening on TCP 127.0.0.1:5100
2014-10-16 20:49:07.904221 I Listening on TCP 192.168.1.130:5100
2014-10-16 20:49:07.905968 I Registering service 3a4b52674d6b@MythTV on FE-Devel._raop._tcp port 5000 TXT tp=UDPsm=falsesv=falseek=1et=0,1cn=0,1ch=2ss=16sr=44100pw=falsevn=3 txtvers=1md=0,1,2vs=115.2da=trueam=MythTV,1
2014-10-16 20:49:07.909799 I Current MythTV Schema Version (DBSchemaVer): 1331
2014-10-16 20:49:07.912088 I VDP: decoder<->render support: ffmpeg null opengl opengl-lite vdpau nullvdpau nullvaapi openglvaapi
2014-10-16 20:49:07.912126 I VDP: decoder<->render support: vdpau vdpau nullvdpau
2014-10-16 20:49:07.912145 I VDP: decoder<->render support: vaapi nullvaapi openglvaapi
2014-10-16 20:49:07.917908 N VDP: Ignoring profile item 710 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.918074 N VDP: Ignoring profile item 711 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.918208 N VDP: Ignoring profile item 712 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.918337 N VDP: Ignoring profile item 713 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.922521 N VDP: Ignoring profile item 714 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.922659 N VDP: Ignoring profile item 715 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.922787 N VDP: Ignoring profile item 716 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.922912 N VDP: Ignoring profile item 717 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.929863 N VDP: Ignoring profile item 718 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.930026 N VDP: Ignoring profile item 719 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.930154 N VDP: Ignoring profile item 720 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:07.930276 N VDP: Ignoring profile item 721 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2014-10-16 20:49:08.182332 W Key Alt+V is already bound to a jump point.
2014-10-16 20:49:08.342927 N Registering Internal as a media playback plugin.
2014-10-16 20:49:08.344721 N Creating MediaMonitor
2014-10-16 20:49:08.344737 I IgnoreDevices=
2014-10-16 20:49:08.402433 I MMUnix::AddDevice() - Added /dev/sr0
2014-10-16 20:49:08.403797 I Initial device list...
/dev/sr0 (Slimtype DVD A DS8A3S)
2014-10-16 20:49:08.403840 I Registering \'MythDVD DVD Media Handler\' as a media handler for MEDIATYPE_DVD
2014-10-16 20:49:08.416524 N Registering WebBrowser as a media playback plugin.
2014-10-16 20:49:08.416742 I Loading pl translation for module mythbrowser
2014-10-16 20:49:08.453981 I Registering \'MythGallery Media Handler 1/2\' as a media handler for MEDIATYPE_DATA
2014-10-16 20:49:08.454246 I Registering \'MythGallery Media Handler 2/2\' as a media handler for MEDIATYPE_MGALLERY, ext(bmp,gif,ico,jpeg,jpg,mng,pbm,pgm,png,ppm,svg,svgz,tga,tif,tiff,xbm,xpm)
2014-10-16 20:49:08.454499 I Loading pl translation for module mythgallery
2014-10-16 20:49:08.466139 I Loading pl translation for module mythgame
2014-10-16 20:49:08.481867 I Current MythMusic Schema Version (MusicDBSchemaVer): 1022
2014-10-16 20:49:08.488382 C Database backup script does not exist: /usr/share/mythtv/mythconverg_backup.pl
2014-10-16 20:49:08.493585 E SG(DB Backups): FindNextDirMostFree: \'/myth/backup\' does not exist!
2014-10-16 20:49:08.493645 C Neither /bin/gzip nor /usr/bin/gzip exist. The database backup will be uncompressed.
2014-10-16 20:49:08.494144 C Backing up database to file: \'/tmp/mythconverg-1331-20141016184908.sql\'
2014-10-16 20:49:08.637128 E DBUtil: Error backing up database: \'mysqldump --defaults-extra-file=\'/tmp/mythtv_db_backup_conf_pZFGXX\' --host=\'192.168.1.221\' --user=\'mythtv\' --add-drop-table --add-locks --allow-keywords --complete-insert --extended-insert --lock-tables --no-create-db --quick \'mythconverg\' > \'/tmp/mythconverg-1331-20141016184908.sql\' 2>/dev/null\' (127)
2014-10-16 20:49:08.691291 I Bonjour: Service registration complete: name \'MythTV on FE-Devel\' type \'_airplay._tcp.\' domain: \'local.\'
2014-10-16 20:49:08.704417 I Bonjour: Service registration complete: name \'3a4b52674d6b@MythTV on FE-Devel\' type \'_raop._tcp.\' domain: \'local.\'
2014-10-16 20:49:10.709740 I Bonjour: Service registration complete: name \'Mythfrontend on FE-Devel\' type \'_mythfrontend._tcp.\' domain: \'local.\'
2014-10-16 20:49:21.764350 I ScreenSaverX11Private: Calling xscreensaver-command -deactivate
2014-10-16 20:49:31.886560 N Upgrading to MythMusic schema version 1023
2014-10-16 20:49:32.367430 I MythMusic database schema upgrade complete.
2014-10-16 20:49:32.397891 I Registering \'MythMusic Media Handler 1/2\' as a media handler for MEDIATYPE_MIXED
2014-10-16 20:49:32.397925 I Registering \'MythMusic Media Handler 2/2\' as a media handler for MEDIATYPE_MMUSIC, ext(mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv)
2014-10-16 20:49:32.434275 I Loading pl translation for module mythmusic
2014-10-16 20:49:32.440051 I Loading pl translation for module mythnetvision
2014-10-16 20:49:32.447997 I Loading pl translation for module mythnews
2014-10-16 20:49:32.457722 I Loading pl translation for module mythweather
2014-10-16 20:49:32.463878 I Loading pl translation for module mythzoneminder
2014-10-16 20:49:32.464311 N Starting MediaMonitor
2014-10-16 20:49:32.467168 I Listening on TCP 127.0.0.1:6546
2014-10-16 20:49:32.467393 I Listening on TCP 192.168.1.130:6546
2014-10-16 20:49:32.733266 N Found mainmenu.xml for theme \'Default\'
2014-10-16 20:49:32.742665 I Registering HouseKeeperTask \'HardwareProfiler\'.
2014-10-16 20:49:32.747152 I Queueing HouseKeeperTask \'HardwareProfiler\'.
2014-10-16 20:49:32.747177 I Starting HouseKeeper.
2014-10-16 20:49:52.370722 I AirPlay: New connection from 192.168.1.150:56188
2014-10-16 20:49:52.420621 I AirPlay: Method: GET URI: /server-info
2014-10-16 20:49:52.440719 I 



deviceid 
3A:4B:52:67:4D:6B 
features 
119 
model 
MythTV,1 
protovers 
1.0 
srcvers 
115.2 



2014-10-16 20:49:52.445881 I AirPlay: Method: GET URI: /slideshow-features
2014-10-16 20:49:52.445993 I AirPlay: Slideshow functionality not implemented.
2014-10-16 20:49:52.477864 I AirPlay: Removing connection 192.168.1.150:56188
2014-10-16 20:49:52.478081 I AirPlay: Removing session \'4dda39f6-dcc8-408d-9074-e3f22f931e46\'
2014-10-16 20:49:52.511506 I AirPlay: New connection from 192.168.1.150:56189
2014-10-16 20:49:52.522495 I AirPlay: Method: GET URI: /server-info
2014-10-16 20:49:52.522653 I 



deviceid 
3A:4B:52:67:4D:6B 
features 
119 
model 
MythTV,1 
protovers 
1.0 
srcvers 
115.2 



2014-10-16 20:49:52.540491 I AirPlay: New connection from 192.168.1.150:56190
2014-10-16 20:49:52.545596 I AirPlay: Method: POST URI: /reverse
2014-10-16 20:49:52.694515 I AirPlay: Method: PUT URI: /photo
2014-10-16 20:49:52.764293 I AirPlay: Received 960x540 png photo
2014-10-16 20:49:53.143542 I AirPlay: Removing connection 192.168.1.150:56189
2014-10-16 20:49:53.147102 I AirPlay: Removing connection 192.168.1.150:56190
2014-10-16 20:49:53.147268 I AirPlay: Removing session \'28e6f6ea-4b4f-404c-a8bd-69e06a1380b9\'
2014-10-16 20:49:54.650736 I AirPlay: New connection from 192.168.1.150:56191
2014-10-16 20:49:54.694103 I AirPlay: Method: GET URI: /server-info
2014-10-16 20:49:54.710128 I 



deviceid 
3A:4B:52:67:4D:6B 
features 
119 
model 
MythTV,1 
protovers 
1.0 
srcvers 
115.2 



2014-10-16 20:49:54.983826 I AirPlay: New connection from 192.168.1.150:56192
2014-10-16 20:49:55.003777 I AirPlay: Method: POST URI: /reverse
2014-10-16 20:49:55.036594 I AirPlay: Method: POST URI: /play
2014-10-16 20:49:55.037044 I PList: Parsing binary plist (160 bytes)
2014-10-16 20:49:55.037240 I PList: Parse complete.
2014-10-16 20:49:55.127030 I TV: Creating TV object
2014-10-16 20:49:55.204232 I TV: Finished creating TV object
2014-10-16 20:49:55.204432 I TV: StartTV() -- begin
2014-10-16 20:49:55.204493 N Suspending idle timer
2014-10-16 20:49:55.216112 I TV: Init -- begin
2014-10-16 20:49:55.219093 I TV: Created TvPlayWindow.
2014-10-16 20:49:55.220637 I TV: DrawUnusedRects() -- begin
2014-10-16 20:49:55.220675 I TV: DrawUnusedRects() -- end
2014-10-16 20:49:55.234903 I TV: Init -- end
2014-10-16 20:49:55.235019 I TV: tv->Playback() -- begin
2014-10-16 20:49:55.252923 I TV: HandleStateChange(0) -- begin
2014-10-16 20:49:55.252966 I TV: Attempting to change from None to WatchingVideo
2014-10-16 20:49:55.983792 I StreamRingBuf(http://192.168.1.150:7001/1/898a48e0-a0f3-5b13-91a9-4065f722e3dd.MOV): Trying http://192.168.1.150:7001/1/898a48e0-a0f3-5b13-91a9-4065f722e3dd.MOV (allow seeks: 1
2014-10-16 20:49:55.983856 I TV: StartPlayer(0, WatchingVideo, main) -- begin
2014-10-16 20:49:55.983934 I TV: Elapsed time since TV constructor was called: 856 ms
2014-10-16 20:49:56.137503 I ALSA: OpenDevice sysdefault:CARD=NVidia
2014-10-16 20:49:56.251295 I AOS: Sample rate 48000 is supported
2014-10-16 20:49:56.251377 I AOS: Format signed 24 bit MSB is supported
2014-10-16 20:49:56.251407 I AOS: Format signed 32 bit is supported
2014-10-16 20:49:56.251465 I AOS: 2 channel(s) are supported
2014-10-16 20:49:56.315023 N AudioPlayer: Enabling Audio
2014-10-16 20:49:56.595229 I Player(0): Stretch Factor 1, allow passthru 
2014-10-16 20:49:56.595804 I AFD: Buffer size: 32768 streamed 0 seekable 1
2014-10-16 20:49:57.537705 I AFD: Stream #0, has id 0x1 codec id H264, type Video, bitrate 10412218 at 0x7f6e08854880
2014-10-16 20:49:57.537741 I AFD: Stream #1, has id 0x2 codec id AAC, type Audio, bitrate 63991 at 0x7f6e08854c00
2014-10-16 20:49:57.537759 I AFD: codec AAC has 1 channels
2014-10-16 20:49:57.537773 I AFD: Looking for decoder for AAC
2014-10-16 20:49:57.542348 I AFD: Opened codec 0x7f6e08854fe0, id(AAC) type(Audio)
2014-10-16 20:49:57.542567 I AFD: Audio Track #1, of type (Normalny) is A/V stream #1 (id=0x2) and has 1 channels in the Undetermined language(7695972).
2014-10-16 20:49:57.542583 I AFD: Trying to select best video track
2014-10-16 20:49:57.542665 I AFD: Selected track #0 (id 0x1 codec id H264, type Video, bitrate 10412218 at 0x7f6e08854880)
2014-10-16 20:49:57.546567 I VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasic,vdpaubasic) filt(vdpaucolorspace=auto,vdpausharpen=0.5,vdpaudenoise=1,0)
2014-10-16 20:49:57.546664 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0,vdpaudenoise=1.0)
2014-10-16 20:49:57.546709 I VDP: LoadBestPreferences(2048x2048, 0)
2014-10-16 20:49:57.546767 I VDP: LoadBestPreferences(2048x2048, 60)
2014-10-16 20:49:57.546807 I VDP: LoadBestPreferences(1280x720, 60)
2014-10-16 20:49:57.546968 I VDPAU: Checking VDPAU support.
2014-10-16 20:49:58.296359 I VDPAU: Version 1
2014-10-16 20:49:58.296388 I VDPAU: Information NVIDIA VDPAU Driver Shared Library 340.46 Wed Sep 24 13:57:15 PDT 2014
2014-10-16 20:49:58.296432 I VDPAU: HQ Scaling not supported.
2014-10-16 20:49:58.296448 I VDPAU: MPEG4 hardware acceleration not supported.
2014-10-16 20:49:58.307140 I AFD: Using 1 CPUs for decoding
2014-10-16 20:49:58.307182 I AFD: InitVideoCodec() 0x7f6e08563260 id(H264) type (Video).
2014-10-16 20:49:58.307306 I AFD: Selected FPS is 23.9865 (avg 23.9865 codec 600 container 600 estimated 23.976)
2014-10-16 20:49:58.307382 I Player(0): SetFrameInterval ps:1 scan:3
2014-10-16 20:49:58.307436 I Player(0): detectInterlace(Detect Scan, Interlaced Scan, 23.9865, 720) ->Progressive Scan
2014-10-16 20:49:58.307480 I AFD: Using vdpau for video decoding
2014-10-16 20:49:58.308904 I AFD: Opened codec 0x7f6e08563260, id(H264) type(Video)
2014-10-16 20:49:58.309070 I AFD: Selected track 1: Undetermined AAC 1ch (A/V Stream #1)
2014-10-16 20:49:58.309178 I AFD: Audio data is planar
2014-10-16 20:49:58.310777 I AFD: Initializing audio parms from audio track #1
2014-10-16 20:49:58.310882 I AFD: Audio format changed 
from id(NONE) -1Hz -1ch -1bps (profile 0) to id( AAC) 44100Hz 1ch 32bps (profile 0)
2014-10-16 20:49:58.310955 I AOBase: Needs upmix from 1 -> 2 channels
2014-10-16 20:49:58.311004 I AOBase: Killing AudioOutputDSP
2014-10-16 20:49:58.311098 I AOBase: Original codec was AAC, 32 bit floating point, 44 kHz, 1 channels
2014-10-16 20:49:58.311165 I AOBase: enc(0), passthru(0), features () configured_channels(2), 2 channels supported(1) max_channels(2)
2014-10-16 20:49:58.311228 I AOBase: Resampling from 44 kHz to 48 kHz with quality medium
2014-10-16 20:49:58.311385 I AOBase: Resampler allocating 17840
2014-10-16 20:49:58.311441 I AOBase: Audio processing enabled
2014-10-16 20:49:58.311496 I AOBase: Opening audio device \'sysdefault:CARD=NVidia\' ch 2(1) sr 48000 sf signed 32 bit reenc 0
2014-10-16 20:49:58.311519 I ALSA: OpenDevice sysdefault:CARD=NVidia
2014-10-16 20:49:58.322711 I ALSA: SetParameters(format=10, channels=2, rate=48000, buffer_time=500000, period_time=4)
2014-10-16 20:49:58.323469 I ALSA: Buffer size range from 2048 to 16384
2014-10-16 20:49:58.323489 I ALSA: Period size range from 1024 to 1024
2014-10-16 20:49:58.323604 W ALSA: Requested 500000us got 341333 buffer time
2014-10-16 20:49:58.323939 I ALSA: Hardware audio buffer cur: 512 need: 768 max allowed: 32768
2014-10-16 20:49:58.324025 W ALSA: Try to manually increase audio buffer with: echo 768 | sudo tee /proc/asound/card0/pcm0p/sub0/prealloc
2014-10-16 20:49:58.324072 I ALSA: Buffer time = 341333 us
2014-10-16 20:49:58.324130 I ALSA: Period time = 16 periods
2014-10-16 20:49:58.324392 I ALSA: Buffer size = 16384 | Period size = 1024
2014-10-16 20:49:58.326846 I ALSA: found playback control PCM on mixer device default
2014-10-16 20:49:58.326929 I ALSA: mixer volume range on mixer device default/PCM - min 0, max 255, range 255
2014-10-16 20:49:58.326950 I ALSA: mixer device default/PCM set up successfully
2014-10-16 20:49:58.328255 I ALSA: channel 0 volume set 80 => 204
2014-10-16 20:49:58.328292 I ALSA: channel 1 volume set 80 => 204
2014-10-16 20:49:58.328311 I AOBase: Audio fragment size: 4096
2014-10-16 20:49:58.328411 I ALSA: get volume channel 0: 80
2014-10-16 20:49:58.328444 I ALSA: channel 0 volume set 80 => 204
2014-10-16 20:49:58.328471 I ALSA: channel 1 volume set 80 => 204
2014-10-16 20:49:58.328513 I AOBase: Audio Stretch Factor: 1
2014-10-16 20:49:58.328618 I AOBase: Ending Reconfigure()
2014-10-16 20:49:58.328682 I Dec: Selected track #1 (type 2) in the Unknown language(0)
2014-10-16 20:49:58.328722 I Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2014-10-16 20:49:58.328961 I AOBase: kickoffOutputAudioLoop: pid = 8276
2014-10-16 20:49:58.328981 I AOBase: OutputAudioLoop: Play Event
2014-10-16 20:49:58.335848 I Dec: SyncPositionMap prerecorded, from DB: 0 entries
2014-10-16 20:49:58.337035 I AFD: Recording has no position -- using libavformat seeking.
2014-10-16 20:49:58.337535 I AFD: Successfully opened decoder for file: "http://192.168.1.150:7001/1/898a48e0-a0f3-5b13-91a9-4065f722e3dd.MOV". novideo(0)
2014-10-16 20:49:58.338513 I VideoOutput: Allowed renderers: vdpau
2014-10-16 20:49:58.338552 I VideoOutput: Allowed renderers (filt: vdpau): vdpau
2014-10-16 20:49:58.341286 I VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasic,vdpaubasic) filt(vdpaucolorspace=auto,vdpausharpen=0.5,vdpaudenoise=1,0)
2014-10-16 20:49:58.341366 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0,vdpaudenoise=1.0)
2014-10-16 20:49:58.341398 I VDP: LoadBestPreferences(2048x2048, 0)
2014-10-16 20:49:58.341449 I VDP: LoadBestPreferences(2048x2048, 60)
2014-10-16 20:49:58.341489 I VDP: LoadBestPreferences(1280x720, 60)
2014-10-16 20:49:58.341544 I VideoOutput: Preferred renderer: vdpau
2014-10-16 20:49:58.341576 I VideoOutput: Trying video renderer: \'vdpau\'
2014-10-16 20:49:58.359893 I VDP: Accepting: cmp(>= 0 720) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpaubasic,vdpaubasic) filt(vdpaucolorspace=auto,vdpausharpen=0.5,vdpaudenoise=1,0)
2014-10-16 20:49:58.359994 I VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpausharpen=1.0,vdpaudenoise=1.0)
2014-10-16 20:49:58.360040 I VDP: LoadBestPreferences(2048x2048, 0)
2014-10-16 20:49:58.360098 I VDP: LoadBestPreferences(2048x2048, 60)
2014-10-16 20:49:58.360193 I VideoOutWindow::SetPIPState. pip_state: 0]
2014-10-16 20:49:58.360218 I VDP: LoadBestPreferences(2048x2048, 23.9865)
2014-10-16 20:49:58.360271 I Clearing OpenGL painter cache.
2014-10-16 20:49:58.363956 I Snapping width to avoid scaling: width: 1280, left: 0
2014-10-16 20:49:58.364003 I Display Rect left: 0, top: 128, width: 1280, height: 768, aspect: 1.33333
2014-10-16 20:49:58.364032 I Video Rect left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2014-10-16 20:49:58.364062 I VDP: LoadBestPreferences(1280x720, 23.9865)
2014-10-16 20:49:58.364115 I Snapping width to avoid scaling: width: 1280, left: 0
2014-10-16 20:49:58.364143 I Display Rect left: 0, top: 128, width: 1280, height: 768, aspect: 1.33333
2014-10-16 20:49:58.364169 I Video Rect left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2014-10-16 20:49:58.364197 I VDP: SetVideoRenderer(vdpau)
2014-10-16 20:49:58.364217 I VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2014-10-16 20:49:58.365719 I VideoOutput: Pixel dimensions: Screen 1280x1024, window 1280x1024
2014-10-16 20:49:58.365809 I VideoOutput: Actual display dimensions: 433x347 mm Aspect: 1.24784
2014-10-16 20:49:58.365852 I VideoOutput: Estimated window dimensions: 433x347 mm Aspect: 1.24784
2014-10-16 20:49:58.365989 I VidOutVDPAU: VDPAU Sharpen 0.50
2014-10-16 20:49:58.366021 I VidOutVDPAU: VDPAU Denoise 1.00
2014-10-16 20:49:58.428913 I VDPAU: Created 2 output surfaces.
2014-10-16 20:49:58.428967 I VDPAU: Set colorkey to 0x20202
2014-10-16 20:49:58.428990 I VDPAU: Created VDPAU render device 1280x1024
2014-10-16 20:49:58.429030 I VidOutVDPAU: Created VDPAU osd (1280x1024)
2014-10-16 20:49:58.520979 I ColourSpace: PictureAttributes: Brightness, Contrast, Colour, Hue, Studio Levels, 
2014-10-16 20:49:58.521003 I VidOutVDPAU: Using ITU BT.709 colorspace
2014-10-16 20:49:58.521049 I Snapping height to avoid scaling: height: 720, top: 152
2014-10-16 20:49:58.521069 I Snapping width to avoid scaling: width: 1280, left: 0
2014-10-16 20:49:58.521105 I Display Rect left: 0, top: 152, width: 1280, height: 720, aspect: 1.24784
2014-10-16 20:49:58.521133 I Video Rect left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2014-10-16 20:49:58.521148 I VidOutVDPAU: Created VDPAU context (GPU decode)
2014-10-16 20:49:58.525057 I Over/underscan. V: 0, H: 0
2014-10-16 20:49:58.525086 I Snapping height to avoid scaling: height: 720, top: 152
2014-10-16 20:49:58.525106 I Snapping width to avoid scaling: width: 1280, left: 0
2014-10-16 20:49:58.525138 I Display Rect left: 0, top: 152, width: 1280, height: 720, aspect: 1.24784
2014-10-16 20:49:58.525166 I Video Rect left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2014-10-16 20:49:58.525195 N Player(0): Forcing decode extra audio option on (Video method requires it).
2014-10-16 20:49:58.525287 I FilterManager: GetFilterInfo(convert) returning: 0x0
2014-10-16 20:49:58.525335 I FilterManager: GetFilterInfo(0) returning: 0x0
2014-10-16 20:49:58.525351 E FilterManager: Failed to load filter \'0\', no such filter exists
2014-10-16 20:49:58.525375 I Player(0): LoadFilters(\'vdpaucolorspace=auto,vdpausharpen=0.5,vdpaudenoise=1,0\'..) -> 0x0
2014-10-16 20:49:58.530838 I Player(0): Decoder thread starting.
2014-10-16 20:49:58.548602 I OSD: Loaded window osd_message
2014-10-16 20:49:58.563957 I OSD: Loaded window osd_input
2014-10-16 20:49:58.643483 I OSD: Loaded window program_info
2014-10-16 20:49:58.676916 I OSD: Loaded window browse_info
2014-10-16 20:49:58.704353 I OSD: Loaded window osd_status
2014-10-16 20:49:58.757206 I OSD: Loaded window osd_program_editor
2014-10-16 20:49:58.814939 I OSD: Loaded window osd_debug
2014-10-16 20:49:58.815010 I OSD: Loaded OSD: size 1280x1024 offset 0+0
2014-10-16 20:49:58.815418 I OSD: Created window aa_OSD_SUBTITLES
2014-10-16 20:49:58.815567 I Player(0): ClearAfterSeek(0)
2014-10-16 20:49:58.822807 I VDP: GetFilteredDeint() : vdpau -> \'vdpaubasic\'
2014-10-16 20:49:58.826741 I AOBase: OutputAudioLoop: Play Event
2014-10-16 20:49:58.843754 I VidOutVDPAU: Enabled deinterlacing.
2014-10-16 20:49:58.843934 E VSYNC: DRMVideoSync: VBlank ioctl did not work, unimplemented in this driver?
2014-10-16 20:49:58.844126 E VSYNC: RTCVideoSync: Could not open /dev/rtc: 
eno: No such file or directory (2)
2014-10-16 20:49:58.846365 I Player(0): Video timing method: USleep with busy wait
2014-10-16 20:49:58.846422 I Player(0): Display Refresh Rate: 60.020 Video Frame Rate: 23.987
2014-10-16 20:49:58.846456 I Player(0): SetFrameInterval ps:1 scan:1
2014-10-16 20:49:58.850141 I playCtx: StartPlaying(): took 0 ms to start player.
2014-10-16 20:49:58.850188 I TV: Created player.
2014-10-16 20:49:58.850285 I TV: StartPlayer(0, WatchingVideo, main) -- end ok
2014-10-16 20:49:58.850323 I TV: Changing from None to WatchingVideo
2014-10-16 20:49:58.863433 I TV: DrawUnusedRects() -- begin
2014-10-16 20:49:58.863496 I TV: DrawUnusedRects() -- end
2014-10-16 20:49:58.863602 I TV: Main UI disabled.
2014-10-16 20:49:58.863635 I TV: HandleStateChange(0) -- end
2014-10-16 20:49:58.863816 I TV: tv->Playback() -- end
2014-10-16 20:49:58.866578 I TV: Entering main playback loop.
2014-10-16 20:49:58.937205 I VidOutVDPAU: Created VDPAU decoder (1 ref frames)
2014-10-16 20:49:58.938632 I Player(0): Waiting for video buffers...
2014-10-16 20:49:58.966015 I TV: DoPlayerSeekToFrame 935
2014-10-16 20:49:58.966063 I AOBase: Pause 1
2014-10-16 20:49:58.966102 I UpdateOSDSeekMessage(Skocz do, 2)
2014-10-16 20:49:58.973398 I AOBase: OutputAudioLoop: audio paused
2014-10-16 20:49:59.013894 I AirPlay: File: \'http://192.168.1.150:7001/1/898a48e0-a0f3-5b13-91a9-4065f722e3dd.MOV\' start_pos \'0.229243\'
2014-10-16 20:49:59.027900 I AFD: DoFastForward(934 (7), do discard frames)
2014-10-16 20:49:59.028095 I AFD: No DTS Seeking Hack!
2014-10-16 20:49:59.028137 I AFD: SeekReset(-1, 0, do flush, do discard)
2014-10-16 20:49:59.028194 I AFD: SeekReset() flushing
2014-10-16 20:49:59.029499 I VidOutVDPAU: DiscardFrames(1)
2014-10-16 20:49:59.029598 I VideoBuffers::DiscardFrames(1): AAAAAAAUUUUUUU
2014-10-16 20:49:59.029662 I VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA -- done
2014-10-16 20:49:59.029711 I VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAA -- done()
2014-10-16 20:49:59.083418 I Player(0): ClearAfterSeek(0)
2014-10-16 20:49:59.087774 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.088012 I AirPlay: Scrub: (get) returned 39.6591 of 173
2014-10-16 20:49:59.093489 N Player(0): Waited 155ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:49:59.111814 I AirPlay: Method: HTTP/1.1 URI: 200
2014-10-16 20:49:59.155832 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.156018 I AirPlay: Scrub: (get) returned 39.6591 of 173
2014-10-16 20:49:59.182572 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.182756 I AirPlay: Scrub: (get) returned 39.6591 of 173
2014-10-16 20:49:59.191590 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.191771 I AirPlay: Scrub: (get) returned 39.6591 of 173
2014-10-16 20:49:59.200098 N Player(0): Waited 261ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:49:59.232800 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.232987 I AirPlay: Scrub: (get) returned 39.6591 of 173
2014-10-16 20:49:59.310071 N Player(0): Waited 371ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:49:59.387570 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.387766 I AirPlay: Scrub: (get) returned 39.6591 of 173
2014-10-16 20:49:59.413419 N Player(0): Waited 475ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:49:59.429762 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.429946 I AirPlay: Scrub: (get) returned 39.6591 of 173
2014-10-16 20:49:59.452770 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.696854 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.703420 N Player(0): Waited 765ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:49:59.729629 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.729816 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.755613 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.755791 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.783076 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.783258 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.792917 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.793102 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.813441 N Player(0): Waited 875ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:49:59.827901 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.828079 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.899196 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.899427 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.915911 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.916084 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.920098 N Player(0): Waited 981ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:49:59.944588 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.944766 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.961178 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.961349 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:49:59.998551 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:49:59.998734 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.013295 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.013501 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.030119 N Player(0): Waited 1091ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:50:00.048491 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.048706 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.085616 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.085800 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.098848 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.099028 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.133415 N Player(0): Waited 1195ms for video buffers AAAAAAAAAAAAAA
2014-10-16 20:50:00.146201 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.146382 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.159505 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.159690 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.194468 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.411110 I Player(0): progressive frame seen after 2 interlaced frames
2014-10-16 20:50:00.413613 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.414881 I VDPAU: Added 2 output surfaces (total 4, max 4)
2014-10-16 20:50:00.445051 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.445301 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.461296 I Player(0): Waiting for video buffers...
2014-10-16 20:50:00.505492 I Player(0): Disabled deinterlacing
2014-10-16 20:50:00.545242 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.545466 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.598740 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.598946 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.653377 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.653584 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.692370 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:00.692580 I AirPlay: Scrub: (get) returned 38 of 173
2014-10-16 20:50:00.839416 I AOBase: Pause 0
2014-10-16 20:50:00.859027 I AOBase: OutputAudioLoop: Play Event
2014-10-16 20:50:00.867244 I Player(0): Video is 9.77453 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:00.867292 I AOBase: Pause 1
2014-10-16 20:50:00.867415 I Player(0): Waiting for video buffers...
2014-10-16 20:50:00.880315 I AOBase: OutputAudioLoop: audio paused
2014-10-16 20:50:00.910149 I Player(0): Video is 16.8536 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:00.910264 I Player(0): Video is 21.9769 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:00.910335 I Player(0): Waiting for video buffers...
2014-10-16 20:50:00.950110 I Player(0): Video is 25.5736 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:00.950193 I Player(0): Waiting for video buffers...
2014-10-16 20:50:00.960599 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:01.005870 I Player(0): Video is 28.1092 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.006047 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.006902 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:01.046827 I Player(0): Video is 29.7171 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.046930 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.090098 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.090181 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.143453 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.143542 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.183454 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.183564 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.214306 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:01.214499 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:01.223544 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.223638 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.243446 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.243532 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.273435 I Player(0): Video is 29.9909 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.273516 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.316830 I Player(0): Video is 29.4492 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.316918 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.426740 N Player(0): Waited 110ms for video buffers AAAAAAAAffAAAA
2014-10-16 20:50:01.453197 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:01.453423 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:01.472698 I AFD: gopset not set, syncing positionMap
2014-10-16 20:50:01.472749 I Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2014-10-16 20:50:01.472768 I AFD: Initial key frame distance: 15.
2014-10-16 20:50:01.476792 I Player(0): Video is 28.6652 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.476883 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.503476 I Player(0): Video is 28.0293 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.503560 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.523561 I Player(0): Video is 27.0786 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.523661 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.563464 I Player(0): Video is 26.2336 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.563553 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.603590 I Player(0): Video is 25.2101 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.603707 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.663581 I Player(0): Video is 24.4305 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.663681 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.693577 I Player(0): Video is 23.5639 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.693680 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.735946 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:01.744700 I Player(0): Video is 22.4582 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.744852 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.750177 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:01.773859 I Player(0): Video is 21.4431 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.774039 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.813474 I Player(0): Video is 20.4659 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.813570 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.853505 I Player(0): Video is 19.4811 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.853598 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.893446 I Player(0): Video is 18.5866 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.893539 I Player(0): Waiting for video buffers...
2014-10-16 20:50:01.976202 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:01.976438 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:01.996759 N Player(0): Waited 103ms for video buffers AAAAAAFFuuAAAA
2014-10-16 20:50:01.996919 I Player(0): Video is 17.5199 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.997024 I Player(0): Video is 16.4621 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:01.997095 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.017388 I Player(0): Video is 15.4168 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.017517 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.080159 I Player(0): Video is 14.369 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.080252 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.130105 I Player(0): Video is 13.3253 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.130196 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.170144 I Player(0): Video is 12.3507 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.170282 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.210140 I Player(0): Video is 11.4698 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.210231 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.232458 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:02.232651 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:02.250148 I Player(0): Video is 10.4133 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.250245 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.293465 I Player(0): Video is 9.36908 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.293554 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.333450 I Player(0): Video is 8.45399 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.333539 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.366779 I Player(0): Video is 7.37191 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.366868 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.416783 I Player(0): Video is 6.50638 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.416870 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.500508 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:02.520133 N Player(0): Waited 103ms for video buffers AAAAffAAAAAAAA
2014-10-16 20:50:02.520211 I Player(0): Resetting audio buffer
2014-10-16 20:50:02.520215 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:02.576842 I Player(0): Video is 5.52742 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.576941 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.596796 I Player(0): Video is 14.6757 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.596880 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.636811 I Player(0): Video is 21.261 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.636900 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.676772 I Player(0): Video is 25.9541 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.676861 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.706780 I Player(0): Video is 29.3181 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.706864 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.739907 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:02.740153 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:02.750220 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.750316 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.780124 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.780219 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.820122 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.820208 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.873444 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.873535 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.916794 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.916883 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.946775 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.946859 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.966769 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:02.966858 I Player(0): Waiting for video buffers...
2014-10-16 20:50:02.996053 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:02.996251 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:03.076792 N Player(0): Waited 110ms for video buffers AAffAAAAAAAAAA
2014-10-16 20:50:03.086830 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.086924 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.106827 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.106913 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.156788 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.156875 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.186767 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.186854 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.226768 I Player(0): Video is 29.8497 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.226851 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.255380 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:03.280111 I Player(0): Video is 28.9776 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.280249 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.283500 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:03.333468 I Player(0): Video is 28.0117 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.333571 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.393450 I Player(0): Video is 27.2153 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.393540 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.423463 I Player(0): Video is 26.3421 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.423554 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.463453 I Player(0): Video is 25.2375 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.463536 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.504316 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:03.504518 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:03.514076 I Player(0): Video is 24.1572 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.514198 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.556858 I Player(0): Video is 23.251 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.556950 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.660088 N Player(0): Waited 103ms for video buffers ffAAAAAAAAAAAA
2014-10-16 20:50:03.680136 I Player(0): Video is 22.1996 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.680223 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.755310 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:03.755509 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:03.760203 I Player(0): Video is 21.1292 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.760321 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.790135 I Player(0): Video is 20.1704 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.790223 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.840113 I Player(0): Video is 19.1456 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.840201 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.880110 I Player(0): Video is 18.281 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.880199 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.920117 I Player(0): Video is 17.2367 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.920204 I Player(0): Waiting for video buffers...
2014-10-16 20:50:03.980125 I Player(0): Video is 16.2077 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:03.980221 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.020679 I Player(0): Video is 15.1601 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.020794 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.052353 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:04.106413 I Player(0): Video is 14.2245 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.106571 I Player(0): Video is 13.1629 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.106674 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.116888 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:04.150133 I Player(0): Video is 12.1209 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.150229 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.180101 I Player(0): Video is 11.2015 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.180187 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.262025 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:04.262225 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:04.283406 N Player(0): Waited 103ms for video buffers AAAAAAAAAAAAff
2014-10-16 20:50:04.283459 I Player(0): Resetting audio buffer
2014-10-16 20:50:04.323469 I Player(0): Video is 10.2061 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.323588 I Player(0): Video is 19.324 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.323659 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.333468 I Player(0): Video is 25.9106 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.333550 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.443420 N Player(0): Waited 110ms for video buffers fAfAAAAAAAAAAA
2014-10-16 20:50:04.546751 N Player(0): Waited 213ms for video buffers fAfAAAAAAAAAAA
2014-10-16 20:50:04.653412 N Player(0): Waited 320ms for video buffers fAfAAAAAAAAAAA
2014-10-16 20:50:04.691011 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:04.721729 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.721883 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.721954 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.733564 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:04.746872 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.746968 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.766808 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.766895 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.772241 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:04.772436 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:04.810147 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.810248 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.880168 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.880278 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.900121 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.900209 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.927349 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:04.927543 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:04.940155 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.940249 I Player(0): Waiting for video buffers...
2014-10-16 20:50:04.963484 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:04.964123 I Player(0): FPS: 21.06 Mean: 47476 Std.Dev: 45922 CPUs: 9% 8% 13% 15% 
2014-10-16 20:50:04.964289 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.073458 N Player(0): Waited 109ms for video buffers AAAAAAAAAAffAA
2014-10-16 20:50:05.126809 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.126902 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.192713 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:05.192918 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:05.196879 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.196975 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.236770 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.236858 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.276799 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.276886 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.316769 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.316853 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.370125 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.370225 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.413455 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.413544 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.433423 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.433503 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.444685 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:05.472124 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.472256 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.473499 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:05.506825 I Player(0): Video is 29.7707 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.506916 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.556810 I Player(0): Video is 28.9243 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.556907 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.586770 I Player(0): Video is 27.9598 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.586853 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.680112 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:05.680342 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:05.696742 N Player(0): Waited 110ms for video buffers AAAAAAAAFFuuAA
2014-10-16 20:50:05.696894 I Player(0): Video is 26.9245 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.697000 I Player(0): Video is 26.0281 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.697072 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.716806 I Player(0): Video is 25.1159 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.716890 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.780116 I Player(0): Video is 24.054 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.780208 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.800108 I Player(0): Video is 23.0057 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.800191 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.843460 I Player(0): Video is 21.9497 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.843546 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.903467 I Player(0): Video is 20.9237 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.903608 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.933464 I Player(0): Video is 19.8784 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.933551 I Player(0): Waiting for video buffers...
2014-10-16 20:50:05.962503 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:05.962702 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:05.986806 I Player(0): Video is 18.8846 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:05.986905 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.046860 I Player(0): Video is 17.8214 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.046965 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.096774 I Player(0): Video is 16.7481 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.096856 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.146796 I Player(0): Video is 15.7573 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.146885 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.217384 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:06.236867 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:06.253450 N Player(0): Waited 107ms for video buffers AAAAAAffAAAAAA
2014-10-16 20:50:06.253518 I Player(0): Resetting audio buffer
2014-10-16 20:50:06.296784 I Player(0): Video is 14.9182 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.296876 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.380139 I Player(0): Video is 24.0934 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.380229 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.430131 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.430230 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.460088 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.460167 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.471793 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:06.471990 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:06.503490 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.503586 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.553481 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.553579 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.593447 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.593530 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.643447 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.643539 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.673443 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.673525 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.703451 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.703538 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.727781 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:06.756847 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:06.760264 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.760367 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.780100 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.780177 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.873459 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.873572 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.873641 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.903473 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.903561 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.926804 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.926895 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.970103 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:06.970189 I Player(0): Waiting for video buffers...
2014-10-16 20:50:06.982857 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:06.983059 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:07.010171 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.010262 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.070171 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.070273 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.113520 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.113610 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.166773 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.166906 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.210106 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.210192 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.238478 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:07.238681 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:07.243537 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.243628 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.306794 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.306887 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.410079 N Player(0): Waited 103ms for video buffers AAffAAAAAAAAAA
2014-10-16 20:50:07.433498 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.433588 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.443424 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.443501 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.488188 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:07.537738 I Player(0): Video is 29.4169 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.538422 I Player(0): Video is 28.4791 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.538528 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.543505 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:07.580147 I Player(0): Video is 27.5298 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.580248 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.620115 I Player(0): Video is 26.4401 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.620195 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.660131 I Player(0): Video is 25.6108 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.660221 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.680150 I Player(0): Video is 24.5931 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.680237 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.720115 I Player(0): Video is 23.7039 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.720201 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.720846 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:07.721036 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:07.793457 I Player(0): Video is 22.6412 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.793546 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.896735 N Player(0): Waited 103ms for video buffers AAAAAAAAAAAAff
2014-10-16 20:50:07.926809 I Player(0): Video is 21.6463 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.926901 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.940159 I Player(0): Video is 20.6842 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:07.940237 I Player(0): Waiting for video buffers...
2014-10-16 20:50:07.948801 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:07.949057 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:08.006813 I Player(0): Video is 19.7947 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.006924 I Player(0): Video is 18.6539 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.006992 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.046823 I Player(0): Video is 17.5524 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.046918 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.090161 I Player(0): Video is 16.5704 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.090247 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.156802 I Player(0): Video is 15.6 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.156896 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.206829 I Player(0): Video is 14.5004 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.206920 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.211701 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:08.245787 I Player(0): Video is 13.4839 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.245912 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.253583 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:08.296807 I Player(0): Video is 12.5595 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.296893 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.356828 I Player(0): Video is 11.5424 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.356923 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.426793 I Player(0): Video is 10.5038 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.426882 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.464339 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:08.464552 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:08.476789 I Player(0): Video is 9.50892 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.476879 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.506810 I Player(0): Video is 8.43296 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.506919 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.610106 N Player(0): Waited 103ms for video buffers AuAAAAAAAAAAFF
2014-10-16 20:50:08.610160 I Player(0): Resetting audio buffer
2014-10-16 20:50:08.610302 I Player(0): Video is 7.55402 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:08.610377 I Player(0): Waiting for video buffers...
2014-10-16 20:50:08.640086 I AOBase: Pause 0
2014-10-16 20:50:08.645684 I AOBase: OutputAudioLoop: Play Event
2014-10-16 20:50:08.709329 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:08.709550 I AirPlay: Scrub: (get) returned 39 of 173
2014-10-16 20:50:08.980810 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:09.036919 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:09.070069 I ALSA: WriteAudio: buffer underrun
2014-10-16 20:50:09.103835 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.136848 I Player(0): Video is 10.4342 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.136879 I AOBase: Pause 1
2014-10-16 20:50:09.136991 I Player(0): Video is 18.2178 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.137010 I AOBase: Pause 1
2014-10-16 20:50:09.137078 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.157725 I AOBase: OutputAudioLoop: audio paused
2014-10-16 20:50:09.166813 I Player(0): Video is 23.8037 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.166899 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.206783 I Player(0): Video is 27.8252 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.206874 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.228987 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:09.229201 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:09.276789 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.276875 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.310123 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.310212 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.370121 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.370208 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.420121 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.420211 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.470132 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.470217 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.477861 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:09.478078 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:09.533437 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.533527 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.603488 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.603928 I Player(0): FPS: 20.48 Mean: 48834 Std.Dev: 28870 CPUs: 7% 0% 10% 0% 
2014-10-16 20:50:09.604067 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.633474 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.633560 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.734530 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:09.736750 N Player(0): Waited 103ms for video buffers AAAAAAAAffAAAA
2014-10-16 20:50:09.784957 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.785091 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.790228 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:09.850143 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.850232 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.903483 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.903574 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.933442 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.933528 I Player(0): Waiting for video buffers...
2014-10-16 20:50:09.983436 I Player(0): Video is 29.5392 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:09.983518 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.001690 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:10.001917 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:10.016813 I Player(0): Video is 28.6368 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.016899 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.080130 I Player(0): Video is 27.6661 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.080227 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.120146 I Player(0): Video is 26.6863 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.120233 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.173466 I Player(0): Video is 25.6995 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.173552 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.216802 I Player(0): Video is 24.7436 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.216887 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.241490 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:10.241704 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:10.256776 I Player(0): Video is 23.7987 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.256865 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.286779 I Player(0): Video is 22.8382 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.286859 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.390077 N Player(0): Waited 103ms for video buffers AAAAAAffAAAAAA
2014-10-16 20:50:10.455382 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:10.470276 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:10.493450 N Player(0): Waited 207ms for video buffers AAAAAAffAAAAAA
2014-10-16 20:50:10.503470 I Player(0): Video is 21.902 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.503580 I Player(0): Video is 20.858 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.503647 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.513425 I Player(0): Video is 19.8231 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.513500 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.523473 I Player(0): Video is 18.8551 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.523559 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.533467 I Player(0): Video is 17.7993 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.533572 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.553467 I Player(0): Video is 16.8155 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.553552 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.573513 I Player(0): Video is 15.8018 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.573603 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.663465 I Player(0): Video is 14.8197 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.663555 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.693429 I Player(0): Video is 13.8432 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.693515 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.702915 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:10.703171 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:10.733441 I Player(0): Video is 12.895 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.733530 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.786808 I Player(0): Video is 11.938 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.786897 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.826848 I Player(0): Video is 10.9084 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.826941 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.936745 N Player(0): Waited 110ms for video buffers AAAAFFuAAAAAAA
2014-10-16 20:50:10.936895 I Player(0): Video is 9.88431 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.936975 I Player(0): Waiting for video buffers...
2014-10-16 20:50:10.954166 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:10.954377 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:10.970133 I Player(0): Video is 8.85841 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:10.970216 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.020136 I Player(0): Video is 7.93907 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.020227 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.073471 I Player(0): Video is 6.91377 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.073557 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.116790 I Player(0): Video is 5.9349 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.116880 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.166806 I Player(0): Video is 4.96687 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.166891 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.227485 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:11.256938 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:11.263552 I Player(0): Video is 3.90504 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.263674 I AOBase: Pause 0
2014-10-16 20:50:11.269680 I AOBase: OutputAudioLoop: Play Event
2014-10-16 20:50:11.461441 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:11.461673 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:11.473681 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.580073 N Player(0): Waited 106ms for video buffers AAffAAAAAAAAAA
2014-10-16 20:50:11.580128 I Player(0): Resetting audio buffer
2014-10-16 20:50:11.589669 I AOBase: OutputAudioLoop: Play Event
2014-10-16 20:50:11.660406 I Player(0): Video is 11.1932 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.660435 I AOBase: Pause 1
2014-10-16 20:50:11.660533 I Player(0): Video is 18.955 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.660552 I AOBase: Pause 1
2014-10-16 20:50:11.660642 I Player(0): Video is 24.5244 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.660660 I AOBase: Pause 1
2014-10-16 20:50:11.660747 I Player(0): Video is 28.4557 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.660765 I AOBase: Pause 1
2014-10-16 20:50:11.660831 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.675032 I AOBase: OutputAudioLoop: audio paused
2014-10-16 20:50:11.700126 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.700213 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.705052 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:11.705299 I AirPlay: Scrub: (get) returned 46 of 173
2014-10-16 20:50:11.733446 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.733531 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.793441 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.793534 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.833473 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.833561 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.873460 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.873551 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.916799 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.916884 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.955382 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:11.974117 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:11.974246 I Player(0): Waiting for video buffers...
2014-10-16 20:50:11.983587 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:12.083409 N Player(0): Waited 109ms for video buffers ffAAAAAAAAAAAA
2014-10-16 20:50:12.103485 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.103577 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.170140 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.170228 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.190115 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.190199 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.204183 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:12.204389 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:12.236787 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.236870 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.266787 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.266874 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.306766 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.306855 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.356820 I Player(0): Video is 29.2299 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.356912 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.406821 I Player(0): Video is 28.3628 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.406910 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.446776 I Player(0): Video is 27.3767 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.446865 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.459315 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:12.459523 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:12.486899 I Player(0): Video is 26.3912 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.486994 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.516877 I Player(0): Video is 25.3583 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.516975 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.556875 I Player(0): Video is 24.4157 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.556968 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.660142 N Player(0): Waited 103ms for video buffers AAAAAAAAAAAAff
2014-10-16 20:50:12.670247 I Player(0): Video is 23.4149 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.670364 I Player(0): Video is 22.4724 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.670440 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.680274 I Player(0): Video is 21.5137 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.680400 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.704006 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:12.752846 I Player(0): Video is 20.4648 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.753011 I Player(0): Video is 19.4743 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.753082 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.760251 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:12.783433 I Player(0): Video is 18.4796 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.783519 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.823448 I Player(0): Video is 17.5776 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.823533 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.853486 I Player(0): Video is 16.6012 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.853579 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.893458 I Player(0): Video is 15.5572 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.893543 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.936790 I Player(0): Video is 14.5283 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.936879 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.966763 I Player(0): Video is 13.4748 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:12.966847 I Player(0): Waiting for video buffers...
2014-10-16 20:50:12.974022 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:12.974235 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:13.016806 I Player(0): Video is 12.5167 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.016893 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.123403 N Player(0): Waited 107ms for video buffers AAAAAAAAAAffAA
2014-10-16 20:50:13.143492 I Player(0): Video is 11.5643 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.143583 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.193465 I Player(0): Video is 10.5862 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.193553 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.233483 I Player(0): Video is 9.51672 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.233571 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.239225 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:13.239504 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:13.273438 I Player(0): Video is 8.46879 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.273526 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.313436 I Player(0): Video is 7.52694 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.313519 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.353490 I Player(0): Video is 6.56867 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.353580 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.393439 I Player(0): Video is 5.60413 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.393578 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.423446 I Player(0): Video is 4.58088 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.423528 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.463446 I Player(0): Video is 3.64553 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:13.463533 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.486150 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:13.507626 I AOBase: Pause 0
2014-10-16 20:50:13.509675 I AOBase: OutputAudioLoop: Play Event
2014-10-16 20:50:13.513602 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:13.537039 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.647946 I Player(0): Waiting for video buffers...
2014-10-16 20:50:13.722512 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:13.722723 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:13.753428 N Player(0): Waited 106ms for video buffers AAAAAAAAffAAAA
2014-10-16 20:50:13.863399 N Player(0): Waited 216ms for video buffers AAAAAAAAffAAAA
2014-10-16 20:50:13.973394 N Player(0): Waited 326ms for video buffers AAAAAAAAffAAAA
2014-10-16 20:50:13.973444 I Player(0): Resetting audio buffer
2014-10-16 20:50:13.979012 I AOBase: OutputAudioLoop: Play Event
2014-10-16 20:50:13.996112 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:13.996353 I AirPlay: Scrub: (get) returned 48 of 173
2014-10-16 20:50:14.037072 I Player(0): Video is 12.8601 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.037105 I AOBase: Pause 1
2014-10-16 20:50:14.037200 I Player(0): Video is 21.5064 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.037219 I AOBase: Pause 1
2014-10-16 20:50:14.037306 I Player(0): Video is 27.7453 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.037324 I AOBase: Pause 1
2014-10-16 20:50:14.037410 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.037428 I AOBase: Pause 1
2014-10-16 20:50:14.037513 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.037532 I AOBase: Pause 1
2014-10-16 20:50:14.037617 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.037635 I AOBase: Pause 1
2014-10-16 20:50:14.037721 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.037738 I AOBase: Pause 1
2014-10-16 20:50:14.037804 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.046774 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.046795 I AOBase: Pause 1
2014-10-16 20:50:14.046893 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.046911 I AOBase: Pause 1
2014-10-16 20:50:14.047348 I Player(0): FPS: 21.38 Mean: 46767 Std.Dev: 51795 CPUs: 15% 0% 3% 2% 
2014-10-16 20:50:14.047472 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.050077 I AOBase: OutputAudioLoop: audio paused
2014-10-16 20:50:14.076809 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.076915 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.106808 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.106900 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.216721 N Player(0): Waited 110ms for video buffers AAAAAAffAAAAAA
2014-10-16 20:50:14.263287 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:14.275449 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.275571 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.276970 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:14.380065 N Player(0): Waited 104ms for video buffers AAAAAAAAfAAAAA
2014-10-16 20:50:14.441190 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:14.441416 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:14.483385 N Player(0): Waited 208ms for video buffers AAAAAAAAfAAAAA
2014-10-16 20:50:14.593381 N Player(0): Waited 318ms for video buffers AAAAAAAAfAAAAA
2014-10-16 20:50:14.665272 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:14.665500 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:14.703392 N Player(0): Waited 428ms for video buffers AAAAAAAAfAAAAA
2014-10-16 20:50:14.803443 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.803548 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.803616 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.823424 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.823499 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.853466 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.853550 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.873418 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.873497 I Player(0): Waiting for video buffers...
2014-10-16 20:50:14.903417 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:14.903491 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.006731 N Player(0): Waited 103ms for video buffers AfAAAAAAAAAAAf
2014-10-16 20:50:15.020526 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:15.045180 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.045322 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.053599 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:15.056809 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.056891 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.066756 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.066834 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.106785 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.106863 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.150093 I Player(0): Video is 29.0833 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.150169 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.206341 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:15.206605 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:15.253398 N Player(0): Waited 103ms for video buffers AAAAffAAAAAAAA
2014-10-16 20:50:15.263457 I Player(0): Video is 28.133 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.263565 I Player(0): Video is 27.0784 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.263637 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.283415 I Player(0): Video is 26.0415 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.283489 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.313413 I Player(0): Video is 24.9881 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.313486 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.353461 I Player(0): Video is 23.9821 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.353542 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.396786 I Player(0): Video is 22.9758 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.396868 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.426750 I Player(0): Video is 21.9992 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.426828 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.461397 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:15.461626 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:15.466756 I Player(0): Video is 21.0508 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.466834 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.506772 I Player(0): Video is 20.0877 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.506853 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.546746 I Player(0): Video is 19.0715 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.546823 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.590120 I Player(0): Video is 18.0575 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.590200 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.630088 I Player(0): Video is 17.0632 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.630167 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.707681 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:15.723668 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:15.733430 N Player(0): Waited 103ms for video buffers AAffAAAAAAAAAA
2014-10-16 20:50:15.743440 I Player(0): Video is 16.0236 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.743522 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.786752 I Player(0): Video is 15.052 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.786825 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.816774 I Player(0): Video is 14.0894 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.816853 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.866758 I Player(0): Video is 13.0137 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.866838 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.906799 I Player(0): Video is 12.003 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.906907 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.936762 I Player(0): Video is 10.9991 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.936841 I Player(0): Waiting for video buffers...
2014-10-16 20:50:15.961697 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:15.961907 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:15.966764 I Player(0): Video is 10.0303 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:15.966840 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.006761 I Player(0): Video is 8.99192 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.006836 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.030108 I Player(0): Video is 7.91926 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.030188 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.050083 I Player(0): Video is 6.90489 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.050157 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.070099 I Player(0): Video is 5.86827 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.070173 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.130146 I Player(0): Video is 4.82096 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.130232 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.235015 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:16.240077 N Player(0): Waited 109ms for video buffers ffAAAAAAAAAAAA
2014-10-16 20:50:16.240132 I Player(0): Resetting audio buffer
2014-10-16 20:50:16.250296 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:16.310140 I Player(0): Video is 3.90955 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.310256 I Player(0): Video is 13.0845 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.310324 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.330095 I Player(0): Video is 19.7198 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.330192 I Player(0): Video is 24.4204 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.330259 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.400126 I Player(0): Video is 27.6941 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.400215 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.420086 I Player(0): Video is 29.9394 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.420161 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.453463 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.453547 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.516762 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.516839 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.536753 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.536827 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.566769 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.566852 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.572911 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:16.573120 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:16.586758 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.586833 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.606801 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.606885 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.716725 N Player(0): Waited 110ms for video buffers AAAAAAAAAAAAff
2014-10-16 20:50:16.736765 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.736848 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.740646 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:16.796900 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:16.803508 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.803601 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.856796 I Player(0): Video is 29.9913 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.856876 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.896774 I Player(0): Video is 29.2157 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.896854 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.926752 I Player(0): Video is 28.3822 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.926829 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.980168 I Player(0): Video is 27.4512 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:16.980251 I Player(0): Waiting for video buffers...
2014-10-16 20:50:16.984003 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:16.984214 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:17.000095 I Player(0): Video is 26.531 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.000173 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.050091 I Player(0): Video is 25.6131 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.050167 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.090117 I Player(0): Video is 24.6907 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.090199 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.133447 I Player(0): Video is 23.7471 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.133525 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.173423 I Player(0): Video is 22.8115 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.173498 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.213433 I Player(0): Video is 21.816 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.213512 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.282013 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:17.282277 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:17.316731 N Player(0): Waited 103ms for video buffers AAAAAAAAAAffAA
2014-10-16 20:50:17.326795 I Player(0): Video is 20.8175 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.326902 I Player(0): Video is 19.8707 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.326970 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.336755 I Player(0): Video is 18.9148 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.336827 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.346753 I Player(0): Video is 17.862 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.346826 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.390103 I Player(0): Video is 16.8805 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.390183 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.489469 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:17.516926 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:17.523406 N Player(0): Waited 133ms for video buffers FAFuuAAAAAAAAA
2014-10-16 20:50:17.523590 I Player(0): Video is 15.9166 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.523695 I Player(0): Video is 14.8338 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.523764 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.583431 I Player(0): Video is 13.7698 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.583508 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.623465 I Player(0): Video is 12.84 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.623554 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.683435 I Player(0): Video is 11.8427 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.683512 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.690939 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:17.691208 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:17.723487 I Player(0): Video is 10.8189 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.723599 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.766825 I Player(0): Video is 9.81125 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.766914 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.876728 N Player(0): Waited 110ms for video buffers AAAAAAAAffAAAA
2014-10-16 20:50:17.876780 I Player(0): Resetting audio buffer
2014-10-16 20:50:17.926762 I Player(0): Video is 8.82161 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.926865 I Player(0): Video is 17.8959 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.926933 I Player(0): Waiting for video buffers...
2014-10-16 20:50:17.942346 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:17.942554 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:17.966775 I Player(0): Video is 24.4557 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:17.966856 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.006762 I Player(0): Video is 29.2677 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.006839 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.050120 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.050201 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.080085 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.080159 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.123418 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.123495 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.153451 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.153586 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.193439 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.193521 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.214723 I AirPlay: Method: GET URI: /scrub
2014-10-16 20:50:18.230274 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.230417 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.230435 I AirPlay: Scrub: (get) returned 50 of 173
2014-10-16 20:50:18.270120 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.270204 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.300095 I Player(0): Video is 30 frames behind audio (too slow), dropping frame to catch up.
2014-10-16 20:50:18.300173 I Player(0): Waiting for video buffers...
2014-10-16 20:50:18.380639 I AirPlay: Method: POST URI: /rate
2014-10-16 20:50:18.403407 N Player(0): Waited 103ms for video buffers AAAAAAFFuuAAAA
2014-10-16 20:50:18.403783 I AOBase: Pause 1
2014-10-16 20:50:18.416373 I AOBase: OutputAudioLoop: audio paused
2014-10-16 20:50:18.450848 I UpdateOSDSeekMessage(Pauza, 0)
2014-10-16 20:50:18.452916 I Player(0): Play speed: rate: 23.9865 speed: 0 skip: 0 => new interval 41690
2014-10-16 20:50:18.490385 I AirPlay: Method: HTTP/1.1 URI: 200
2014-10-16 20:50:18.500049 I VidOutVDPAU: UpdatePauseFrame() AAAAAAFFUuuAAA

Attachments (5)

plotpackets.py (4.3 KB) - added by Roger Siddons 18 months ago.
Plots distribution of video & audio packets
nonAppleMovie.png (27.5 KB) - added by Roger Siddons 18 months ago.
./plotpackets.py PC250001.MOV
iphoneMovie.png (32.4 KB) - added by Roger Siddons 18 months ago.
./plotpackets.py iPhone/IMG_0266.MOV
iPhone6.png (46.5 KB) - added by Roger Siddons 18 months ago.
iPhone 6 youtube sample
mythavtest.20171119151717.15997.log (126.3 KB) - added by Roger Siddons 17 months ago.
Sync issue using XVideo

Download all attachments as: .zip

Change History (17)

Changed 18 months ago by Roger Siddons

Attachment: plotpackets.py added

Plots distribution of video & audio packets

Changed 18 months ago by Roger Siddons

Attachment: nonAppleMovie.png added

./plotpackets.py PC250001.MOV

Changed 18 months ago by Roger Siddons

Attachment: iphoneMovie.png added

./plotpackets.py iPhone/IMG_0266.MOV

comment:1 Changed 18 months ago by Roger Siddons

AirPlay is irrelevant here. The same issue can be seen by playing the sources via Video or Gallery.

The underlying issue is MythPlayer doesn't cope with Apple videos because of their structure: the video & audio is too dispersed. By the time the video packets are decoded the audio buffer overflows and the player starts extreme re-syncing.

Attaching a python script derived from https://github.com/zeroepoch/plotbitrate and some samples to illustrate. It's dated but works for me (Ubuntu 17.10) after installation of python3-matplotlib and python3-tk

comment:2 Changed 18 months ago by Peter Bennett

Owner: changed from JYA to Peter Bennett
Status: newassigned

comment:3 Changed 18 months ago by Peter Bennett

Please supply a test video. Also you can try the patch in #13172. If that patch does not do the trick you can try it with setting -O AudioReadAhead=1000 where 1000 in milliseconds. Also try higher values if necessary.

comment:4 Changed 18 months ago by Peter Bennett

Based on your graph with 2.5 second discontinuity you may have to use -O AudioReadAhead=2500

Last edited 18 months ago by Peter Bennett (previous) (diff)

Changed 18 months ago by Roger Siddons

Attachment: iPhone6.png added

iPhone 6 youtube sample

comment:5 Changed 18 months ago by Roger Siddons

Tried v30-Pre-308-g708baf2 with the patch. No change, even with 5000 readahead. Still stutters.

I found a 200Mb downloadable sample (via Mediafire link) at https://www.youtube.com/watch?v=HbDJtiAW3u0 "Apple iPhone 6 video sample (downloadable)"

It contains no metadata but has same fingerprint & exhibits same behaviour

comment:6 Changed 18 months ago by Peter Bennett

I downloaded the video from the mediafire link in that youtube video's comment section. I tested v30-Pre-316-g648a40d without the patch. I added it to the video library in MythTV and ran a video scan. In mythfrontend I went to videos and played it. There is a brief outage of sound near the start of playback, after that video and audio seems smooth. I can't tell if it is in sync because the commentary does not show the speaker's lips. Am I doing something wrong here? I am not testing airplay, just the frontend video playback. What do I do to see this "stutter"?

comment:7 Changed 18 months ago by Peter Bennett

Status: assignedinfoneeded

comment:8 Changed 17 months ago by Roger Siddons

Correct procedure. Audio/video plays for 0.5 sec, then freezes for 0.5 sec, then jumps forward 0.5 secs. Repeat until end.

Years ago I saw it on all playback profiles. Retesting I confirm it does now play ok with VDPAU/ALSA. Looks like it was fixed by 2a1ccc06a or thereabouts.

I've been reproducing it lately on an XVideo/ALSA test box. Log attached. After disabling the 'pause audio when dropping frames' https://github.com/MythTV/mythtv/blob/master/mythtv/libs/libmythtv/mythplayer.cpp#L1920 it plays smoothly with clipped audio & errors (ALSA: WriteAudio?: buffer underrun).

Extra audio buffering doesn't help. I haven't tried increasing the hardware buffer.

I can't get PulseAudio? to work at all (PulseAudio?: WriteAudio?, stream write failed: PA_ERR_INVALID)

And I thought AirPlay? broke a long time ago anyway.

Original issue was raised against VDPAU so I suggest this be closed/fixed with the above commit.

Changed 17 months ago by Roger Siddons

Sync issue using XVideo

comment:9 Changed 17 months ago by Peter Bennett

The ALSA: WriteAudio: buffer underrun issue should be fixed by 7cb7ed9b5. Increasing the new setting that is added by that commit will read more audio data equal to the number of milliseconds specified. That should prevent the buffer underrun. I will give it a try with XVideo.

comment:10 Changed 17 months ago by Peter Bennett

Status: infoneededassigned

comment:11 Changed 16 months ago by Peter Bennett

Resolution: Works for me
Status: assignedclosed

On latest master I do not see the problem, even with Xvideo output.

comment:12 Changed 12 months ago by Peter Bennett

Owner: changed from Peter Bennett to Peter Bennett
Note: See TracTickets for help on using tickets.