2012-08-28 20:48:05.251025 C  mythavtest version: master [v0.26-rc-8-g3b81805] www.mythtv.org
2012-08-28 20:48:05.251038 C  Qt version: compile: 4.8.1, runtime: 4.8.1
2012-08-28 20:48:05.251043 N  Enabled verbose msgs:  general playback libav
2012-08-28 20:48:05.251052 N  Setting Log Level to LOG_DEBUG
2012-08-28 20:48:05.251194 N  Using runtime prefix = /usr
2012-08-28 20:48:05.251211 N  Using configuration directory = /home/mce/.mythtv
2012-08-28 20:48:05.251267 I  Added logging to the console
2012-08-28 20:48:05.251268 I  Assumed character encoding: en_GB.UTF-8
2012-08-28 20:48:05.251617 N  Empty LocalHostName.
2012-08-28 20:48:05.251622 I  Using localhost value of media
2012-08-28 20:48:05.251646 I  Testing network connectivity to '192.168.1.68'
2012-08-28 20:48:05.251998 I  Starting process signal handler
2012-08-28 20:48:05.252066 I  Starting process manager
2012-08-28 20:48:05.252099 I  Starting IO manager (read)
2012-08-28 20:48:05.254652 I  Starting IO manager (write)
2012-08-28 20:48:05.361195 D  FindDatabase() - Success!
2012-08-28 20:48:05.361995 N  Setting QT default locale to en_US
2012-08-28 20:48:05.362059 I  Current locale en_US
2012-08-28 20:48:05.362090 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2012-08-28 20:48:05.453469 I  ScreenSaverX11Private: XScreenSaver support enabled
2012-08-28 20:48:05.453832 I  ScreenSaverX11Private: DPMS is disabled.
2012-08-28 20:48:05.460985 N  Desktop video mode: 1920x1080 50.000 Hz
2012-08-28 20:48:05.465945 I  max_width: 1920 max_height: 1080
2012-08-28 20:48:05.467105 N  Desktop video mode: 1920x1080 50.000 Hz
2012-08-28 20:48:05.469672 I  max_width: 1920 max_height: 1080
2012-08-28 20:48:05.469683 I  Trying 1920x1080 0.000 Hz
2012-08-28 20:48:05.470005 I  Dynamic TwinView rate found, set 6.90282e-310Hz as XRandR 0
2012-08-28 20:48:05.470069 I  SwitchToGUI: Switched to 1920x1080 0.000 Hz
2012-08-28 20:48:05.473760 I  LIRC: Successfully initialized '/dev/lircd' using '/home/mce/.lircrc' config
2012-08-28 20:48:05.473819 E  JoystickMenuThread: Joystick disabled - Failed to read /home/mce/.mythtv/joystickmenurc
2012-08-28 20:48:05.477951 I  Added logging to mythlogserver at TCP:35327
2012-08-28 20:48:05.495632 E  CECAdapter: Failed to find any CEC devices.
2012-08-28 20:48:05.495693 I  CECAdapter: Closing down CEC.
2012-08-28 20:48:05.495705 I  UDPListener: Enabling
2012-08-28 20:48:05.496514 D  Adding IPv4 loopback to address list.
2012-08-28 20:48:05.496525 D  Adding BackendServerIP6 to address list.
2012-08-28 20:48:05.496534 D  Adding BackendServerIP to address list.
2012-08-28 20:48:05.496565 D  Adding link-local 'FE80::224:1DFF:FE75:4802%eth0' to address list.
2012-08-28 20:48:05.496873 I  Binding to UDP 127.0.0.1:6948
2012-08-28 20:48:05.496913 I  Binding to UDP 192.168.1.68:6948
2012-08-28 20:48:05.496963 I  Binding to UDP [::1]:6948
2012-08-28 20:48:05.497014 I  Binding to UDP [fe80::224:1dff:fe75:4802%eth0]:6948
2012-08-28 20:48:05.497057 I  Binding to UDP 192.168.1.255:6948
2012-08-28 20:48:05.517038 I  Using Frameless Window
2012-08-28 20:48:05.517065 I  Using Full Screen Window
2012-08-28 20:48:05.518241 E  MythUIHelper: LoadScalePixmap(/home/mce/.mythtv/themes/Arclight/Backgrounds/tendrils1.png)Unable to find image file
2012-08-28 20:48:05.519074 I  Trying the OpenGL painter
2012-08-28 20:48:05.522259 W  NVCtrl: OpenGL Sync to VBlank is disabled.
2012-08-28 20:48:05.522296 W  NVCtrl: For best results enable this in NVidia settings or try running:
2012-08-28 20:48:05.522300 W  NVCtrl: nvidia-settings -a "SyncToVBlank=1"
2012-08-28 20:48:05.522304 W  NVCtrl: Alternatively try setting the '__GL_SYNC_TO_VBLANK' environment variable.
2012-08-28 20:48:05.573695 D  OpenGL: Extension not found: glGenFencesAPPLE
2012-08-28 20:48:05.574009 D  OpenGL: Extension not found: glDeleteFencesAPPLE
2012-08-28 20:48:05.574312 D  OpenGL: Extension not found: glSetFenceAPPLE
2012-08-28 20:48:05.574613 D  OpenGL: Extension not found: glFinishFenceAPPLE
2012-08-28 20:48:05.575148 I  OpenGL1: Fragment program support available
2012-08-28 20:48:05.575203 I  OpenGL: OpenGL vendor  : NVIDIA Corporation
2012-08-28 20:48:05.575209 I  OpenGL: OpenGL renderer: GeForce GT 430/PCIe/SSE2
2012-08-28 20:48:05.575212 I  OpenGL: OpenGL version : 4.2.0 NVIDIA 295.40
2012-08-28 20:48:05.575219 I  OpenGL: Max texture size: 16384 x 16384
2012-08-28 20:48:05.575223 I  OpenGL: Max texture units: 4
2012-08-28 20:48:05.575226 I  OpenGL: Direct rendering: Yes
2012-08-28 20:48:05.575232 I  OpenGL: PixelBufferObject support available
2012-08-28 20:48:05.575235 I  OpenGL: Initialised MythRenderOpenGL
2012-08-28 20:48:05.720840 I  Setup Interrupt handler
2012-08-28 20:48:05.720848 I  Setup Terminated handler
2012-08-28 20:48:05.720854 I  Setup Segmentation fault handler
2012-08-28 20:48:05.720860 I  Setup Aborted handler
2012-08-28 20:48:05.720865 I  Setup Bus error handler
2012-08-28 20:48:05.720871 I  Setup Floating point exception handler
2012-08-28 20:48:05.720877 I  Setup Illegal instruction handler
2012-08-28 20:48:05.776233 I  Current MythTV Schema Version (DBSchemaVer): 1307
2012-08-28 20:48:05.777963 I  TV: Creating TV object
2012-08-28 20:48:05.788310 I  TV: Finished creating TV object
2012-08-28 20:48:05.788317 I  TV: StartTV() -- begin
2012-08-28 20:48:05.788373 N  Suspending idle timer
2012-08-28 20:48:05.788382 I  TV: Init -- begin
2012-08-28 20:48:05.789590 I  TV: Created TvPlayWindow.
2012-08-28 20:48:05.790900 I  TV: DrawUnusedRects() -- begin
2012-08-28 20:48:05.790909 I  TV: DrawUnusedRects() -- end
2012-08-28 20:48:05.866658 I  TV: DrawUnusedRects() -- begin
2012-08-28 20:48:05.866680 I  TV: DrawUnusedRects() -- end
2012-08-28 20:48:05.874521 I  TV: Init -- end
2012-08-28 20:48:05.874568 D  SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STARTED HOSTNAME media CHANID 1001 STARTTIME 2012-08-26T10:55:00Z SENDER media) called from UI thread
2012-08-28 20:48:05.875915 I  MythCoreContext: Connecting to backend server: 192.168.1.68:6543 (try 1 of 1)
2012-08-28 20:48:05.876372 I  Using protocol version 75
2012-08-28 20:48:05.876971 I  TV: tv->Playback() -- begin
2012-08-28 20:48:05.878985 I  TV: HandleStateChange(0) -- begin
2012-08-28 20:48:05.878998 I  TV: Attempting to change from None to WatchingPreRecorded
2012-08-28 20:48:05.879223 I  FileRingBuf(/var/lib/mythtv/recordings/1001_20120826105500.mpg): OpenFile(/var/lib/mythtv/recordings/1001_20120826105500.mpg, 2000 ms)
2012-08-28 20:48:05.882674 I  TV: StartPlayer(0, WatchingPreRecorded, main) -- begin
2012-08-28 20:48:05.882695 I  TV: Elapsed time since TV constructor was called: 105 ms
2012-08-28 20:48:05.883360 D  FilterManager: filters[adjust] = 0x17443c0
2012-08-28 20:48:05.883441 D  FilterManager: filters[bobdeint] = 0x174ffe0
2012-08-28 20:48:05.883519 D  FilterManager: filters[crop] = 0x1337fd0
2012-08-28 20:48:05.883601 D  FilterManager: filters[denoise3d] = 0x1705290
2012-08-28 20:48:05.883671 D  FilterManager: filters[fieldorderdoubleprocessdeint] = 0x17423c0
2012-08-28 20:48:05.883739 D  FilterManager: filters[forceyv12] = 0x17062b0
2012-08-28 20:48:05.883748 D  FilterManager: filters[forceyuv422p] = 0x178dd20
2012-08-28 20:48:05.883753 D  FilterManager: filters[forcergb24] = 0x16ecc60
2012-08-28 20:48:05.883759 D  FilterManager: filters[forceargb32] = 0x196c500
2012-08-28 20:48:05.883852 D  FilterManager: filters[greedyhdeint] = 0x173ee80
2012-08-28 20:48:05.883859 D  FilterManager: filters[greedyhdoubleprocessdeint] = 0x174dff0
2012-08-28 20:48:05.883934 D  FilterManager: filters[invert] = 0x196d610
2012-08-28 20:48:05.884008 D  FilterManager: filters[ivtc] = 0x172ec80
2012-08-28 20:48:05.884296 D  FilterManager: filters[kerneldeint] = 0x1705b60
2012-08-28 20:48:05.884303 D  FilterManager: filters[kerneldoubleprocessdeint] = 0x176d2d0
2012-08-28 20:48:05.884393 D  FilterManager: filters[linearblend] = 0x17704c0
2012-08-28 20:48:05.884475 D  FilterManager: filters[onefield] = 0x1731730
2012-08-28 20:48:05.884645 D  FilterManager: filters[postprocess] = 0x136b5f0
2012-08-28 20:48:05.884725 D  FilterManager: filters[quickdnr] = 0x1360370
2012-08-28 20:48:05.884797 D  FilterManager: filters[vflip] = 0x17a0e60
2012-08-28 20:48:05.884898 D  FilterManager: filters[yadifdeint] = 0x172b740
2012-08-28 20:48:05.884904 D  FilterManager: filters[yadifdoubleprocessdeint] = 0x169b090
2012-08-28 20:48:06.003184 N  AudioPlayer: Enabling Audio
2012-08-28 20:48:06.005737 D  AFD: PlayerFlags: 0xc0
2012-08-28 20:48:06.005757 I  Player(0): Stretch Factor 1, allow passthru 
2012-08-28 20:48:06.005977 I  AFD: Buffer size: 32768, streamed 0
2012-08-28 20:48:06.006157 D  mpegts_read_header: TS packet size = 188
2012-08-28 20:48:06.006224 D  mpegts_add_stream: at pid 0x65 with type 2
2012-08-28 20:48:06.006918 D  [mpegts @ 0x7f11d8d2da60] stream=0 stream_type=2 pid=65 prog_reg_desc=
2012-08-28 20:48:06.006934 D  mpegts_add_stream: stream #0, has id 0x65 and codec MPEG2VIDEO, type Video at 0x1760880
2012-08-28 20:48:06.006941 D  mpegts_add_stream: at pid 0x66 with type 3
2012-08-28 20:48:06.007534 D  [mpegts @ 0x7f11d8d2da60] stream=1 stream_type=3 pid=66 prog_reg_desc=
2012-08-28 20:48:06.007550 D  mpegts_add_stream: stream #1, has id 0x66 and codec MP3, type Audio at 0x16a7a60
2012-08-28 20:48:06.007555 D  mpegts_add_stream: at pid 0x6a with type 3
2012-08-28 20:48:06.008169 D  [mpegts @ 0x7f11d8d2da60] stream=2 stream_type=3 pid=6a prog_reg_desc=
2012-08-28 20:48:06.008179 D  mpegts_add_stream: stream #2, has id 0x6a and codec MP3, type Audio at 0x16e7f40
2012-08-28 20:48:06.008185 D  mpegts_add_stream: at pid 0x69 with type 6
2012-08-28 20:48:06.008781 D  [mpegts @ 0x7f11d8d2da60] stream=3 stream_type=6 pid=69 prog_reg_desc=
2012-08-28 20:48:06.008791 D  mpegts_add_stream: stream #3, has id 0x69 and codec DVB_SUBTITLE, type Subtitle at 0x16ff5a0
2012-08-28 20:48:06.008796 D  mpegts_add_stream: at pid 0x6e with type 11
2012-08-28 20:48:06.009974 D  mpegts_add_stream: stream #4, has id 0x6e and codec DSMCC_B, type Data at 0x1373620
2012-08-28 20:48:06.009980 D  mpegts_add_stream: at pid 0x1ce9 with type 11
2012-08-28 20:48:06.011144 D  mpegts_add_stream: stream #5, has id 0x1ce9 and codec DSMCC_B, type Data at 0x175db20
2012-08-28 20:48:06.011151 D  mpegts_add_stream: at pid 0x1c21 with type 11
2012-08-28 20:48:06.012392 D  mpegts_add_stream: stream #6, has id 0x1c21 and codec DSMCC_B, type Data at 0x1716080
2012-08-28 20:48:06.012401 D  mpegts_add_stream: at pid 0x1c22 with type 11
2012-08-28 20:48:06.013630 D  mpegts_add_stream: stream #7, has id 0x1c22 and codec DSMCC_B, type Data at 0x1740660
2012-08-28 20:48:06.013638 D  mpegts_add_stream: at pid 0x1c23 with type 11
2012-08-28 20:48:06.014798 D  mpegts_add_stream: stream #8, has id 0x1c23 and codec DSMCC_B, type Data at 0x17229a0
2012-08-28 20:48:06.014804 D  mpegts_add_stream: at pid 0x1c24 with type 11
2012-08-28 20:48:06.015988 D  mpegts_add_stream: stream #9, has id 0x1c24 and codec DSMCC_B, type Data at 0x171ae20
2012-08-28 20:48:06.015998 D  mpegts_add_stream: at pid 0x1c25 with type 11
2012-08-28 20:48:06.017147 D  mpegts_add_stream: stream #10, has id 0x1c25 and codec DSMCC_B, type Data at 0x1703740
2012-08-28 20:48:06.065859 I  AFD: Stream #0, has id 0x65 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x1760880
2012-08-28 20:48:06.066049 I  VDP: decoder<->render support: ffmpeg      null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi openglvaapi
2012-08-28 20:48:06.066059 I  VDP: decoder<->render support: vdpau       vdpau nullvdpau
2012-08-28 20:48:06.066067 I  VDP: decoder<->render support: vaapi       nullvaapi openglvaapi
2012-08-28 20:48:06.066076 I  VDP: decoder<->render support: crystalhd   null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi
2012-08-28 20:48:06.067251 I  VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpaustudio,vdpauhqscaling)
2012-08-28 20:48:06.067268 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-08-28 20:48:06.067290 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-08-28 20:48:06.067306 I  VDP: LoadBestPreferences(720x576, 60)
2012-08-28 20:48:06.067361 I  AFD: Using 1 CPUs for decoding
2012-08-28 20:48:06.067372 I  AFD: InitVideoCodec() 0x1ad4ca0 id(MPEG2VIDEO) type (Video).
2012-08-28 20:48:06.067414 I  AFD: Selected FPS is 25 (avg 25 codec 25 container 90000 estimated 25)
2012-08-28 20:48:06.067433 I  Player(0): SetFrameInterval ps:1 scan:3
2012-08-28 20:48:06.067456 I  Player(0): detectInterlace(Detect Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
2012-08-28 20:48:06.067505 I  AFD: Using vdpau for video decoding
2012-08-28 20:48:06.067511 I  AFD: Looking for decoder for MPEG2VIDEO
2012-08-28 20:48:06.067536 I  AFD: Opened codec 0x1ad4ca0, id(MPEG2VIDEO) type(Video)
2012-08-28 20:48:06.067549 I  AFD: Stream #1, has id 0x66 codec id MP2, type Audio, bitrate 256000 at 0x16a7a60
2012-08-28 20:48:06.067556 I  AFD: codec MP2 has 2 channels
2012-08-28 20:48:06.067561 I  AFD: Looking for decoder for MP2
2012-08-28 20:48:06.067631 I  AFD: Opened codec 0x168db00, id(MP2) type(Audio)
2012-08-28 20:48:06.067654 I  AFD: Stream #2, has id 0x6a codec id MP3, type Audio, bitrate 0 at 0x16e7f40
2012-08-28 20:48:06.067661 I  AFD: codec MP3 has 0 channels
2012-08-28 20:48:06.067666 I  AFD: Looking for decoder for MP3
2012-08-28 20:48:06.067709 I  AFD: Opened codec 0x1761980, id(MP3) type(Audio)
2012-08-28 20:48:06.067723 I  AFD: Stream #3, has id 0x69 codec id DVB_SUBTITLE, type Subtitle, bitrate 0 at 0x16ff5a0
2012-08-28 20:48:06.067729 I  AFD: subtitle codec (Subtitle)
2012-08-28 20:48:06.067734 I  AFD: Looking for decoder for DVB_SUBTITLE
2012-08-28 20:48:06.067762 D  [dvbsub @ 0x7f11d8475000] Invalid extradata, subtitle streams may be combined!
2012-08-28 20:48:06.067773 I  AFD: Opened codec 0x1781660, id(DVB_SUBTITLE) type(Subtitle)
2012-08-28 20:48:06.067793 I  AFD: Subtitle track #1 is A/V stream #3 and is in the English language(6647399).
2012-08-28 20:48:06.067805 I  AFD: Stream #4, has id 0x6e codec id DSMCC_B, type Data, bitrate 0 at 0x1373620
2012-08-28 20:48:06.067818 I  AFD: data codec (Data)
2012-08-28 20:48:06.067829 I  AFD: Stream #5, has id 0x1ce9 codec id DSMCC_B, type Data, bitrate 0 at 0x175db20
2012-08-28 20:48:06.067838 I  AFD: data codec (Data)
2012-08-28 20:48:06.067849 I  AFD: Stream #6, has id 0x1c21 codec id DSMCC_B, type Data, bitrate 0 at 0x1716080
2012-08-28 20:48:06.067857 I  AFD: data codec (Data)
2012-08-28 20:48:06.067868 I  AFD: Stream #7, has id 0x1c22 codec id DSMCC_B, type Data, bitrate 0 at 0x1740660
2012-08-28 20:48:06.067876 I  AFD: data codec (Data)
2012-08-28 20:48:06.067887 I  AFD: Stream #8, has id 0x1c23 codec id DSMCC_B, type Data, bitrate 0 at 0x17229a0
2012-08-28 20:48:06.067895 I  AFD: data codec (Data)
2012-08-28 20:48:06.067907 I  AFD: Stream #9, has id 0x1c24 codec id DSMCC_B, type Data, bitrate 0 at 0x171ae20
2012-08-28 20:48:06.067914 I  AFD: data codec (Data)
2012-08-28 20:48:06.067926 I  AFD: Stream #10, has id 0x1c25 codec id DSMCC_B, type Data, bitrate 0 at 0x1703740
2012-08-28 20:48:06.067934 I  AFD: data codec (Data)
2012-08-28 20:48:06.068211 I  AO: Opening audio device 'hdmi:CARD=NVidia,DEV=0' ch 2(2) sr 48000 sf signed 16 bit reenc 0
2012-08-28 20:48:06.074039 E  ALSA: Requested 500000us got 341333 buffer time
2012-08-28 20:48:06.074149 E  ALSA: Try to manually increase audio buffer with: echo 128 | sudo tee /proc/asound/card0/pcm3p/sub0/prealloc
2012-08-28 20:48:06.265566 I  Dec: Selected track #1 in the Unknown language(0)
2012-08-28 20:48:06.265583 I  Dec: Selected track #1 in the English language(6647399)
2012-08-28 20:48:06.265596 I  Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2012-08-28 20:48:06.266284 I  Dec: Position map filled from DB to: 402
2012-08-28 20:48:06.266295 I  Dec: SyncPositionMap prerecorded, from DB: 18 entries
2012-08-28 20:48:06.266306 I  Dec: SyncPositionMap, new totframes: 402, new length: 16, posMap size: 18
2012-08-28 20:48:06.267303 D  Input #0, mpegts, from '/var/lib/mythtv/recordings/1001_20120826105500.mpg':
2012-08-28 20:48:06.267321 D  Duration: 00:00:16.08, start: 38601.861900, bitrate: 3138 kb/s
2012-08-28 20:48:06.267364 D  Stream #0:0[0x65], 119, 1/90000: Video: mpeg2video, yuv420p, 720x576 [SAR 64:45 DAR 16:9], 1/50, 15000 kb/s, 25 fps, 25 tbr, 90k tbn, 50 tbc
2012-08-28 20:48:06.267381 D  Stream #0:1[0x66](eng), 211, 1/90000: Audio: mp2, 48000 Hz, stereo, s16, 256 kb/s
2012-08-28 20:48:06.267396 D  Stream #0:2[0x6a](eng), 0, 1/90000: Audio: mp3, 0 channels, s16
2012-08-28 20:48:06.267408 D  Stream #0:3[0x69](eng), 6, 1/90000: Subtitle: dvb_subtitle
2012-08-28 20:48:06.267418 D  Stream #0:4[0x6e], 10, 1/90000: Data: dsmcc_b
2012-08-28 20:48:06.267429 D  Stream #0:5[0x1ce9], 28, 1/90000: Data: dsmcc_b
2012-08-28 20:48:06.267439 D  Stream #0:6[0x1c21], 203, 1/90000: Data: dsmcc_b
2012-08-28 20:48:06.267449 D  Stream #0:7[0x1c22], 49, 1/90000: Data: dsmcc_b
2012-08-28 20:48:06.267459 D  Stream #0:8[0x1c23], 48, 1/90000: Data: dsmcc_b
2012-08-28 20:48:06.267469 D  Stream #0:9[0x1c24], 20, 1/90000: Data: dsmcc_b
2012-08-28 20:48:06.267479 D  Stream #0:10[0x1c25], 43, 1/90000: Data: dsmcc_b
2012-08-28 20:48:06.267487 I  AFD: Position map found
2012-08-28 20:48:06.267495 I  AFD: Successfully opened decoder for file: "/var/lib/mythtv/recordings/1001_20120826105500.mpg". novideo(0)
2012-08-28 20:48:06.270337 I  VideoOutput: Allowed renderers: vdpau
2012-08-28 20:48:06.270353 I  VideoOutput: Allowed renderers (filt: vdpau): vdpau
2012-08-28 20:48:06.271039 I  VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpaustudio,vdpauhqscaling)
2012-08-28 20:48:06.271052 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-08-28 20:48:06.271072 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-08-28 20:48:06.271088 I  VDP: LoadBestPreferences(720x576, 60)
2012-08-28 20:48:06.271105 I  VideoOutput: Preferred renderer: vdpau
2012-08-28 20:48:06.271115 I  VideoOutput: Trying video renderer: 'vdpau'
2012-08-28 20:48:06.273344 D  ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2012-08-28 20:48:06.273360 D  ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2012-08-28 20:48:06.273375 D  ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2012-08-28 20:48:06.273388 D  ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2012-08-28 20:48:06.273405 D  ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2012-08-28 20:48:06.273427 D  ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2012-08-28 20:48:06.273441 D  ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2012-08-28 20:48:06.273454 D  ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2012-08-28 20:48:06.273471 D  ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2012-08-28 20:48:06.273485 D  ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2012-08-28 20:48:06.273498 D  ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2012-08-28 20:48:06.273510 D  ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2012-08-28 20:48:06.273528 D  ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2012-08-28 20:48:06.273542 D  ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2012-08-28 20:48:06.273555 D  ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2012-08-28 20:48:06.273568 D  ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2012-08-28 20:48:06.273585 D  ColourSpace: Brightness: 0.0000 Contrast: 1.0000 Saturation: 1.0000 Hue: 0.0000 StudioLevels: 0
2012-08-28 20:48:06.273598 D  ColourSpace: 1.1644 0.0000 1.5972 -0.8748
2012-08-28 20:48:06.273612 D  ColourSpace: 1.1644 -0.3916 -0.8128 0.5315
2012-08-28 20:48:06.273625 D  ColourSpace: 1.1644 2.0184 0.0000 -1.0862
2012-08-28 20:48:06.275351 I  VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled) deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto,vdpaustudio,vdpauhqscaling)
2012-08-28 20:48:06.275362 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-08-28 20:48:06.275380 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-08-28 20:48:06.275408 I  VideoOutWindow::SetPIPState. pip_state: 0]
2012-08-28 20:48:06.275418 I  VDP: LoadBestPreferences(2048x2048, 25)
2012-08-28 20:48:06.275437 I  Clearing OpenGL painter cache.
2012-08-28 20:48:06.276574 I  Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
2012-08-28 20:48:06.276587 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-08-28 20:48:06.276597 I  VDP: LoadBestPreferences(720x576, 25)
2012-08-28 20:48:06.276620 I  Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
2012-08-28 20:48:06.276633 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-08-28 20:48:06.276640 I  VDP: SetVideoRenderer(vdpau)
2012-08-28 20:48:06.276648 I  VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2012-08-28 20:48:06.277151 I  Trying to match best refresh rate 25.000Hz
2012-08-28 20:48:06.277168 I  Trying 1920x1080 50.000 Hz
2012-08-28 20:48:06.277627 I  Dynamic TwinView rate found, set 50Hz as XRandR 50
2012-08-28 20:48:06.277726 I  SwitchToVideo: Video size 720 x 576: 
    Switched to displaying resolution 1920 x 1080, 487mm x 274mm
