Ticket #8557: audio.txt

File audio.txt, 46.7 KB (added by danielk, 14 years ago)
Line 
12010-06-13 00:24:51.710 mythfrontend version: trunk [25100] www.mythtv.org
22010-06-13 00:24:51.711 Using runtime prefix = /usr/local
32010-06-13 00:24:51.711 Using configuration directory = /home/danielk/.mythtv
42010-06-13 00:24:52.719 Empty LocalHostName.
52010-06-13 00:24:52.719 Using localhost value of t61
62010-06-13 00:24:52.719 Testing network connectivity to '192.168.1.160'
72010-06-13 00:24:52.728 New DB connection, total: 1
82010-06-13 00:24:52.735 Connected to database 'mythconverg' at host: 192.168.1.160
92010-06-13 00:24:52.736 Closing DB connection named 'DBManager0'
102010-06-13 00:24:52.736 Current locale en_US
112010-06-13 00:24:52.736 No locale defaults file for en_US, skipping
122010-06-13 00:24:52.742 DPMS is disabled.
132010-06-13 00:24:52.749 Connected to database 'mythconverg' at host: 192.168.1.160
142010-06-13 00:24:52.786 Desktop video mode: 1680x1050 60.1106 Hz
152010-06-13 00:24:52.882 max_width: 1680 max_height: 1050
162010-06-13 00:24:52.907 user: 502 effective user: 502 before privileged thread
172010-06-13 00:24:52.908 user: 502 effective user: 502 after privileged thread
182010-06-13 00:24:52.908 user: 502 effective user: 502 run_priv_thread
192010-06-13 00:24:52.908 Enabled verbose msgs:  important general playback audio
202010-06-13 00:24:52.997 LIRC, Error: Failed to connect to Unix socket '/dev/lircd'
21                        eno: No such file or directory (2)
222010-06-13 00:24:52.997 JoystickMenuThread: Joystick disabled - Failed to read /home/danielk/.mythtv/joystickmenurc
232010-06-13 00:24:53.346 Using the OpenGL painter
242010-06-13 00:24:53.605 OpenGL: OpenGL vendor  : NVIDIA Corporation
252010-06-13 00:24:53.605 OpenGL: OpenGL renderer: Quadro NVS 140M/PCI/SSE2
262010-06-13 00:24:53.605 OpenGL: OpenGL version : 3.2.0 NVIDIA 195.36.15
272010-06-13 00:24:53.605 OpenGL: Max texture size: 8192 x 8192
282010-06-13 00:24:53.605 OpenGL: Max texture units: 4
292010-06-13 00:24:53.605 OpenGL: Direct rendering: Yes
302010-06-13 00:24:53.605 Initialised MythRenderOpenGL
312010-06-13 00:24:53.796 Current MythTV Schema Version (DBSchemaVer): 1259
322010-06-13 00:24:53.797 VDP: decoder<->render support: ffmpeg      null directfb xlib xshm xv-blit opengl vdpau
332010-06-13 00:24:53.797 VDP: decoder<->render support: libmpeg2    null directfb xlib xshm xv-blit opengl vdpau
342010-06-13 00:24:53.797 VDP: decoder<->render support: xvmc        xvmc-blit
352010-06-13 00:24:53.797 VDP: decoder<->render support: xvmc-vld    xvmc-blit
362010-06-13 00:24:53.797 VDP: decoder<->render support: vdpau       vdpau
372010-06-13 00:24:53.825 VDP: Ignoring profile item 102 (OSD Renderer opengl is not supported w/renderer xvmc-blit (supported: chromakey,ia44blend))
382010-06-13 00:24:53.834 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
392010-06-13 00:24:53.842 VDP: Ignoring profile item 106 (decoder ivtv is not supported (supported: ffmpeg,libmpeg2,xvmc,xvmc-vld,vdpau))
402010-06-13 00:24:53.850 VDP: Ignoring profile item 113 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
412010-06-13 00:24:53.850 VDP: Ignoring profile item 114 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
422010-06-13 00:24:53.860 VDP: Ignoring profile item 117 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
432010-06-13 00:24:53.860 VDP: Ignoring profile item 118 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
442010-06-13 00:24:53.870 VDP: Ignoring profile item 121 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
452010-06-13 00:24:53.870 VDP: Ignoring profile item 122 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
462010-06-13 00:24:57.190 ALSA: Found ALSA device: null, (Discard all samples (playback) or generate zero samples (capture))
472010-06-13 00:24:57.190 ALSA: Found ALSA device: default:CARD=Intel, (HDA Intel, AD198x Analog
48Default Audio Device)
492010-06-13 00:24:57.190 ALSA: Found ALSA device: front:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
50Front speakers)
512010-06-13 00:24:57.190 ALSA: Found ALSA device: surround40:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
524.0 Surround output to Front and Rear speakers)
532010-06-13 00:24:57.190 ALSA: Found ALSA device: surround41:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
544.1 Surround output to Front, Rear and Subwoofer speakers)
552010-06-13 00:24:57.190 ALSA: Found ALSA device: surround50:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
565.0 Surround output to Front, Center and Rear speakers)
572010-06-13 00:24:57.190 ALSA: Found ALSA device: surround51:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
585.1 Surround output to Front, Center, Rear and Subwoofer speakers)
592010-06-13 00:24:57.190 ALSA: Found ALSA device: surround71:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
607.1 Surround output to Front, Center, Side, Rear and Woofer speakers)
612010-06-13 00:24:57.190 ALSA: Found ALSA device: iec958:CARD=Intel,DEV=0, (HDA Intel, AD198x Digital
62IEC958 (S/PDIF) Digital Audio Output)
632010-06-13 00:24:58.245 Registering Internal as a media playback plugin.
642010-06-13 00:24:58.250 Plugin mytharchive (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
652010-06-13 00:24:58.251 Test Popup Version Failed
662010-06-13 00:24:58.251 Unable to initialize plugin 'mytharchive'.
672010-06-13 00:24:58.252 Plugin mythbrowser (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
682010-06-13 00:24:58.252 Unable to initialize plugin 'mythbrowser'.
692010-06-13 00:24:58.254 Plugin mythgallery (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
702010-06-13 00:24:58.254 Unable to initialize plugin 'mythgallery'.
712010-06-13 00:24:58.256 Plugin mythgame (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
722010-06-13 00:24:58.256 libmythgame.so/main.o: binary version mismatch
732010-06-13 00:24:58.256 Unable to initialize plugin 'mythgame'.
742010-06-13 00:24:58.257 Plugin mythmovies (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
752010-06-13 00:24:58.257 libmythmovies.so/main.o: binary version mismatch
762010-06-13 00:24:58.257 Unable to initialize plugin 'mythmovies'.
772010-06-13 00:24:58.268 Plugin mythmusic (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
782010-06-13 00:24:58.268 Unable to initialize plugin 'mythmusic'.
792010-06-13 00:24:58.269 Plugin mythnetvision (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
802010-06-13 00:24:58.269 Unable to initialize plugin 'mythnetvision'.
812010-06-13 00:24:58.271 Plugin mythnews (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
822010-06-13 00:24:58.271 Unable to initialize plugin 'mythnews'.
832010-06-13 00:24:58.273 Plugin mythvideo (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
842010-06-13 00:24:58.273 Unable to initialize plugin 'mythvideo'.
852010-06-13 00:24:58.275 Plugin mythweather (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
862010-06-13 00:24:58.275 Unable to initialize plugin 'mythweather'.
872010-06-13 00:24:58.276 Plugin mythzoneminder (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
882010-06-13 00:24:58.276 Unable to initialize plugin 'mythzoneminder'.
892010-06-13 00:24:58.298 MMUnix::AddDevice() Error: failed to stat /dev/bdi,
90                        eno: No such file or directory (2)
912010-06-13 00:24:58.302 MMUnix::AddDevice() Error: failed to stat /dev/power,
92                        eno: No such file or directory (2)
932010-06-13 00:24:58.305 MMUnix::AddDevice() Error: failed to stat /dev/trace,
94                        eno: No such file or directory (2)
952010-06-13 00:24:58.403 Loading menu theme from /usr/local/share/mythtv/themes/defaultmenu//mainmenu.xml
962010-06-13 00:24:58.408 Found mainmenu.xml for theme 'MythCenter-wide'
972010-06-13 00:24:58.477 MythCoreContext: Connecting to backend server: 192.168.1.160:6543 (try 1 of 1)
982010-06-13 00:24:58.486 Using protocol version 57
992010-06-13 00:24:58.525 SendReceiveStringList(QUERY_TIME_ZONE) called from UI thread
1002010-06-13 00:24:59.207 Loading menu theme from /usr/local/share/mythtv/themes/defaultmenu//library.xml
1012010-06-13 00:24:59.690 SendReceiveStringList(QUERY_HOSTNAME) called from UI thread
1022010-06-13 00:24:59.703 New DB connection, total: 2
1032010-06-13 00:24:59.708 Connected to database 'mythconverg' at host: 192.168.1.160
1042010-06-13 00:25:01.243 TV: StartTV() -- begin
1052010-06-13 00:25:01.243 TV: ctor -- begin
1062010-06-13 00:25:01.267 TV: ctor -- end
1072010-06-13 00:25:01.267 TV: Init -- begin
1082010-06-13 00:25:01.270 TV: Init -- end channel groups
1092010-06-13 00:25:01.297 TV: DrawUnusedRects() -- begin
1102010-06-13 00:25:01.297 TV: DrawUnusedRects() -- end
1112010-06-13 00:25:01.297 TV: Init -- end
1122010-06-13 00:25:01.297 SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STARTED HOSTNAME t61 CHANID 4019 STARTTIME 2010-06-11T19:00:00 SENDER t61) called from UI thread
1132010-06-13 00:25:01.306 TV: tv->Playback() -- begin
1142010-06-13 00:25:01.338 TV: tv->Playback() -- end
1152010-06-13 00:25:01.338 TV: StartTV -- process events begin
1162010-06-13 00:25:01.357 TV: HandleStateChange(0) -- begin
1172010-06-13 00:25:01.357 TV: Attempting to change from None to WatchingPreRecorded
1182010-06-13 00:25:01.378 SendReceiveStringList(QUERY_CHECKFILE,0...) called from UI thread
1192010-06-13 00:25:01.409 RingBuf(myth://192.168.1.160:6543/4019_20100611190000.mpg): OpenFile(myth://192.168.1.160:6543/4019_20100611190000.mpg, 12)
1202010-06-13 00:25:01.472 RingBuf(myth://192.168.1.160:6543/4019_20100611190000.mpg): CalcReadAheadThresh(0 KB)
121                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
1222010-06-13 00:25:01.534 TV: StartPlayer(0, WatchingPreRecorded, main) -- begin
1232010-06-13 00:25:01.534 TV: Elapsed time since TV constructor was called: 291 ms
1242010-06-13 00:25:01.624 AudioPlayer: Disabling Audio, params(0,2,44100)
1252010-06-13 00:25:01.636 AO: Killing AudioOutputDSP
1262010-06-13 00:25:01.636 ALSA: GetOutputSettings() opening default
1272010-06-13 00:25:01.672 AO: Sample rate 48000 is supported
1282010-06-13 00:25:01.672 AO: Original codec was NONE, unknown, 44 kHz, 2 channels
1292010-06-13 00:25:01.672 AO: Resampling from 44 kHz to 48 kHz with quality medium
1302010-06-13 00:25:01.672 AO: Audio processing enabled
1312010-06-13 00:25:01.672 AO: Opening audio device 'default' ch 2(2) sr 48000 sf signed 32 bit reenc 0
1322010-06-13 00:25:01.672 ALSA: Setting IEC958 status: audio
1332010-06-13 00:25:01.711 Opening ALSA audio device 'default'.
1342010-06-13 00:25:01.751 ALSA: SetParameters(format=10, channels=2, rate=48000, buffer_time=100000, period_time=50000)
1352010-06-13 00:25:01.751 ALSA: Buffer time = 106666 us
1362010-06-13 00:25:01.751 ALSA: Period time = 21333 us
1372010-06-13 00:25:01.752 ALSA: Buffer size = 5120 | Period size = 1024
1382010-06-13 00:25:01.756 ALSA: found playback control PCM on mixer device default
1392010-06-13 00:25:01.756 ALSA: mixer volume range on mixer device default/PCM - min 0, max 39, range 39
1402010-06-13 00:25:01.756 ALSA: mixer device default/PCM set up successfully
1412010-06-13 00:25:01.759 ALSA: channel 0 volume set 100 => 39
1422010-06-13 00:25:01.760 ALSA: channel 1 volume set 100 => 39
1432010-06-13 00:25:01.760 ALSA: get volume channel 0: 100
1442010-06-13 00:25:01.760 ALSA: channel 0 volume set 100 => 39
1452010-06-13 00:25:01.760 ALSA: channel 1 volume set 100 => 39
1462010-06-13 00:25:01.760 AO: Audio fragment size: 4096
1472010-06-13 00:25:01.760 AO: Audio Stretch Factor: 1
1482010-06-13 00:25:01.760 AO: Ending Reconfigure()
1492010-06-13 00:25:01.760 AudioPlayer: Enabling Audio
1502010-06-13 00:25:01.760 AO: kickoffOutputAudioLoop: pid = 29730
1512010-06-13 00:25:01.760 AO: Reconfigure(): No change -> exiting
1522010-06-13 00:25:01.760 AO: OutputAudioLoop: Play Event
1532010-06-13 00:25:02.718 AFD: Stream #0, has id 0x1011 codec id H264, type Video, bitrate 0 at 0x7fcb781f2210
1542010-06-13 00:25:02.732 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
1552010-06-13 00:25:02.733 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
1562010-06-13 00:25:02.733 VDP: LoadBestPreferences(2048x2048, 0)
1572010-06-13 00:25:02.733 VDP: LoadBestPreferences(2048x2048, 60)
1582010-06-13 00:25:02.733 VDP: LoadBestPreferences(1920x1080, 60)
1592010-06-13 00:25:02.768 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
1602010-06-13 00:25:02.768 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
1612010-06-13 00:25:02.768 VDP: LoadBestPreferences(2048x2048, 0)
1622010-06-13 00:25:02.768 VDP: LoadBestPreferences(2048x2048, 60)
1632010-06-13 00:25:02.768 VDP: LoadBestPreferences(1920x1080, 60)
1642010-06-13 00:25:02.768 AFD: Using 1 CPUs for decoding
1652010-06-13 00:25:02.768 AFD: InitVideoCodec() 0x7fcb781f2430 id(H264) type (Video).
1662010-06-13 00:25:02.768 NVP(0): detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan
1672010-06-13 00:25:02.768 AFD: Using ffmpeg for video decoding
1682010-06-13 00:25:02.768 AFD: Looking for decoder for H264
1692010-06-13 00:25:02.769 AFD: Opened codec 0x7fcb781f2430, id(H264) type(Video)
1702010-06-13 00:25:02.769 AFD: Stream #1, has id 0x1100 codec id AAC, type Audio, bitrate 142125 at 0x7fcb781f2a30
1712010-06-13 00:25:02.769 AFD: codec AAC has 2 channels
1722010-06-13 00:25:02.769 AFD: Looking for decoder for AAC
1732010-06-13 00:25:02.770 AFD: Opened codec 0x7fcb78455240, id(AAC) type(Audio)
1742010-06-13 00:25:02.770 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the Undetermined language(7695972).
1752010-06-13 00:25:02.785 RingBuf(myth://192.168.1.160:6543/4019_20100611190000.mpg): CalcReadAheadThresh(0 KB)
176                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
1772010-06-13 00:25:02.785 AFD: Selected track 1: Undetermined AAC 2ch (A/V Stream #1)
1782010-06-13 00:25:02.785 AFD: Initializing audio parms from audio track #1
1792010-06-13 00:25:02.785 AFD: Audio format changed
180                        from id(NONE)     -1Hz -1ch -1bps     to id( AAC)  48000Hz  2ch 16bps   
1812010-06-13 00:25:02.785 AO: SetEffDsp: 4800000
1822010-06-13 00:25:02.785 AO: Killing AudioOutputDSP
1832010-06-13 00:25:02.791 AO: OutputAudioLoop: Stop Event
1842010-06-13 00:25:02.791 AO: kickoffOutputAudioLoop exiting
1852010-06-13 00:25:02.792 AO: Original codec was AAC, signed 16 bit, 48 kHz, 2 channels
1862010-06-13 00:25:02.792 AO: Audio processing enabled
1872010-06-13 00:25:02.792 AO: Opening audio device 'default' ch 2(2) sr 48000 sf signed 32 bit reenc 0
1882010-06-13 00:25:02.792 ALSA: Setting IEC958 status: audio
1892010-06-13 00:25:02.832 Opening ALSA audio device 'default'.
1902010-06-13 00:25:02.871 ALSA: SetParameters(format=10, channels=2, rate=48000, buffer_time=100000, period_time=50000)
1912010-06-13 00:25:02.871 ALSA: Buffer time = 106666 us
1922010-06-13 00:25:02.871 ALSA: Period time = 21333 us
1932010-06-13 00:25:02.872 ALSA: Buffer size = 5120 | Period size = 1024
1942010-06-13 00:25:02.872 ALSA: found playback control PCM on mixer device default
1952010-06-13 00:25:02.872 ALSA: mixer volume range on mixer device default/PCM - min 0, max 39, range 39
1962010-06-13 00:25:02.872 ALSA: mixer device default/PCM set up successfully
1972010-06-13 00:25:02.872 ALSA: channel 0 volume set 100 => 39
1982010-06-13 00:25:02.872 ALSA: channel 1 volume set 100 => 39
1992010-06-13 00:25:02.872 ALSA: get volume channel 0: 100
2002010-06-13 00:25:02.872 ALSA: channel 0 volume set 100 => 39
2012010-06-13 00:25:02.872 ALSA: channel 1 volume set 100 => 39
2022010-06-13 00:25:02.872 AO: Audio fragment size: 4096
2032010-06-13 00:25:02.872 AO: Audio Stretch Factor: 1
2042010-06-13 00:25:02.872 AO: Ending Reconfigure()
2052010-06-13 00:25:02.872 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2062010-06-13 00:25:02.872 AO: kickoffOutputAudioLoop: pid = 29730
2072010-06-13 00:25:02.873 AO: OutputAudioLoop: Play Event
2082010-06-13 00:25:02.905 Position map filled from DB to: 56317
2092010-06-13 00:25:02.905 Dec: SyncPositionMap prerecorded, from DB: 441 entries
2102010-06-13 00:25:02.905 Dec: SyncPositionMap, new totframes: 56317, new length: 1879, posMap size: 441
2112010-06-13 00:25:02.905 AFD: Position map found
2122010-06-13 00:25:02.905 AFD: Successfully opened decoder for file: "myth://192.168.1.160:6543/4019_20100611190000.mpg". novideo(0)
2132010-06-13 00:25:02.948 VideoOutput: Allowed renderers: directfb,xv-blit,xshm,xlib,opengl,vdpau
2142010-06-13 00:25:02.948 VideoOutput: Allowed renderers (filt: ffmpeg): directfb,xlib,xshm,xv-blit,opengl,vdpau
2152010-06-13 00:25:02.998 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2162010-06-13 00:25:02.998 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2172010-06-13 00:25:02.998 VDP: LoadBestPreferences(2048x2048, 0)
2182010-06-13 00:25:02.998 VDP: LoadBestPreferences(2048x2048, 60)
2192010-06-13 00:25:02.998 VDP: LoadBestPreferences(1920x1080, 60)
2202010-06-13 00:25:02.998 VideoOutput: Preferred renderer: xv-blit
2212010-06-13 00:25:02.998 VideoOutput: Trying video renderer: 'xv-blit'
2222010-06-13 00:25:03.375 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2232010-06-13 00:25:03.375 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2242010-06-13 00:25:03.375 VDP: LoadBestPreferences(2048x2048, 0)
2252010-06-13 00:25:03.375 VDP: LoadBestPreferences(2048x2048, 60)
2262010-06-13 00:25:03.504 VideoOutputXv: ctor
2272010-06-13 00:25:03.504 VideoOutWindow::SetPIPState. pip_state: 0]
2282010-06-13 00:25:03.504 VideoOutputXv: Creating gc
2292010-06-13 00:25:03.504 VideoOutputXv: XJ_screen_num: '0'
2302010-06-13 00:25:03.504 VideoOutputXv: XJ_curwin:     '67108870'
2312010-06-13 00:25:03.504 VideoOutputXv: XJ_win:        '67108870'
2322010-06-13 00:25:03.504 VideoOutputXv: XJ_root:       '291'
2332010-06-13 00:25:03.504 VideoOutputXv: XJ_gc:         '0x7fcb784147f0'
2342010-06-13 00:25:03.505 Display Rect  left: 0, top: 61, width: 720, height: 364, aspect: 1.33333
2352010-06-13 00:25:03.505 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2362010-06-13 00:25:03.505 VDP: LoadBestPreferences(1920x1088, 60)
2372010-06-13 00:25:03.505 Display Rect  left: 0, top: 61, width: 720, height: 364, aspect: 1.33333
2382010-06-13 00:25:03.505 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2392010-06-13 00:25:03.505 VideoOutput: Pixel dimensions: Screen 1680x1050, window 720x486
2402010-06-13 00:25:03.505 VideoOutput: Actual display dimensions: 331x210 mm  Aspect: 1.57619
2412010-06-13 00:25:03.505 VideoOutput: Estimated window dimensions: 141x97 mm  Aspect: 1.45361
2422010-06-13 00:25:03.505 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2432010-06-13 00:25:03.569 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2442010-06-13 00:25:03.569 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2452010-06-13 00:25:03.569 VDP: LoadBestPreferences(2048x2048, 0)
2462010-06-13 00:25:03.569 VDP: LoadBestPreferences(2048x2048, 60)
2472010-06-13 00:25:03.569 VDP: LoadBestPreferences(1920x1088, 60)
2482010-06-13 00:25:03.569 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2492010-06-13 00:25:03.569 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2502010-06-13 00:25:03.569 VideoOutputXv: Has XVideo flags...
2512010-06-13 00:25:03.570 VideoOutputXv: Has XV_BRIGHTNESS...
2522010-06-13 00:25:03.570 VideoOutputXv: Here...
2532010-06-13 00:25:03.570 VideoOutputXv: Grabbed xv port 254
2542010-06-13 00:25:03.570 VideoOutputXv: XVideo surface found on port 254
2552010-06-13 00:25:03.570 VideoOutputXv: XV_SET_DEFAULTS is supported on this port
2562010-06-13 00:25:03.570 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2572010-06-13 00:25:03.570 VideoOutputXv: XVideo Format #0 is 'YUY2'
2582010-06-13 00:25:03.570 VideoOutputXv: XVideo Format #1 is 'YV12'
2592010-06-13 00:25:03.570 VideoOutputXv: XVideo Format #2 is 'UYVY'
2602010-06-13 00:25:03.570 VideoOutputXv: XVideo Format #3 is 'I420'
2612010-06-13 00:25:03.570 VideoOutputXv: Using XVideo Format 'YV12'
2622010-06-13 00:25:03.570 VideoOutputXv: CreateShmImages(32): video_dim: 1920x1088
2632010-06-13 00:25:03.708 VDP: SetVideoRenderer(xv-blit)
2642010-06-13 00:25:03.708 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2652010-06-13 00:25:03.708 VideoOutputXv: Chromakeying not possible with this XVideo port.
2662010-06-13 00:25:03.709 Display Rect  left: 0, top: 44, width: 720, height: 397, aspect: 1.45361
2672010-06-13 00:25:03.709 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2682010-06-13 00:25:03.724 Over/underscan. V: 0, H: 0
2692010-06-13 00:25:03.724 Display Rect  left: 0, top: 44, width: 720, height: 397, aspect: 1.45361
2702010-06-13 00:25:03.724 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2712010-06-13 00:25:03.724 VDP: LoadBestPreferences(1920x1088, 29.97)
2722010-06-13 00:25:03.724 NVP(0): LoadFilters(''..) -> 0x0
2732010-06-13 00:25:03.731 OSD: Base theme size: 1280x720
2742010-06-13 00:25:03.731 OSD: Scaling factors: 1.5x1.5
2752010-06-13 00:25:03.731 NVP(0): Decoder thread starting.
2762010-06-13 00:25:03.741 OSD: Loaded window osd_message
2772010-06-13 00:25:03.744 OSD: Loaded window osd_input
2782010-06-13 00:25:03.759 OSD: Loaded window program_info
2792010-06-13 00:25:03.763 OSD: Loaded window browse_info
2802010-06-13 00:25:03.768 OSD: Loaded window osd_status
2812010-06-13 00:25:03.772 OSD: Loaded window osd_program_editor
2822010-06-13 00:25:03.772 OSD: Loaded OSD: size 1920x1080 offset 0+0
2832010-06-13 00:25:03.772 OSD: Base theme size: 1280x720
2842010-06-13 00:25:03.772 OSD: Scaling factors: 1.5x1.5
2852010-06-13 00:25:03.803 OSD: Base theme size: 1280x720
2862010-06-13 00:25:03.803 OSD: Scaling factors: 1.5x1.5
2872010-06-13 00:25:03.830 NVP(0): ClearAfterSeek(1)
2882010-06-13 00:25:03.830 VideoOutputXv: ClearAfterSeek()
2892010-06-13 00:25:03.830 VideoOutputXv: DiscardFrames(0)
2902010-06-13 00:25:03.830 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAuULL
2912010-06-13 00:25:03.830 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAaALL -- done
2922010-06-13 00:25:03.830 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAaALL -- done()
2932010-06-13 00:25:03.831 VDP: GetFilteredDeint() : xv-blit -> 'none'
2942010-06-13 00:25:03.832 Couldn't load deinterlace filter none
2952010-06-13 00:25:03.832 Using deinterlace method
2962010-06-13 00:25:03.834 AO: OutputAudioLoop: Play Event
2972010-06-13 00:25:03.875 VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2982010-06-13 00:25:03.875 VSYNC: RTCVideoSync: Could not open /dev/rtc, Permission denied.
2992010-06-13 00:25:03.875 NVP(0): Video timing method: USleep with busy wait
3002010-06-13 00:25:03.875 NVP(0): Refresh rate: 16636, frame interval: 33366
3012010-06-13 00:25:03.875 playCtx: StartPlaying(): took 0 ms to start player.
3022010-06-13 00:25:03.875 TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok
3032010-06-13 00:25:03.876 SendReceiveStringList(MESSAGE,COMMFLAG_REQUEST 4019_2010-06-11T19:00:00) called from UI thread
3042010-06-13 00:25:03.888 TV: Changing from None to WatchingPreRecorded
3052010-06-13 00:25:03.893 TV: HandleStateChange(0) -- end
3062010-06-13 00:25:03.925 NVP(0): Waiting for video buffers...
3072010-06-13 00:25:03.925 AO: Pause 1
3082010-06-13 00:25:03.925 AO: OutputAudioLoop: audio paused
3092010-06-13 00:25:04.025 NVP(0): Waited 100ms for video buffers ULUUULAAAAAAAAAAAAAAAAAAAAAAAuU
3102010-06-13 00:25:04.034 NVP(0): Waited 100ms for video buffers UuUUULLAAAAAAAAAAAAAAAAAAAAAAUU
3112010-06-13 00:25:04.162 NVP(0): Waited 100ms for video buffers UUUUUuUUULLAAAAAAAAAAAAAAAAAAUU
3122010-06-13 00:25:04.187 AO: Pause 0
3132010-06-13 00:25:04.187 VDP: GetFilteredDeint() : xv-blit -> 'none'
3142010-06-13 00:25:04.188 Couldn't load deinterlace filter none
3152010-06-13 00:25:04.189 Using deinterlace method
3162010-06-13 00:25:04.189 NVP(0): Failed to enable deinterlacing
3172010-06-13 00:25:04.193 AO: OutputAudioLoop: Play Event
3182010-06-13 00:25:04.276 VideoOutput: Created YV12 OSD.
3192010-06-13 00:25:06.304 TV: DoNVPSeek() -- begin
3202010-06-13 00:25:06.304 AO: Pause 1
3212010-06-13 00:25:06.304 TV: DoNVPSeek() -- ff
3222010-06-13 00:25:06.304 TV: DoNVPSeek() -- end
3232010-06-13 00:25:06.304 UpdateOSDSeekMessage(Skip Ahead, 1)
3242010-06-13 00:25:06.305 AO: OutputAudioLoop: audio paused
3252010-06-13 00:25:06.347 AFD: DoFastForward(965 (85), do discard frames)
3262010-06-13 00:25:06.347 Dec: DoFastForward(965 (85), do discard frames)
3272010-06-13 00:25:06.347 Dec: FindPosition(965, search not adjusted) -->
328                        [7:893(31791928),8:1021(36834464)]
3292010-06-13 00:25:06.350 AFD: SeekReset(1021, 0, do flush, do discard)
3302010-06-13 00:25:06.350 AFD: SeekReset() flushing
3312010-06-13 00:25:06.350 VideoOutputXv: DiscardFrames(1)
3322010-06-13 00:25:06.350 VideoBuffers::DiscardFrames(1): AUAUUUUUUUUUUUUUUAUAUAAAAAAAAUA
3332010-06-13 00:25:06.350 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3342010-06-13 00:25:06.350 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
3352010-06-13 00:25:06.350 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3362010-06-13 00:25:06.351 NVP(0): ClearAfterSeek(0)
3372010-06-13 00:25:06.351 NVP(0): Waiting for video buffers...
3382010-06-13 00:25:06.454 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAAAALAAAAAAAA
3392010-06-13 00:25:06.459 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAAAALAAAAAAAA
3402010-06-13 00:25:06.549 AO: Pause 0
3412010-06-13 00:25:06.561 AO: OutputAudioLoop: Play Event
3422010-06-13 00:25:06.587 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUAULAUALAAAA
3432010-06-13 00:25:06.596 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUAULAUALAAAA
3442010-06-13 00:25:06.734 NVP(0): Waited 100ms for video buffers LAAAAAAAAAAAAAAAAAAUAUuUUULAUAA
3452010-06-13 00:25:06.838 NVP(0): Waited 100ms for video buffers LALAAAAAAAAAAAAAAAAUAUUUUUuUUAA
3462010-06-13 00:25:06.843 NVP(0): Waited 100ms for video buffers LALAAAAAAAAAAAAAAAAUAUUUUUuUUAA
3472010-06-13 00:25:06.934 AO: Pause 0
3482010-06-13 00:25:06.976 NVP(0): Video is 3.54663 frames behind audio (too slow), dropping frame to catch up.
3492010-06-13 00:25:06.976 NVP(0): Waiting for video buffers...
3502010-06-13 00:25:06.976 AO: Pause 1
3512010-06-13 00:25:06.988 AO: OutputAudioLoop: audio paused
3522010-06-13 00:25:07.001 AO: Pause 0
3532010-06-13 00:25:07.002 NVP(0): Video is 5.31985 frames behind audio (too slow), dropping frame to catch up.
3542010-06-13 00:25:07.002 NVP(0): Waiting for video buffers...
3552010-06-13 00:25:07.002 AO: Pause 1
3562010-06-13 00:25:07.009 AO: OutputAudioLoop: audio paused
3572010-06-13 00:25:07.031 AO: Pause 0
3582010-06-13 00:25:07.032 NVP(0): Video is 6.43248 frames behind audio (too slow), dropping frame to catch up.
3592010-06-13 00:25:07.032 NVP(0): Waiting for video buffers...
3602010-06-13 00:25:07.032 AO: Pause 1
3612010-06-13 00:25:07.052 AO: OutputAudioLoop: audio paused
3622010-06-13 00:25:07.099 AO: Pause 0
3632010-06-13 00:25:07.100 NVP(0): Video is 6.92229 frames behind audio (too slow), dropping frame to catch up.
3642010-06-13 00:25:07.100 NVP(0): Waiting for video buffers...
3652010-06-13 00:25:07.100 AO: Pause 1
3662010-06-13 00:25:07.116 AO: OutputAudioLoop: audio paused
3672010-06-13 00:25:07.129 AO: Pause 0
3682010-06-13 00:25:07.130 NVP(0): Video is 7.07235 frames behind audio (too slow), dropping frame to catch up.
3692010-06-13 00:25:07.130 NVP(0): Waiting for video buffers...
3702010-06-13 00:25:07.130 AO: Pause 1
3712010-06-13 00:25:07.137 AO: OutputAudioLoop: audio paused
3722010-06-13 00:25:07.159 AO: Pause 0
3732010-06-13 00:25:07.160 NVP(0): Video is 6.99011 frames behind audio (too slow), dropping frame to catch up.
3742010-06-13 00:25:07.160 NVP(0): Waiting for video buffers...
3752010-06-13 00:25:07.160 AO: Pause 1
3762010-06-13 00:25:07.180 AO: OutputAudioLoop: audio paused
3772010-06-13 00:25:07.194 AO: Pause 0
3782010-06-13 00:25:07.195 NVP(0): Video is 6.59126 frames behind audio (too slow), dropping frame to catch up.
3792010-06-13 00:25:07.195 NVP(0): Waiting for video buffers...
3802010-06-13 00:25:07.195 AO: Pause 1
3812010-06-13 00:25:07.201 AO: OutputAudioLoop: audio paused
3822010-06-13 00:25:07.233 AO: Pause 0
3832010-06-13 00:25:07.234 NVP(0): Video is 6.14227 frames behind audio (too slow), dropping frame to catch up.
3842010-06-13 00:25:07.244 AO: OutputAudioLoop: Play Event
3852010-06-13 00:25:07.254 NVP(0): Waiting for video buffers...
3862010-06-13 00:25:07.254 AO: Pause 1
3872010-06-13 00:25:07.265 AO: OutputAudioLoop: audio paused
3882010-06-13 00:25:07.292 AO: Pause 0
3892010-06-13 00:25:07.293 NVP(0): Video is 5.52829 frames behind audio (too slow), dropping frame to catch up.
3902010-06-13 00:25:07.293 NVP(0): Waiting for video buffers...
3912010-06-13 00:25:07.293 AO: Pause 1
3922010-06-13 00:25:07.308 AO: OutputAudioLoop: audio paused
3932010-06-13 00:25:07.322 AO: Pause 0
3942010-06-13 00:25:07.323 NVP(0): Video is 4.94042 frames behind audio (too slow), dropping frame to catch up.
3952010-06-13 00:25:07.323 NVP(0): Waiting for video buffers...
3962010-06-13 00:25:07.323 AO: Pause 1
3972010-06-13 00:25:07.329 AO: OutputAudioLoop: audio paused
3982010-06-13 00:25:07.352 AO: Pause 0
3992010-06-13 00:25:07.353 NVP(0): Video is 4.3122 frames behind audio (too slow), dropping frame to catch up.
4002010-06-13 00:25:07.354 NVP(0): Waiting for video buffers...
4012010-06-13 00:25:07.354 AO: Pause 1
4022010-06-13 00:25:07.372 AO: OutputAudioLoop: audio paused
4032010-06-13 00:25:07.420 AO: Pause 0
4042010-06-13 00:25:07.421 NVP(0): Video is 3.50387 frames behind audio (too slow), dropping frame to catch up.
4052010-06-13 00:25:07.421 NVP(0): Waiting for video buffers...
4062010-06-13 00:25:07.421 AO: Pause 1
4072010-06-13 00:25:07.436 AO: OutputAudioLoop: audio paused
4082010-06-13 00:25:07.450 AO: Pause 0
4092010-06-13 00:25:07.454 NVP(0): Waiting for video buffers...
4102010-06-13 00:25:07.454 AO: Pause 1
4112010-06-13 00:25:07.457 AO: OutputAudioLoop: audio paused
4122010-06-13 00:25:07.487 AO: Pause 0
4132010-06-13 00:25:07.491 NVP(0): Waiting for video buffers...
4142010-06-13 00:25:07.491 AO: Pause 1
4152010-06-13 00:25:07.500 AO: OutputAudioLoop: audio paused
4162010-06-13 00:25:07.520 AO: Pause 0
4172010-06-13 00:25:07.521 AO: OutputAudioLoop: Play Event
4182010-06-13 00:25:07.524 NVP(0): Waiting for video buffers...
4192010-06-13 00:25:07.524 AO: Pause 1
4202010-06-13 00:25:07.542 AO: OutputAudioLoop: audio paused
4212010-06-13 00:25:07.591 AO: Pause 0
4222010-06-13 00:25:07.595 NVP(0): Waiting for video buffers...
4232010-06-13 00:25:07.595 AO: Pause 1
4242010-06-13 00:25:07.606 AO: OutputAudioLoop: audio paused
4252010-06-13 00:25:07.624 AO: Pause 0
4262010-06-13 00:25:07.627 NVP(0): Waiting for video buffers...
4272010-06-13 00:25:07.627 AO: Pause 1
4282010-06-13 00:25:07.628 AO: OutputAudioLoop: audio paused
4292010-06-13 00:25:07.656 AO: Pause 0
4302010-06-13 00:25:07.660 NVP(0): Waiting for video buffers...
4312010-06-13 00:25:07.660 AO: Pause 1
4322010-06-13 00:25:07.670 AO: OutputAudioLoop: audio paused
4332010-06-13 00:25:07.693 AO: Pause 0
4342010-06-13 00:25:07.696 NVP(0): Waiting for video buffers...
4352010-06-13 00:25:07.697 AO: Pause 1
4362010-06-13 00:25:07.713 AO: OutputAudioLoop: audio paused
4372010-06-13 00:25:07.730 AO: Pause 0
4382010-06-13 00:25:07.733 NVP(0): Video is 3.31184 frames ahead of audio,
439                        doubling video frame interval to slow down.
4402010-06-13 00:25:07.733 NVP(0): Waiting for video buffers...
4412010-06-13 00:25:07.733 AO: Pause 1
4422010-06-13 00:25:07.734 AO: OutputAudioLoop: audio paused
4432010-06-13 00:25:07.767 AO: Pause 0
4442010-06-13 00:25:07.777 AO: OutputAudioLoop: Play Event
4452010-06-13 00:25:07.786 NVP(0): Video is 4.17722 frames ahead of audio,
446                        doubling video frame interval to slow down.
4472010-06-13 00:25:07.786 NVP(0): Waiting for video buffers...
4482010-06-13 00:25:07.786 AO: Pause 1
4492010-06-13 00:25:07.799 AO: OutputAudioLoop: audio paused
4502010-06-13 00:25:07.811 AO: Pause 0
4512010-06-13 00:25:07.814 NVP(0): Video is 4.99856 frames ahead of audio,
452                        doubling video frame interval to slow down.
4532010-06-13 00:25:07.820 AO: OutputAudioLoop: Play Event
4542010-06-13 00:25:07.860 NVP(0): Video is 5.81688 frames ahead of audio,
455                        doubling video frame interval to slow down.
4562010-06-13 00:25:07.910 NVP(0): Video is 6.33321 frames ahead of audio,
457                        doubling video frame interval to slow down.
4582010-06-13 00:25:07.960 NVP(0): Video is 6.60058 frames ahead of audio,
459                        doubling video frame interval to slow down.
4602010-06-13 00:25:08.012 NVP(0): Video is 6.67374 frames ahead of audio,
461                        doubling video frame interval to slow down.
4622010-06-13 00:25:08.060 NVP(0): Video is 6.57876 frames ahead of audio,
463                        doubling video frame interval to slow down.
4642010-06-13 00:25:08.110 NVP(0): Video is 6.41012 frames ahead of audio,
465                        doubling video frame interval to slow down.
4662010-06-13 00:25:08.160 NVP(0): Video is 6.15627 frames ahead of audio,
467                        doubling video frame interval to slow down.
4682010-06-13 00:25:08.211 NVP(0): Video is 5.84598 frames ahead of audio,
469                        doubling video frame interval to slow down.
4702010-06-13 00:25:08.260 NVP(0): Video is 5.4709 frames ahead of audio,
471                        doubling video frame interval to slow down.
4722010-06-13 00:25:08.310 NVP(0): Video is 5.0847 frames ahead of audio,
473                        doubling video frame interval to slow down.
4742010-06-13 00:25:08.360 NVP(0): Video is 4.66769 frames ahead of audio,
475                        doubling video frame interval to slow down.
4762010-06-13 00:25:08.410 NVP(0): Video is 4.22754 frames ahead of audio,
477                        doubling video frame interval to slow down.
478'video_output' mean = '41669.73', std. dev. = '64520.90', fps = '24.00'
4792010-06-13 00:25:08.461 NVP(0): Video is 3.77007 frames ahead of audio,
480                        doubling video frame interval to slow down.
4812010-06-13 00:25:08.510 NVP(0): Video is 3.29959 frames ahead of audio,
482                        doubling video frame interval to slow down.
4832010-06-13 00:25:09.511 TV: DoNVPSeek() -- begin
4842010-06-13 00:25:09.512 AO: Pause 1
4852010-06-13 00:25:09.512 TV: DoNVPSeek() -- ff
4862010-06-13 00:25:09.512 TV: DoNVPSeek() -- end
4872010-06-13 00:25:09.512 UpdateOSDSeekMessage(Skip Ahead, 1)
4882010-06-13 00:25:09.516 AFD: DoFastForward(1985 (1115), do discard frames)
4892010-06-13 00:25:09.516 Dec: DoFastForward(1985 (1115), do discard frames)
4902010-06-13 00:25:09.516 Dec: FindPosition(1985, search not adjusted) -->
491                        [15:1917(69522588),16:2045(74940936)]
4922010-06-13 00:25:09.519 AFD: SeekReset(2045, 0, do flush, do discard)
4932010-06-13 00:25:09.519 AFD: SeekReset() flushing
4942010-06-13 00:25:09.519 VideoOutputXv: DiscardFrames(1)
4952010-06-13 00:25:09.519 VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUUUUAUUUUUUUAAUUUUUU
4962010-06-13 00:25:09.520 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
4972010-06-13 00:25:09.520 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
4982010-06-13 00:25:09.520 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
4992010-06-13 00:25:09.520 NVP(0): ClearAfterSeek(0)
5002010-06-13 00:25:09.520 NVP(0): Waiting for video buffers...
5012010-06-13 00:25:09.527 AO: OutputAudioLoop: audio paused
5022010-06-13 00:25:09.623 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
5032010-06-13 00:25:09.628 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
5042010-06-13 00:25:09.742 AO: Pause 0
5052010-06-13 00:25:09.761 AO: OutputAudioLoop: Play Event
5062010-06-13 00:25:09.765 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAUAAAAAALLUUAAAAA
5072010-06-13 00:25:09.895 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAUAAAAAALuUULUUAU
5082010-06-13 00:25:10.013 NVP(0): Waited 100ms for video buffers AAUAUALAAAAAAAAUAAAAAAuUUULUUAU
5092010-06-13 00:25:10.066 AO: Pause 0
5102010-06-13 00:25:10.072 NVP(0): Waiting for video buffers...
5112010-06-13 00:25:10.072 AO: Pause 1
5122010-06-13 00:25:10.081 AO: OutputAudioLoop: audio paused
5132010-06-13 00:25:10.102 AO: Pause 0
5142010-06-13 00:25:10.103 AO: OutputAudioLoop: Play Event
5152010-06-13 00:25:10.106 NVP(0): Waiting for video buffers...
5162010-06-13 00:25:10.106 AO: Pause 1
5172010-06-13 00:25:10.124 AO: OutputAudioLoop: audio paused
5182010-06-13 00:25:10.140 AO: Pause 0
5192010-06-13 00:25:10.141 NVP(0): Video is 3.45379 frames behind audio (too slow), dropping frame to catch up.
5202010-06-13 00:25:10.141 NVP(0): Waiting for video buffers...
5212010-06-13 00:25:10.141 AO: Pause 1
5222010-06-13 00:25:10.145 AO: OutputAudioLoop: audio paused
5232010-06-13 00:25:10.170 AO: Pause 0
5242010-06-13 00:25:10.171 NVP(0): Video is 4.47848 frames behind audio (too slow), dropping frame to catch up.
5252010-06-13 00:25:10.171 NVP(0): Waiting for video buffers...
5262010-06-13 00:25:10.171 AO: Pause 1
5272010-06-13 00:25:10.188 AO: OutputAudioLoop: audio paused
5282010-06-13 00:25:10.200 AO: Pause 0
5292010-06-13 00:25:10.201 NVP(0): Video is 4.89483 frames behind audio (too slow), dropping frame to catch up.
5302010-06-13 00:25:10.201 NVP(0): Waiting for video buffers...
5312010-06-13 00:25:10.201 AO: Pause 1
5322010-06-13 00:25:10.209 AO: OutputAudioLoop: audio paused
5332010-06-13 00:25:10.230 AO: Pause 0
5342010-06-13 00:25:10.230 AO: OutputAudioLoop: Play Event
5352010-06-13 00:25:10.231 NVP(0): Video is 5.02727 frames behind audio (too slow), dropping frame to catch up.
5362010-06-13 00:25:10.231 NVP(0): Waiting for video buffers...
5372010-06-13 00:25:10.231 AO: Pause 1
5382010-06-13 00:25:10.252 AO: OutputAudioLoop: audio paused
5392010-06-13 00:25:10.260 AO: Pause 0
5402010-06-13 00:25:10.261 NVP(0): Video is 4.9468 frames behind audio (too slow), dropping frame to catch up.
5412010-06-13 00:25:10.261 NVP(0): Waiting for video buffers...
5422010-06-13 00:25:10.262 AO: Pause 1
5432010-06-13 00:25:10.273 AO: OutputAudioLoop: audio paused
5442010-06-13 00:25:10.295 AO: Pause 0
5452010-06-13 00:25:10.296 NVP(0): Video is 4.69912 frames behind audio (too slow), dropping frame to catch up.
5462010-06-13 00:25:10.296 NVP(0): Waiting for video buffers...
5472010-06-13 00:25:10.296 AO: Pause 1
5482010-06-13 00:25:10.316 AO: OutputAudioLoop: audio paused
5492010-06-13 00:25:10.325 AO: Pause 0
5502010-06-13 00:25:10.326 NVP(0): Video is 4.20617 frames behind audio (too slow), dropping frame to catch up.
5512010-06-13 00:25:10.327 NVP(0): Waiting for video buffers...
5522010-06-13 00:25:10.327 AO: Pause 1
5532010-06-13 00:25:10.337 AO: OutputAudioLoop: audio paused
5542010-06-13 00:25:10.356 AO: Pause 0
5552010-06-13 00:25:10.357 NVP(0): Video is 3.65663 frames behind audio (too slow), dropping frame to catch up.
5562010-06-13 00:25:10.357 NVP(0): Waiting for video buffers...
5572010-06-13 00:25:10.357 AO: Pause 1
5582010-06-13 00:25:10.359 AO: OutputAudioLoop: audio paused
5592010-06-13 00:25:10.386 AO: Pause 0
5602010-06-13 00:25:10.387 NVP(0): Video is 3.05715 frames behind audio (too slow), dropping frame to catch up.
5612010-06-13 00:25:10.387 NVP(0): Waiting for video buffers...
5622010-06-13 00:25:10.388 AO: Pause 1
5632010-06-13 00:25:10.401 AO: OutputAudioLoop: audio paused
5642010-06-13 00:25:10.416 AO: Pause 0
5652010-06-13 00:25:10.420 NVP(0): Waiting for video buffers...
5662010-06-13 00:25:10.420 AO: Pause 1
5672010-06-13 00:25:10.423 AO: OutputAudioLoop: audio paused
5682010-06-13 00:25:10.453 AO: Pause 0
5692010-06-13 00:25:10.458 NVP(0): Waiting for video buffers...
5702010-06-13 00:25:10.458 AO: Pause 1
5712010-06-13 00:25:10.465 AO: OutputAudioLoop: audio paused
5722010-06-13 00:25:10.487 AO: Pause 0
5732010-06-13 00:25:10.490 NVP(0): Waiting for video buffers...
5742010-06-13 00:25:10.490 AO: Pause 1
5752010-06-13 00:25:10.495 AO: Pause 0
5762010-06-13 00:25:10.508 AO: OutputAudioLoop: Play Event
5772010-06-13 00:25:10.531 NVP(0): Waiting for video buffers...
5782010-06-13 00:25:10.531 AO: Pause 1
5792010-06-13 00:25:10.551 AO: OutputAudioLoop: audio paused
5802010-06-13 00:25:10.564 AO: Pause 0
5812010-06-13 00:25:10.568 NVP(0): Waiting for video buffers...
5822010-06-13 00:25:10.568 AO: Pause 1
5832010-06-13 00:25:10.572 AO: OutputAudioLoop: audio paused
5842010-06-13 00:25:10.597 AO: Pause 0
5852010-06-13 00:25:10.601 NVP(0): Waiting for video buffers...
5862010-06-13 00:25:10.601 AO: Pause 1
5872010-06-13 00:25:10.615 AO: OutputAudioLoop: audio paused
5882010-06-13 00:25:10.630 AO: Pause 0
5892010-06-13 00:25:10.635 NVP(0): Video is 3.35066 frames ahead of audio,
590                        doubling video frame interval to slow down.
5912010-06-13 00:25:10.635 NVP(0): Waiting for video buffers...
5922010-06-13 00:25:10.635 AO: Pause 1
5932010-06-13 00:25:10.636 AO: OutputAudioLoop: audio paused
5942010-06-13 00:25:10.668 AO: Pause 0
5952010-06-13 00:25:10.672 NVP(0): Video is 4.1239 frames ahead of audio,
596                        doubling video frame interval to slow down.
5972010-06-13 00:25:10.672 NVP(0): Waiting for video buffers...
5982010-06-13 00:25:10.672 AO: Pause 1
5992010-06-13 00:25:10.679 AO: OutputAudioLoop: audio paused
6002010-06-13 00:25:10.705 AO: Pause 0
6012010-06-13 00:25:10.721 AO: OutputAudioLoop: Play Event
6022010-06-13 00:25:10.724 NVP(0): Video is 4.98855 frames ahead of audio,
603                        doubling video frame interval to slow down.
6042010-06-13 00:25:10.725 NVP(0): Waiting for video buffers...
6052010-06-13 00:25:10.725 AO: Pause 1
6062010-06-13 00:25:10.743 AO: OutputAudioLoop: audio paused
6072010-06-13 00:25:10.749 AO: Pause 0
6082010-06-13 00:25:10.753 NVP(0): Video is 5.82437 frames ahead of audio,
609                        doubling video frame interval to slow down.
6102010-06-13 00:25:10.753 NVP(0): Waiting for video buffers...
6112010-06-13 00:25:10.753 AO: Pause 1
6122010-06-13 00:25:10.764 AO: OutputAudioLoop: audio paused
6132010-06-13 00:25:10.782 AO: Pause 0
6142010-06-13 00:25:10.785 AO: OutputAudioLoop: Play Event
6152010-06-13 00:25:10.786 NVP(0): Video is 6.63855 frames ahead of audio,
616                        doubling video frame interval to slow down.
6172010-06-13 00:25:10.786 NVP(0): Waiting for video buffers...
6182010-06-13 00:25:10.786 AO: Pause 1
6192010-06-13 00:25:10.795 AO: Pause 0
6202010-06-13 00:25:10.807 AO: OutputAudioLoop: Play Event
6212010-06-13 00:25:10.815 NVP(0): Video is 7.41401 frames ahead of audio,
622                        doubling video frame interval to slow down.
6232010-06-13 00:25:10.835 NVP(0): Waiting for video buffers...
6242010-06-13 00:25:10.835 AO: Pause 1
6252010-06-13 00:25:10.840 AO: Pause 0
6262010-06-13 00:25:10.849 AO: OutputAudioLoop: Play Event
6272010-06-13 00:25:10.859 NVP(0): Video is 8.02559 frames ahead of audio,
628                        doubling video frame interval to slow down.
6292010-06-13 00:25:10.909 NVP(0): Video is 8.40185 frames ahead of audio,
630                        doubling video frame interval to slow down.
6312010-06-13 00:25:10.959 NVP(0): Video is 8.57166 frames ahead of audio,
632                        doubling video frame interval to slow down.
6332010-06-13 00:25:11.009 NVP(0): Video is 8.56414 frames ahead of audio,
634                        doubling video frame interval to slow down.
6352010-06-13 00:25:11.058 NVP(0): Video is 8.43862 frames ahead of audio,
636                        doubling video frame interval to slow down.
6372010-06-13 00:25:11.108 NVP(0): Video is 8.2246 frames ahead of audio,
638                        doubling video frame interval to slow down.
6392010-06-13 00:25:11.159 NVP(0): Video is 7.9367 frames ahead of audio,
640                        doubling video frame interval to slow down.
6412010-06-13 00:25:11.208 NVP(0): Video is 7.59342 frames ahead of audio,
642                        doubling video frame interval to slow down.
6432010-06-13 00:25:11.259 NVP(0): Video is 7.21606 frames ahead of audio,
644                        doubling video frame interval to slow down.
6452010-06-13 00:25:11.308 NVP(0): Video is 6.80567 frames ahead of audio,
646                        doubling video frame interval to slow down.
6472010-06-13 00:25:11.359 NVP(0): Video is 6.37799 frames ahead of audio,
648                        doubling video frame interval to slow down.
6492010-06-13 00:25:11.409 NVP(0): Video is 5.92238 frames ahead of audio,
650                        doubling video frame interval to slow down.
6512010-06-13 00:25:11.458 NVP(0): Video is 5.45328 frames ahead of audio,
652                        doubling video frame interval to slow down.
6532010-06-13 00:25:11.508 NVP(0): Video is 4.98906 frames ahead of audio,
654                        doubling video frame interval to slow down.
6552010-06-13 00:25:11.559 NVP(0): Video is 4.51352 frames ahead of audio,
656                        doubling video frame interval to slow down.
6572010-06-13 00:25:11.608 NVP(0): Video is 4.022 frames ahead of audio,
658                        doubling video frame interval to slow down.
6592010-06-13 00:25:11.658 NVP(0): Video is 3.54097 frames ahead of audio,
660                        doubling video frame interval to slow down.
6612010-06-13 00:25:11.709 NVP(0): Video is 3.05284 frames ahead of audio,
662                        doubling video frame interval to slow down.
663'video_output' mean = '42152.03', std. dev. = '52971.03', fps = '23.72'
6642010-06-13 00:25:14.980 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
6652010-06-13 00:25:14.980 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
6662010-06-13 00:25:15.079 TV: HandleStateChange(0) -- begin
6672010-06-13 00:25:15.079 TV: Attempting to change from WatchingPreRecorded to None
6682010-06-13 00:25:15.079 TV: StopStuff() for player ctx 0 -- begin
6692010-06-13 00:25:15.079 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
6702010-06-13 00:25:15.079 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
6712010-06-13 00:25:15.079 NVP(0): StopPlaying - begin
6722010-06-13 00:25:15.100 NVP(0): Decoder thread exiting.
6732010-06-13 00:25:15.100 NVP(0): Exited decoder loop.
6742010-06-13 00:25:15.100 VideoOutputXv: dtor
6752010-06-13 00:25:15.100 VideoOutputXv: DiscardFrames(1)
6762010-06-13 00:25:15.100 VideoBuffers::DiscardFrames(1): UULUUUUUUUUUUUUUAUUUUUUUUUuUuAU
6772010-06-13 00:25:15.100 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
6782010-06-13 00:25:15.101 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
6792010-06-13 00:25:15.101 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
6802010-06-13 00:25:15.101 VideoOutputXv: DiscardFrames(1)
6812010-06-13 00:25:15.101 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
6822010-06-13 00:25:15.101 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
6832010-06-13 00:25:15.101 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
6842010-06-13 00:25:15.101 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
6852010-06-13 00:25:15.106 VideoOutputXv: Closing XVideo port 254
6862010-06-13 00:25:15.126 AO: Killing AudioOutputDSP
6872010-06-13 00:25:15.137 AO: OutputAudioLoop: Stop Event
6882010-06-13 00:25:15.137 AO: kickoffOutputAudioLoop exiting
6892010-06-13 00:25:15.138 ALSA: Setting IEC958 status: audio
6902010-06-13 00:25:15.172 NVP(0): StopPlaying - end
6912010-06-13 00:25:15.172 TV: StopStuff(): stopping ring buffer
6922010-06-13 00:25:15.208 TV: StopStuff(): stopping player
6932010-06-13 00:25:15.208 TV: StopStuff() -- end
6942010-06-13 00:25:15.208 TV: Changing from WatchingPreRecorded to None
6952010-06-13 00:25:15.208 TV: HandleStateChange(0) -- end
6962010-06-13 00:25:15.208 TV: StartTV -- process events end
6972010-06-13 00:25:15.208 TV: StartTV -- process events 2 begin
6982010-06-13 00:25:15.208 TV: StartTV -- process events 2 end
6992010-06-13 00:25:15.212 TV::~TV() -- begin
7002010-06-13 00:25:15.273 TV::~TV() -- lock
7012010-06-13 00:25:15.273 NVP(0): StopPlaying - begin
7022010-06-13 00:25:15.273 NVP(0): Exited decoder loop.
7032010-06-13 00:25:15.273 NVP(0): StopPlaying - end
7042010-06-13 00:25:15.292 TV::~TV() -- end
7052010-06-13 00:25:15.292 SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STOPPED HOSTNAME t61 CHANID 4019 STARTTIME 2010-06-11T19:00:00 SENDER t61) called from UI thread
7062010-06-13 00:25:15.312 TV: StartTV -- end
7072010-06-13 00:25:16.487 SendReceiveStringList(QUERY_IS_ACTIVE_BACKEND,t61) called from UI thread
7082010-06-13 00:25:17.276 OpenGL: Deleting OpenGL Resources
7092010-06-13 00:25:17.280 Deleting UPnP client...