2009-04-01 10:40:03.742 Using runtime prefix = /usr
2009-04-01 10:40:03.743 Empty LocalHostName.
2009-04-01 10:40:03.743 Using localhost value of apollo.wilsonet.com
2009-04-01 10:40:03.749 New DB connection, total: 1
2009-04-01 10:40:03.754 Connected to database 'mythconverg' at host: chronos.wilsonet.com
2009-04-01 10:40:03.754 Closing DB connection named 'DBManager0'
2009-04-01 10:40:03.763 ScreenSaverX11Private: Gnome screen saver support enabled
2009-04-01 10:40:03.764 DPMS is active.
2009-04-01 10:40:03.766 Primary screen: 0.
2009-04-01 10:40:03.768 Connected to database 'mythconverg' at host: chronos.wilsonet.com
2009-04-01 10:40:03.771 Using screen 0, 1920x1080 at 0,0
2009-04-01 10:40:03.800 MythUI Image Cache size set to 20971520 bytes
2009-04-01 10:40:03.806 Current Schema Version: 1232
2009-04-01 10:40:04.593 max_width: 1920 max_height: 1080
2009-04-01 10:40:04.596 Primary screen: 0.
2009-04-01 10:40:04.597 Using screen 0, 1920x1080 at 0,0
2009-04-01 10:40:04.599 Using theme base resolution of 1280x720
2009-04-01 10:40:04.622 LIRC: Successfully initialized '/dev/lircd' using '/home/jarod/.mythtv/lircrc' config
2009-04-01 10:40:04.622 JoystickMenuThread Error: Joystick disabled - Failed to read /home/jarod/.mythtv/joystickmenurc
2009-04-01 10:40:04.855 Using the Qt painter
2009-04-01 10:40:05.168 TV: ctor
2009-04-01 10:40:05.243 New DB connection, total: 2
2009-04-01 10:40:05.246 Connected to database 'mythconverg' at host: chronos.wilsonet.com
2009-04-01 10:40:05.375 New DB connection, total: 3
2009-04-01 10:40:05.378 Connected to database 'mythconverg' at host: chronos.wilsonet.com
2009-04-01 10:40:05.382 TV: StartTV() -- begin
2009-04-01 10:40:05.382 TV: ctor
2009-04-01 10:40:05.469 TV: tv->Playback() -- begin
2009-04-01 10:40:05.503 Connecting to backend server: 172.31.27.17:6543 (try 1 of 5)
2009-04-01 10:40:05.504 Using protocol version 44
2009-04-01 10:40:05.565 TV: tv->Playback() -- end
2009-04-01 10:40:05.565 TV: StartTV -- process events begin
2009-04-01 10:40:05.586 TV: HandleStateChange(0) -- begin
2009-04-01 10:40:05.586 TV: Attempting to change from None to Watching WatchingPreRecorded
2009-04-01 10:40:05.590 RingBuf(/storage/television/Word_World_-_Castles_in_the_Sea;_Get_Your_Coat.mpg): OpenFile(/storage/television/Word_World_-_Castles_in_the_Sea;_Get_Your_Coat.mpg, 12)
2009-04-01 10:40:05.607 RingBuf(/storage/television/Word_World_-_Castles_in_the_Sea;_Get_Your_Coat.mpg): CalcReadAheadThresh(0 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-01 10:40:05.608 TV: StartPlayer(0, Watching WatchingPreRecorded, main) -- begin
2009-04-01 10:40:06.293 mpegts_add_stream: at pid 0x7c0 with type 2
2009-04-01 10:40:06.348 mpegts_add_stream: stream #0, has id 0x7c0 and codec MPEG2VIDEO, type Video at 0xac5d10
2009-04-01 10:40:06.349 mpegts_add_stream: at pid 0x7c1 with type 129
2009-04-01 10:40:06.350 mpegts_add_stream: stream #1, has id 0x7c1 and codec AC3, type Audio at 0xac6450
2009-04-01 10:40:06.350 mpegts_add_stream: at pid 0x7c2 with type 129
2009-04-01 10:40:06.351 mpegts_add_stream: stream #2, has id 0x7c2 and codec AC3, type Audio at 0xb25d40
2009-04-01 10:40:06.768 AFD: Stream #0, has id 0x1984 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x0xac5d10
2009-04-01 10:40:06.789 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,linearblend) filt()
2009-04-01 10:40:06.789 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-01 10:40:06.789 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-01 10:40:06.790 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-01 10:40:06.790 VDP: LoadBestPreferences(704x480, 60)
2009-04-01 10:40:06.794 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,linearblend) filt()
2009-04-01 10:40:06.795 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-01 10:40:06.795 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-01 10:40:06.795 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-01 10:40:06.796 VDP: LoadBestPreferences(704x480, 60)
2009-04-01 10:40:06.796 Using 2 CPUs for decoding
2009-04-01 10:40:06.796 AFD: InitVideoCodec() 0xac5ec0 id(MPEG2VIDEO) type (Video).
2009-04-01 10:40:06.797 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2009-04-01 10:40:06.797 AFD: EIA-708 caption service #1 is in the English language.
2009-04-01 10:40:06.797 AFD: Using ffmpeg for video decoding
2009-04-01 10:40:06.798 AFD: Looking for decoder for MPEG2VIDEO
2009-04-01 10:40:06.798 AFD: Opened codec 0xac5ec0, id(MPEG2VIDEO) type(Video)
2009-04-01 10:40:06.798 AFD: Stream #1, has id 0x1985 codec id AC3, type Audio, bitrate 192000 at 0x0xac6450
2009-04-01 10:40:06.798 AFD: codec AC3 has 2 channels
2009-04-01 10:40:06.799 AFD: Looking for decoder for AC3
No accelerated IMDCT transform found
2009-04-01 10:40:06.800 AFD: Opened codec 0xac6600, id(AC3) type(Audio)
2009-04-01 10:40:06.801 AFD: Stream #2, has id 0x1986 codec id AC3, type Audio, bitrate 128000 at 0x0xb25d40
2009-04-01 10:40:06.801 AFD: codec AC3 has 1 channels
2009-04-01 10:40:06.801 AFD: Looking for decoder for AC3
No accelerated IMDCT transform found
2009-04-01 10:40:06.803 AFD: Opened codec 0xb25ef0, id(AC3) type(Audio)
2009-04-01 10:40:06.803 RingBuf(/storage/television/Word_World_-_Castles_in_the_Sea;_Get_Your_Coat.mpg): CalcReadAheadThresh(0 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-04-01 10:40:06.808 Opening audio device 'digital'. ch 6(2) sr 48000
2009-04-01 10:40:06.808 Opening ALSA audio device 'digital'.
2009-04-01 10:40:06.966 Opening audio device 'digital'. ch 2(2) sr 48000
2009-04-01 10:40:06.967 Opening ALSA audio device 'digital'.
2009-04-01 10:40:06.972 Dec: Trying to select track (w/lang)
2009-04-01 10:40:06.973 Dec: Selecting first track
2009-04-01 10:40:06.973 Dec: Selected track #1 in the Unknown language(0)
2009-04-01 10:40:06.973 Dec: Selected track #1 in the English language(6647399)
2009-04-01 10:40:06.973 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2009-04-01 10:40:07.254 Position map filled from DB to: 61020
2009-04-01 10:40:07.254 Dec: SyncPositionMap prerecorded, from DB: 4069 entries
2009-04-01 10:40:07.255 Dec: SyncPositionMap, new totframes: 61020, new length: 2036, posMap size: 4069
2009-04-01 10:40:07.255 Input #0, mpegts, from '/storage/television/Word_World_-_Castles_in_the_Sea;_Get_Your_Coat.mpg':
2009-04-01 10:40:07.255   Duration: 00:33:56.77, start: 30250.402567, bitrate: 4443 kb/s
2009-04-01 10:40:07.256     Stream #0.0[0x7c0], 1/90000: Video: mpeg2video, yuv420p, 704x480 [PAR 10:11 DAR 4:3], 1001/30000, 15000 kb/s, 29.97 tb(r)
2009-04-01 10:40:07.256     Stream #0.1[0x7c1](eng), 1/90000: Audio: liba52, 48000 Hz, stereo, s16, 192 kb/s
2009-04-01 10:40:07.256     Stream #0.2[0x7c2](eng), 1/90000: Audio: liba52, 48000 Hz, mono, s16, 128 kb/s
2009-04-01 10:40:07.257 AFD: Position map found
2009-04-01 10:40:07.257 AFD: Successfully opened decoder for file: "/storage/television/Word_World_-_Castles_in_the_Sea;_Get_Your_Coat.mpg". novideo(0)
2009-04-01 10:40:07.286 VideoOutput: Allowed renderers: ivtv,xv-blit,xshm,xlib,opengl
2009-04-01 10:40:07.287 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,xv-blit,opengl
2009-04-01 10:40:07.291 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,linearblend) filt()
2009-04-01 10:40:07.292 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-01 10:40:07.292 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-01 10:40:07.292 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-01 10:40:07.293 VDP: LoadBestPreferences(704x480, 60)
2009-04-01 10:40:07.293 VideoOutput: Preferred renderer: xv-blit
2009-04-01 10:40:07.293 VideoOutput: Trying video renderer: 'xv-blit'
2009-04-01 10:40:07.317 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,linearblend) filt()
2009-04-01 10:40:07.317 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-01 10:40:07.318 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-01 10:40:07.318 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-01 10:40:07.326 VideoOutputXv: ctor
2009-04-01 10:40:07.326 VideoOutWindow::SetPIPState. pip_state: 0]
2009-04-01 10:40:07.327 VideoOutputXv: Creating XJ_gc
2009-04-01 10:40:07.328 VideoOutputXv: XJ_screen:     '0x1393340'
2009-04-01 10:40:07.328 VideoOutputXv: XJ_screen_num: '0'
2009-04-01 10:40:07.328 VideoOutputXv: XJ_curwin:     '62914592'
2009-04-01 10:40:07.329 VideoOutputXv: XJ_win:        '62914592'
2009-04-01 10:40:07.329 VideoOutputXv: XJ_root:       '265'
2009-04-01 10:40:07.329 VideoOutputXv: XJ_gc:         '0x11dbdf0'
2009-04-01 10:40:07.343 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
2009-04-01 10:40:07.343 Video Rect    left: 0, top: 0, width: 704, height: 480, aspect: 1.33333
2009-04-01 10:40:07.344 VDP: LoadBestPreferences(704x480, 60)
2009-04-01 10:40:07.364 VideoOutputXv: Pixel dimensions: Screen 1920x1080, window 1920x1080
2009-04-01 10:40:07.364 VideoOutputXv: Estimated display dimensions: 820x461 mm  Aspect: 1.77874
2009-04-01 10:40:07.365 VideoOutputXv: Estimated window dimensions: 820x461 mm  Aspect: 1.77874
2009-04-01 10:40:07.366 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2009-04-01 10:40:07.370 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,linearblend) filt()
2009-04-01 10:40:07.370 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2009-04-01 10:40:07.371 VDP: LoadBestPreferences(2048x2048, 0)
2009-04-01 10:40:07.371 VDP: LoadBestPreferences(2048x2048, 60)
2009-04-01 10:40:07.371 VDP: LoadBestPreferences(704x480, 60)
2009-04-01 10:40:07.372 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2009-04-01 10:40:07.372 VideoOutputXv: Adaptor#0: Intel(R) Textured Video has flag[s]: XvInputMask XvImageMask 
2009-04-01 10:40:07.372 VideoOutputXv: Has XVideo flags...
2009-04-01 10:40:07.373 VideoOutputXv: Has XV_BRIGHTNESS...
2009-04-01 10:40:07.373 VideoOutputXv: Here...
2009-04-01 10:40:07.374 VideoOutputXv: Grabbed xv port 79
2009-04-01 10:40:07.374 VideoOutputXv: XVideo surface found on port 79
2009-04-01 10:40:07.374 VideoOutputXv: XVideo Adaptor Name: 'Intel(R) Textured Video'
2009-04-01 10:40:07.375 VideoOutputXv: XVideo Format #0 is 'YUY2'
2009-04-01 10:40:07.375 VideoOutputXv: XVideo Format #1 is 'YV12'
2009-04-01 10:40:07.375 VideoOutputXv: XVideo Format #2 is 'I420'
2009-04-01 10:40:07.376 VideoOutputXv: XVideo Format #3 is 'UYVY'
2009-04-01 10:40:07.376 VideoOutputXv: XVideo Format #4 is 'XVMC'
2009-04-01 10:40:07.376 VideoOutputXv: Using XVideo Format 'YV12'
2009-04-01 10:40:07.377 VideoOutputXv: CreateShmImages(32): video_dim: 704x480
2009-04-01 10:40:07.413 VDP: SetVideoRenderer(xv-blit)
2009-04-01 10:40:07.414 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2009-04-01 10:40:07.414 VideoOutputXv: Chromakeying not possible with this XVideo port.
2009-04-01 10:40:07.414 Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
2009-04-01 10:40:07.414 Video Rect    left: 0, top: 0, width: 704, height: 480, aspect: 1.33333
2009-04-01 10:40:07.419 Over/underscan. V: 0, H: 0
2009-04-01 10:40:07.419 Display Rect  left: 240, top: 0, width: 1440, height: 1080, aspect: 1.77778
2009-04-01 10:40:07.419 Video Rect    left: 0, top: 0, width: 704, height: 480, aspect: 1.33333
2009-04-01 10:40:07.420 VDP: LoadBestPreferences(704x480, 29.97)
2009-04-01 10:40:07.421 NVP(0): LoadFilters(''..) -> 0x0
2009-04-01 10:40:07.423 OSD Theme Dimensions W: 1280 H: 720
2009-04-01 10:40:08.270 NVP(0): ClearAfterSeek(1)
2009-04-01 10:40:08.270 VideoOutputXv: ClearAfterSeek()
2009-04-01 10:40:08.270 VideoOutputXv: DiscardFrames(0)
2009-04-01 10:40:08.270 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-01 10:40:08.270 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-04-01 10:40:08.270 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-01 10:40:08.270 playCtx: StartDecoderThread(): took 983 ms to start player.
2009-04-01 10:40:08.271 TV: StartPlayer(0, Watching WatchingPreRecorded, main) -- end ok
2009-04-01 10:40:08.271 Using realtime priority.
2009-04-01 10:40:08.271 TV: Changing from None to Watching WatchingPreRecorded
2009-04-01 10:40:08.273 VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
2009-04-01 10:40:08.274 TV: HandleStateChange(0) -- end
2009-04-01 10:40:08.275 FilterManager: GetFilterInfo(convert) returning: 0x0
2009-04-01 10:40:08.275 FilterManager: GetFilterInfo(bobdeint) returning: 0x7f3e80054540
2009-04-01 10:40:08.276 Using deinterlace method bobdeint
2009-04-01 10:40:08.291 Set video sync frame interval to 33366
2009-04-01 10:40:08.296 ScreenSaverX11Private: DPMS Deactivated 1
2009-04-01 10:40:08.296 ScreenSaverX11Private: ResetTimer -- begin
2009-04-01 10:40:08.296 ScreenSaverX11Private: StopTimer
2009-04-01 10:40:08.297 ScreenSaverX11Private: StartTimer
2009-04-01 10:40:08.297 ScreenSaverX11Private: ResetTimer -- end
2009-04-01 10:40:08.308 Using audio as timebase
2009-04-01 10:40:08.308 Video timing method: DRM
2009-04-01 10:40:08.308 Refresh rate: 16685, frame interval: 33366
2009-04-01 10:40:08.310 AFD: DoFastForward(3747 (1), do discard frames)
2009-04-01 10:40:08.310 Dec: DoFastForward(3747 (1), do discard frames)
2009-04-01 10:40:08.310 Dec: FindPosition(3747, search not adjusted) --> [249(69229684),250(69502096)]
2009-04-01 10:40:08.310 AFD: SeekReset(3750, 0, do flush, do discard)
2009-04-01 10:40:08.310 AFD: SeekReset() flushing
2009-04-01 10:40:08.310 VideoOutputXv: DiscardFrames(1)
2009-04-01 10:40:08.310 VideoBuffers::DiscardFrames(1): UAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-01 10:40:08.310 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-01 10:40:08.311 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2009-04-01 10:40:08.311 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2009-04-01 10:40:08.311 NVP(0): ClearAfterSeek(0)
2009-04-01 10:40:08.325 NVP(0): Waiting for prebuffer..  0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2009-04-01 10:40:08.459 NVP(0): Waiting for prebuffer..  1 AAUuUULuAAAAAAAAAAAAAAAAAAAAAAA
2009-04-01 10:40:10.551 NVP(0): Timed out waiting for free video buffers.
2009-04-01 10:40:12.580 NVP(0): Timed out waiting for free video buffers.
2009-04-01 10:40:14.607 NVP(0): Timed out waiting for free video buffers.
^CSignal: Interrupt
Ungrabbing XVideo port: 79
<indefinite hang right here>