2012-08-28 20:48:06.278179 I  Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
2012-08-28 20:48:06.278193 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-08-28 20:48:06.278203 I  VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2012-08-28 20:48:06.278214 I  VideoOutput: Actual display dimensions: 487x274 mm  Aspect: 1.77737
2012-08-28 20:48:06.278224 I  VideoOutput: Estimated window dimensions: 487x274 mm  Aspect: 1.77737
2012-08-28 20:48:06.278251 I  VidOutVDPAU: Requesting high quality scaling.
2012-08-28 20:48:06.382688 I  VDPAU: Created 2 output surfaces.
2012-08-28 20:48:06.382709 I  VDPAU: Set colorkey to 0x20202
2012-08-28 20:48:06.382723 I  VDPAU: Version 1
2012-08-28 20:48:06.382734 I  VDPAU: Information NVIDIA VDPAU Driver Shared Library  295.40  Thu Apr  5 22:02:06 PDT 2012
2012-08-28 20:48:06.382748 I  VDPAU: HQ scaling level 1 of 9 available.
2012-08-28 20:48:06.382755 I  VDPAU: MPEG4 hardware acceleration supported.
2012-08-28 20:48:06.382762 I  VDPAU: Created VDPAU render device 1920x1080
2012-08-28 20:48:06.382778 I  VidOutVDPAU: Created VDPAU osd (1920x1080)
2012-08-28 20:48:06.391110 I  VDPAU: Enabling high quality scaling.
2012-08-28 20:48:06.391298 I  ColourSpace: PictureAttributes: Brightness, Contrast, Colour, Hue, Studio Levels, 
2012-08-28 20:48:06.391305 I  VidOutVDPAU: Using ITU BT.601 colorspace
2012-08-28 20:48:06.391334 I  Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
2012-08-28 20:48:06.391347 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-08-28 20:48:06.391353 I  VidOutVDPAU: Created VDPAU context (GPU decode)
2012-08-28 20:48:06.391848 I  Over/underscan. V: 0, H: 0
2012-08-28 20:48:06.391863 I  Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
2012-08-28 20:48:06.391875 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-08-28 20:48:06.391887 N  Player(0): Forcing decode extra audio option on (Video method requires it).
2012-08-28 20:48:06.391901 D  Player(0): InitFilters() vo 'vdpaucolorspace=auto,vdpaustudio,vdpauhqscaling' prog '' over ''
2012-08-28 20:48:06.391917 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2012-08-28 20:48:06.391941 I  Player(0): LoadFilters('vdpaucolorspace=auto,vdpaustudio,vdpauhqscaling'..) -> 0x0
2012-08-28 20:48:06.392772 I  Player(0): Decoder thread starting.
2012-08-28 20:48:06.392791 I  AFD: DoFastForward(316 (0), do discard frames)
2012-08-28 20:48:06.392805 I  Dec: DoFastForward(316 (0), do discard frames)
2012-08-28 20:48:06.392828 I  Dec: FindPosition(316, search not adjusted) --> 
			[13:306(5002304),14:330(5222452)]
