Opened 10 years ago
Closed 6 years ago
Last modified 6 years ago
#12302 closed Bug Report - General (Works for me)
Airplay: issue with playback of iPhone/iPad camera recordings
| Reported by: | 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)
Change History (17)
Changed 6 years ago by
| Attachment: | plotpackets.py added |
|---|
comment:1 Changed 6 years ago by
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 6 years ago by
| Owner: | changed from JYA to Peter Bennett |
|---|---|
| Status: | new → assigned |
comment:3 Changed 6 years ago by
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 6 years ago by
Based on your graph with 2.5 second discontinuity you may have to use -O AudioReadAhead=2500
comment:5 Changed 6 years ago by
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 6 years ago by
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 6 years ago by
| Status: | assigned → infoneeded |
|---|
comment:8 Changed 6 years ago by
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 6 years ago by
| Attachment: | mythavtest.20171119151717.15997.log added |
|---|
Sync issue using XVideo
comment:9 Changed 6 years ago by
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 6 years ago by
| Status: | infoneeded → assigned |
|---|
comment:11 Changed 6 years ago by
| Resolution: | → Works for me |
|---|---|
| Status: | assigned → closed |
On latest master I do not see the problem, even with Xvideo output.
comment:12 Changed 6 years ago by
| Owner: | changed from Peter Bennett to Peter Bennett |
|---|
Note: See
TracTickets for help on using
tickets.
Plots distribution of video & audio packets