2011-03-09 19:56:17.647 TV: StartTV() -- begin 2011-03-09 19:56:17.647 TV: ctor -- begin 2011-03-09 19:56:17.648 TV: ctor -- end 2011-03-09 19:56:17.648 TV: Init -- begin 2011-03-09 19:56:17.657 TV: DrawUnusedRects() -- begin 2011-03-09 19:56:17.657 TV: DrawUnusedRects() -- end 2011-03-09 19:56:17.680 TV: Init -- end 2011-03-09 19:56:17.681 TV: tv->Playback() -- begin 2011-03-09 19:56:17.699 TV: HandleStateChange(0) -- begin 2011-03-09 19:56:17.699 TV: Attempting to change from None to WatchingPreRecorded 2011-03-09 19:56:17.705 RingBuf(myth://192.168.1.50:6543/2051_20110307220000.mpg): OpenFile(myth://192.168.1.50:6543/2051_20110307220000.mpg, 2000 ms) 2011-03-09 19:56:17.797 TV: StartPlayer(0, WatchingPreRecorded, main) -- begin 2011-03-09 19:56:17.798 TV: Elapsed time since TV constructor was called: 151 ms 2011-03-09 19:56:17.967 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:17.981 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:17.982 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:17.999 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.000 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.018 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.018 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.036 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.036 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.053 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.073 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.074 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.074 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.109 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure 2011-03-09 19:56:18.590 [mpegts @ 0x8aa81240]Estimating duration from bitrate, this may be inaccurate 2011-03-09 19:56:18.591 [NULL @ 0x8a45a640]start time is not set in av_estimate_timings_from_pts 2011-03-09 19:56:18.862 AFD: Stream #0, has id 0x840 codec id MPEG2VIDEO, type Video, bitrate 20000000 at 0x21a464e0 2011-03-09 19:56:18.877 VDP: Ignoring profile item 253 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl)) 2011-03-09 19:56:18.877 VDP: Ignoring profile item 254 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl)) 2011-03-09 19:56:18.878 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt() 2011-03-09 19:56:18.878 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt() 2011-03-09 19:56:18.878 VDP: LoadBestPreferences(2048x2048, 0) 2011-03-09 19:56:18.878 VDP: LoadBestPreferences(2048x2048, 60) 2011-03-09 19:56:18.878 VDP: LoadBestPreferences(1920x1080, 60) 2011-03-09 19:56:18.878 AFD: Using 1 CPUs for decoding 2011-03-09 19:56:18.878 AFD: InitVideoCodec() 0x11ee800 id(MPEG2VIDEO) type (Video). 2011-03-09 19:56:18.878 AFD: Selected FPS is 29.97 (avg 36.3922 stream 29.97 container 90000 estimated 29.97) 2011-03-09 19:56:18.879 Player(0): detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan 2011-03-09 19:56:18.879 AFD: EIA-708 caption service #1 is in the English language. 2011-03-09 19:56:18.879 AFD: Using ffmpeg for video decoding 2011-03-09 19:56:18.879 AFD: Looking for decoder for MPEG2VIDEO 2011-03-09 19:56:18.879 AFD: Opened codec 0x11ee800, id(MPEG2VIDEO) type(Video) 2011-03-09 19:56:18.879 AFD: Stream #1, has id 0x841 codec id AC3, type Audio, bitrate 384000 at 0x21a0b950 2011-03-09 19:56:18.879 AFD: codec AC3 has 6 channels 2011-03-09 19:56:18.879 AFD: Looking for decoder for AC3 2011-03-09 19:56:18.879 AFD: Opened codec 0x11b1a00, id(AC3) type(Audio) 2011-03-09 19:56:18.879 AFD: Stream #2, has id 0x842 codec id AC3, type Audio, bitrate 192000 at 0x21a3ff80 2011-03-09 19:56:18.879 AFD: codec AC3 has 1 channels 2011-03-09 19:56:18.879 AFD: Looking for decoder for AC3 2011-03-09 19:56:18.880 AFD: Opened codec 0x1239400, id(AC3) type(Audio) 2011-03-09 19:56:18.880 AFD: Stream #3, has id 0x843 codec id DVB_VBI, type Data, bitrate 0 at 0x5c96c0 2011-03-09 19:56:18.880 AFD: data codec (Data) 2011-03-09 19:56:19.022 AO: Opening audio device 'CoreAudio:' ch 2(6) sr 48000 sf 32 bit floating point reenc 1 2011-03-09 19:56:19.848 AudioPlayer: Enabling Audio 2011-03-09 19:56:19.848 Dec: Selected track #1 in the Unknown language(0) 2011-03-09 19:56:19.848 Dec: Selected track #1 in the English language(6647399) 2011-03-09 19:56:19.848 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0) 2011-03-09 19:56:20.056 Dec: Position map filled from DB to: 107700 2011-03-09 19:56:20.057 Dec: SyncPositionMap prerecorded, from DB: 7181 entries 2011-03-09 19:56:20.057 Dec: SyncPositionMap, new totframes: 107700, new length: 3593, posMap size: 7181 2011-03-09 19:56:20.057 AFD: Position map found 2011-03-09 19:56:20.057 AFD: Successfully opened decoder for file: "myth://192.168.1.50:6543/2051_20110307220000.mpg". novideo(0) 2011-03-09 19:56:20.107 VideoOutput: Allowed renderers: quartz-blit,opengl 2011-03-09 19:56:20.107 VideoOutput: Allowed renderers (filt: ffmpeg): quartz-blit,opengl 2011-03-09 19:56:20.134 VDP: Ignoring profile item 253 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl)) 2011-03-09 19:56:20.134 VDP: Ignoring profile item 254 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl)) 2011-03-09 19:56:20.135 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt() 2011-03-09 19:56:20.135 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt() 2011-03-09 19:56:20.135 VDP: LoadBestPreferences(2048x2048, 0) 2011-03-09 19:56:20.135 VDP: LoadBestPreferences(2048x2048, 60) 2011-03-09 19:56:20.135 VDP: LoadBestPreferences(1920x1080, 60) 2011-03-09 19:56:20.135 VideoOutput: Preferred renderer: quartz-blit 2011-03-09 19:56:20.135 VideoOutput: Trying video renderer: 'quartz-blit' 2011-03-09 19:56:20.178 VDP: Ignoring profile item 253 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl)) 2011-03-09 19:56:20.178 VDP: Ignoring profile item 254 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl)) 2011-03-09 19:56:20.179 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt() 2011-03-09 19:56:20.179 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt() 2011-03-09 19:56:20.179 VDP: LoadBestPreferences(2048x2048, 0) 2011-03-09 19:56:20.179 VDP: LoadBestPreferences(2048x2048, 60) 2011-03-09 19:56:20.179 VideoOutWindow::SetPIPState. pip_state: 0] 2011-03-09 19:56:20.179 SetVideoFrameRate(29.97) - unimplemented? 2011-03-09 19:56:20.179 VideoOutputQuartz::Init(WxH 1920x1080, aspect=1.77778, winid=5480672 win_bounds(x 0, y0, WxH 1360x768), WId embedid=0) 2011-03-09 19:56:20.179 Display Rect left: 0, top: 96, width: 1360, height: 576, aspect: 1.33333 2011-03-09 19:56:20.179 Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778 2011-03-09 19:56:20.179 VDP: LoadBestPreferences(1920x1088, 60) 2011-03-09 19:56:20.179 VideoOutputQuartz::VideoAspectRatioChanged(aspect=1.77778) [was 1.3333] 2011-03-09 19:56:20.179 Display Rect left: 0, top: 96, width: 1360, height: 576, aspect: 1.33333 2011-03-09 19:56:20.179 Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778 2011-03-09 19:56:20.187 Screen size is 1100 x 620 (mm), aspect 1.77419 2011-03-09 19:56:20.187 Main window is 1100 x 620 (mm), aspect 1.77419 2011-03-09 19:56:20.187 VideoOutputQuartz::CreateQuartzBuffers() render: quartz-blit, allowed: quartz-blit 2011-03-09 19:56:20.187 VDP: SetVideoRenderer(quartz-blit) 2011-03-09 19:56:20.187 VDP: SetVideoRender(quartz-blit) == GetVideoRenderer() 2011-03-09 19:56:20.187 VideoOutputQuartz::VProf: rend(quartz-blit) osd(softblend) deint(linearblend,linearblend) filt() 2011-03-09 19:56:20.318 Main window: Viewport currently 0,0 -> 1360,768 2011-03-09 19:56:20.319 Main window: Viewport is 1360 x 768 2011-03-09 19:56:20.319 Main window: Image is 1920 x 1088 2011-03-09 19:56:20.319 Main window: Scaling to 0.708333 x 0.705882 of original 2011-03-09 19:56:20.319 Display Rect left: 0, top: 0, width: 1360, height: 768, aspect: 1.77778 2011-03-09 19:56:20.319 Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778 2011-03-09 19:56:20.319 Main window: Viewport is 1360 x 768 2011-03-09 19:56:20.319 Main window: Image is 1920 x 1088 2011-03-09 19:56:20.319 Main window: Scaling to 0.708333 x 0.705882 of original 2011-03-09 19:56:20.319 Over/underscan. V: 0, H: 0 2011-03-09 19:56:20.319 Display Rect left: 0, top: 0, width: 1360, height: 768, aspect: 1.77778 2011-03-09 19:56:20.319 Video Rect left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778 2011-03-09 19:56:20.319 Player(0): LoadFilters(''..) -> 0x0 2011-03-09 19:56:20.325 OSD: Base theme size: 1920x1080 2011-03-09 19:56:20.325 Player(0): Decoder thread starting. 2011-03-09 19:56:20.325 OSD: Scaling factors: 1x1 2011-03-09 19:56:20.335 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 132 Name: 'basenormal' Type: 'fontdef' 2011-03-09 19:56:20.338 OSD: Loaded window osd_message 2011-03-09 19:56:20.347 OSD: Loaded window osd_input 2011-03-09 19:56:20.354 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 196 Name: 'basesmall' Type: 'fontdef' 2011-03-09 19:56:20.356 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 229 Name: 'basesmalllightblue' Type: 'fontdef' 2011-03-09 19:56:20.379 Detect Letterbox: YV12 frame format detected 2011-03-09 19:56:20.379 Detect Letterbox: The source is already in widescreen (aspect: 1.77778) 2011-03-09 19:56:20.489 OSD: Loaded window program_info 2011-03-09 19:56:20.496 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 567 Name: 'basesmall' Type: 'fontdef' 2011-03-09 19:56:20.497 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 600 Name: 'basesmalllightblue' Type: 'fontdef' 2011-03-09 19:56:20.504 OSD: Loaded window browse_info 2011-03-09 19:56:20.510 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 685 Name: 'basenormal' Type: 'fontdef' 2011-03-09 19:56:20.514 OSD: Loaded window osd_status 2011-03-09 19:56:20.532 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 901 Name: 'basenormal' Type: 'fontdef' 2011-03-09 19:56:20.536 OSD: Loaded window osd_program_editor 2011-03-09 19:56:20.537 OSD: Loaded OSD: size 1920x1080 offset 0+0 2011-03-09 19:56:20.537 OSD: Base theme size: 1920x1080 2011-03-09 19:56:20.537 OSD: Scaling factors: 1x1 2011-03-09 19:56:20.537 Player(0): ClearAfterSeek(0) 2011-03-09 19:56:20.544 VDP: GetFilteredDeint() : quartz-blit -> 'linearblend' 2011-03-09 19:56:20.548 FilterManager: GetFilterInfo(convert) returning: 0x0 2011-03-09 19:56:20.548 FilterManager: GetFilterInfo(linearblend) returning: 0x21a7bee0 2011-03-09 19:56:20.549 VideoOutput: Using deinterlace method linearblend 2011-03-09 19:56:20.549 VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory 2011-03-09 19:56:20.556 Player(0): Video timing method: USleep with busy wait 2011-03-09 19:56:20.556 Player(0): Display Refresh Rate: 60.002 Video Frame Rate: 29.971 2011-03-09 19:56:20.556 playCtx: StartPlaying(): took 0 ms to start player. 2011-03-09 19:56:20.556 TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok 2011-03-09 19:56:20.558 TV: Changing from None to WatchingPreRecorded 2011-03-09 19:56:20.574 TV: DrawUnusedRects() -- begin 2011-03-09 19:56:20.574 TV: DrawUnusedRects() -- end 2011-03-09 19:56:20.575 TV: HandleStateChange(0) -- end 2011-03-09 19:56:20.575 TV: tv->Playback() -- end 2011-03-09 19:56:20.575 TV: StartTV -- process events begin 2011-03-09 19:56:20.577 Player(0): Enabled deinterlacing 2011-03-09 19:56:20.639 VideoOutput: Created YV12 OSD. 2011-03-09 19:56:20.791 Player(0): Video is 4.64545 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.796 Player(0): Video is 8.3618 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.805 Player(0): Video is 10.9018 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.809 Player(0): Video is 12.627 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.820 Player(0): Video is 13.6736 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.829 Player(0): Video is 14.2862 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.833 Player(0): Video is 14.5809 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.837 Player(0): Video is 14.5546 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.842 Player(0): Video is 14.3551 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.846 Player(0): Video is 13.9582 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.850 Player(0): Video is 13.4957 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.855 Player(0): Video is 12.894 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.859 Player(0): Video is 12.1955 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.863 Player(0): Video is 11.5068 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.876 Player(0): Video is 10.7355 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.882 Player(0): Video is 9.98475 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.887 Player(0): Video is 9.24933 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.893 Player(0): Video is 8.45049 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.896 Player(0): Video is 7.68654 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.900 Player(0): Video is 6.85881 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.904 Player(0): Video is 6.0657 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.908 Player(0): Video is 5.22361 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.911 Player(0): Video is 4.33729 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.912 Player(0): Waiting for video buffers... 2011-03-09 19:56:20.925 Player(0): Video is 3.5077 frames behind audio (too slow), dropping frame to catch up. 2011-03-09 19:56:20.925 Player(0): Waiting for video buffers... 'video_output' mean = '27359.98', std. dev. = '12960.71', fps = '36.55' 2011-03-09 19:56:25.121 AFD: EIA-708 caption service #1 is in the English language. 2011-03-09 19:56:25.121 AFD: EIA-608 caption service #1 is in the Undetermined language. 2011-03-09 19:56:25.142 Dec: Selected track #1 in the Undetermined language(7695972) 'video_output' mean = '33362.71', std. dev. = '235.93', fps = '29.97' 2011-03-09 19:56:27.860 AFD: EIA-708 caption service #1 is in the English language. 2011-03-09 19:56:27.861 AFD: EIA-608 caption service #1 is in the Undetermined language. 2011-03-09 19:56:27.861 AFD: EIA-708 caption service #2 is in the Undetermined language. 'video_output' mean = '33367.18', std. dev. = '709.24', fps = '29.97' 2011-03-09 19:56:32.994 AFD: EIA-708 caption service #1 is in the English language. 2011-03-09 19:56:32.994 AFD: EIA-608 caption service #1 is in the Undetermined language. 2011-03-09 19:56:32.994 AFD: EIA-608 caption service #3 is in the Undetermined language. 2011-03-09 19:56:32.994 AFD: EIA-708 caption service #2 is in the Undetermined language. 2011-03-09 19:56:33.350 Player(0): 400 interlaced frames seen. 'video_output' mean = '33370.01', std. dev. = '1640.16', fps = '29.97' 'video_output' mean = '33368.78', std. dev. = '224.06', fps = '29.97' 2011-03-09 19:56:37.160 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin 2011-03-09 19:56:37.160 TV: SetActive(0,w/o OSD) 0 -> 0 -- end 2011-03-09 19:56:37.192 TV: HandleStateChange(0) -- begin 2011-03-09 19:56:37.192 TV: Attempting to change from WatchingPreRecorded to None 2011-03-09 19:56:37.193 TV: StopStuff() for player ctx 0 -- begin 2011-03-09 19:56:37.193 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin 2011-03-09 19:56:37.193 TV: SetActive(0,w/o OSD) 0 -> 0 -- end 2011-03-09 19:56:37.193 Player(0): StopPlaying - begin 2011-03-09 19:56:37.205 Player(0): Decoder thread exiting. 2011-03-09 19:56:37.205 Player(0): Exited decoder loop. 2011-03-09 19:56:37.333 Player(0): StopPlaying - end 2011-03-09 19:56:37.333 TV: StopStuff(): stopping ring buffer 2011-03-09 19:56:37.333 TV: StopStuff(): stopping player 2011-03-09 19:56:37.333 TV: StopStuff() -- end 2011-03-09 19:56:37.333 TV: Changing from WatchingPreRecorded to None 2011-03-09 19:56:37.333 TV: HandleStateChange(0) -- end 2011-03-09 19:56:37.349 TV: StartTV -- process events end 2011-03-09 19:56:37.350 TV: StartTV -- process events 2 begin 2011-03-09 19:56:37.350 PreviewQueue: Requesting preview for '2051_20110307220000.mpg_0x0_-1s' 2011-03-09 19:56:37.351 PreviewQueue: Requested preview for '2051_20110307220000.mpg_0x0_-1s' 2011-03-09 19:56:37.352 TV: StartTV -- process events 2 end 2011-03-09 19:56:37.352 TV::~TV() -- begin 2011-03-09 19:56:37.362 TV::~TV() -- lock 2011-03-09 19:56:37.362 Player(0): StopPlaying - begin 2011-03-09 19:56:37.363 Player(0): Exited decoder loop. 2011-03-09 19:56:37.363 Player(0): StopPlaying - end 2011-03-09 19:56:37.383 TV::~TV() -- end 2011-03-09 19:56:37.388 TV: StartTV -- end