2012-08-28 20:48:06.392865 I  AFD: SeekReset(306, 10, do flush, do discard)
2012-08-28 20:48:06.392886 I  AFD: SeekReset() flushing
2012-08-28 20:48:06.392909 I  VidOutVDPAU: DiscardFrames(1)
2012-08-28 20:48:06.392949 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA
2012-08-28 20:48:06.392984 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA -- done
2012-08-28 20:48:06.393004 I  VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAA -- done()
2012-08-28 20:48:06.398742 D  [mpegts @ 0x7f11d8d2da60] write_section_data: PID 0x1c24 CRC error
2012-08-28 20:48:06.404098 I  VidOutVDPAU: Created VDPAU decoder (2 ref frames)
2012-08-28 20:48:06.404365 D  [mpegts @ 0x7f11d8d2da60] write_section_data: PID 0x1c25 CRC error
2012-08-28 20:48:06.418941 I  Player(0): ClearAfterSeek(0)
2012-08-28 20:48:06.432689 I  OSD: Loaded window osd_message
2012-08-28 20:48:06.437536 I  OSD: Loaded window osd_input
2012-08-28 20:48:06.459064 I  OSD: Loaded window program_info
2012-08-28 20:48:06.464958 I  OSD: Loaded window browse_info
2012-08-28 20:48:06.482425 I  OSD: Loaded window osd_status
2012-08-28 20:48:06.500154 I  OSD: Loaded window osd_program_editor
2012-08-28 20:48:06.513437 I  OSD: Loaded window osd_debug
2012-08-28 20:48:06.513451 I  OSD: Loaded OSD: size 1920x1080 offset 0+0
2012-08-28 20:48:06.513502 I  OSD: Created window aa_OSD_SUBTITLES
2012-08-28 20:48:06.513872 I  Player(0): ClearAfterSeek(0)
2012-08-28 20:48:06.514433 I  VDP: GetFilteredDeint() : vdpau -> 'vdpauadvanceddoublerate'
2012-08-28 20:48:06.514647 I  VDPAU: Enabling high quality scaling.
2012-08-28 20:48:06.515154 I  VidOutVDPAU: Enabled deinterlacing.
2012-08-28 20:48:06.515188 I  VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2012-08-28 20:48:06.515210 E  VSYNC: RTCVideoSync: Could not open /dev/rtc: 
			eno: Permission denied (13)
2012-08-28 20:48:06.516189 I  Player(0): Video timing method: USleep with busy wait
2012-08-28 20:48:06.516205 I  Player(0): Display Refresh Rate: 50.000 Video Frame Rate: 25.000
2012-08-28 20:48:06.516216 I  Player(0): SetFrameInterval ps:1 scan:1
2012-08-28 20:48:06.519389 I  playCtx: StartPlaying(): took 0 ms to start player.
2012-08-28 20:48:06.519406 I  TV: Created player.
2012-08-28 20:48:06.519423 I  TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok
2012-08-28 20:48:06.519458 D  SendReceiveStringList(MESSAGE,COMMFLAG_REQUEST 1001_2012-08-26T10:55:00Z) called from UI thread
2012-08-28 20:48:06.520894 I  TV: Changing from None to WatchingPreRecorded
2012-08-28 20:48:06.522752 I  TV: DrawUnusedRects() -- begin
2012-08-28 20:48:06.522764 I  TV: DrawUnusedRects() -- end
2012-08-28 20:48:06.522878 I  TV: Main UI disabled.
2012-08-28 20:48:06.522885 I  TV: HandleStateChange(0) -- end
2012-08-28 20:48:06.522893 I  TV: tv->Playback() -- end
2012-08-28 20:48:06.522899 I  TV: Entering main playback loop.
2012-08-28 20:48:06.525022 D  ScreenSaverX11Private: ResetTimer -- begin
2012-08-28 20:48:06.525029 D  ScreenSaverX11Private: StopTimer
2012-08-28 20:48:06.525311 D  ScreenSaverX11Private: StartTimer
2012-08-28 20:48:06.525322 D  ScreenSaverX11Private: ResetTimer -- end
2012-08-28 20:48:06.525332 I  TV: DrawUnusedRects() -- begin
2012-08-28 20:48:06.525337 I  TV: DrawUnusedRects() -- end
2012-08-28 20:48:06.525572 I  Player(0): Video Aspect ratio changed from 1.33333 to 1.77778
2012-08-28 20:48:06.525589 I  Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2012-08-28 20:48:06.525602 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.77778
2012-08-28 20:48:06.525617 I  Player(0): Enabled deinterlacing
2012-08-28 20:48:06.537561 I  VDPAU: Added 2 output surfaces (total 4, max 4)
2012-08-28 20:48:07.557727 D  SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_PAUSED HOSTNAME media CHANID 1001 STARTTIME 2012-08-26T10:55:00Z SENDER media) called from UI thread
2012-08-28 20:48:07.601382 I  Player(0): Play speed: rate: 25 speed: 0 skip: 0 => new interval 40000
2012-08-28 20:48:07.601783 I  VDPAU: Enabling high quality scaling.
2012-08-28 20:48:07.601987 I  VDP: GetFilteredDeint(vdpauadvanced) : vdpau -> 'vdpauadvanced'
2012-08-28 20:48:07.602129 I  VDPAU: Enabling high quality scaling.
2012-08-28 20:48:07.602507 I  VidOutVDPAU: Enabled deinterlacing.
2012-08-28 20:48:07.642606 I  VidOutVDPAU: UpdatePauseFrame() UAUUDuDDLUUUUU
2012-08-28 20:48:07.656792 D  ScreenSaverX11Private: StopTimer
2012-08-28 20:48:08.046192 D  TV: OSDDialogEvent: result -1 text  action 0
2012-08-28 20:48:12.610027 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2012-08-28 20:48:12.610049 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2012-08-28 20:48:12.653342 I  TV: HandleStateChange(0) -- begin
2012-08-28 20:48:12.653356 I  TV: Attempting to change from WatchingPreRecorded to None
2012-08-28 20:48:12.653371 I  TV: StopStuff() for player ctx 0 -- begin
2012-08-28 20:48:12.653382 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2012-08-28 20:48:12.653395 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2012-08-28 20:48:12.653404 I  Player(0): StopPlaying - begin
2012-08-28 20:48:12.655539 I  Player(0): Decoder thread exiting.
2012-08-28 20:48:12.655589 I  Player(0): Exited decoder loop.
2012-08-28 20:48:12.657102 I  VidOutVDPAU: DiscardFrames(1)
2012-08-28 20:48:12.657123 I  VideoBuffers::DiscardFrames(1): UAUUAuAALUUUUU
2012-08-28 20:48:12.657146 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAA -- done
2012-08-28 20:48:12.657163 I  VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAA -- done()
2012-08-28 20:48:12.658233 W  MythPainter: 46 images not yet de-allocated.
2012-08-28 20:48:12.658255 I  VDPAU Painter: Clearing VDPAU painter cache.
2012-08-28 20:48:12.742670 I  Trying 1920x1080 0.000 Hz
2012-08-28 20:48:12.742684 I  SwitchToGUI: Switched to 1920x1080 0.000 Hz
2012-08-28 20:48:12.769886 I  Player(0): StopPlaying - end
2012-08-28 20:48:12.769897 I  TV: StopStuff(): stopping ring buffer
2012-08-28 20:48:12.769917 I  TV: StopStuff(): stopping player
2012-08-28 20:48:12.769922 I  TV: StopStuff() -- end
2012-08-28 20:48:12.769935 I  TV: Changing from WatchingPreRecorded to None
2012-08-28 20:48:12.769949 I  TV: HandleStateChange(0) -- end
2012-08-28 20:48:12.769961 I  TV: Exiting main playback loop.
2012-08-28 20:48:12.769968 I  TV: StartTV -- process events 2 begin
2012-08-28 20:48:12.770092 D  ScreenSaverX11Private: StopTimer
2012-08-28 20:48:12.770141 I  TV: StartTV -- process events 2 end
2012-08-28 20:48:12.770162 I  TV::~TV() -- begin
2012-08-28 20:48:12.780363 I  TV::~TV() -- lock
2012-08-28 20:48:12.780822 I  Player(0): StopPlaying - begin
2012-08-28 20:48:12.780837 I  Player(0): Exited decoder loop.
2012-08-28 20:48:12.780845 I  Player(0): StopPlaying - end
2012-08-28 20:48:12.780936 D  Closing PAT Filter: pid=0x0
2012-08-28 20:48:12.780944 D  Closing PMT Filter: pid=0x64
2012-08-28 20:48:12.783093 I  TV::~TV() -- end
2012-08-28 20:48:12.783152 D  SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STOPPED HOSTNAME media CHANID 1001 STARTTIME 2012-08-26T10:55:00Z SENDER media) called from UI thread
2012-08-28 20:48:12.783607 N  Resuming idle timer
2012-08-28 20:48:12.783630 I  TV: StartTV -- end
2012-08-28 20:48:13.192355 I  OpenGL1: Deleting OpenGL Resources
2012-08-28 20:48:13.192411 I  OpenGL: Deleting OpenGL Resources
2012-08-28 20:48:13.192542 E  Object deleted with non-zero reference count!
2012-08-28 20:48:13.193173 I  Waiting for threads to exit.