Ticket #5552: normal_playback.txt

File normal_playback.txt, 22.4 KB (added by scram69@…, 7 years ago)

normal playback

Line 
12011-03-06 10:37:03.985 MythFrontend version: fixes/0.24 [v0.24-198-g0d3d3a4-dirty] www.mythtv.org
2
3...
4
52011-03-06 10:37:24.698 TV: StartTV() -- begin
62011-03-06 10:37:24.698 TV: ctor -- begin
72011-03-06 10:37:24.706 TV: ctor -- end
82011-03-06 10:37:24.706 TV: Init -- begin
92011-03-06 10:37:24.729 TV: DrawUnusedRects() -- begin
102011-03-06 10:37:24.729 TV: DrawUnusedRects() -- end
112011-03-06 10:37:24.752 TV: Init -- end
122011-03-06 10:37:24.753 TV: tv->Playback() -- begin
132011-03-06 10:37:24.775 TV: HandleStateChange(0) -- begin
142011-03-06 10:37:24.775 TV: Attempting to change from None to WatchingPreRecorded
152011-03-06 10:37:24.791 RingBuf(myth://192.168.1.50:6543/2051_20110306055900.mpg): OpenFile(myth://192.168.1.50:6543/2051_20110306055900.mpg, 2000 ms)
162011-03-06 10:37:24.872 TV: StartPlayer(0, WatchingPreRecorded, main) -- begin
172011-03-06 10:37:24.872 TV: Elapsed time since TV constructor was called: 175 ms
182011-03-06 10:37:25.027 CoreAudioData::CoreAudioData - default device ID = 260
192011-03-06 10:37:25.027 AO: Sample rate 44100 is supported
202011-03-06 10:37:25.027 AO: Sample rate 48000 is supported
212011-03-06 10:37:25.027 AO: Sample rate 96000 is supported
222011-03-06 10:37:25.027 AudioOutputCA::Support 2 channels
232011-03-06 10:37:25.027 AO: 2 channel(s) are supported
242011-03-06 10:37:25.027 AO: AC3 or DTS capable
252011-03-06 10:37:25.027 AO: 6 channel(s) are supported
262011-03-06 10:37:25.038 AO: Killing AudioOutputDSP
272011-03-06 10:37:25.095 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
282011-03-06 10:37:25.096 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
292011-03-06 10:37:25.097 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
302011-03-06 10:37:25.098 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
312011-03-06 10:37:25.115 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
322011-03-06 10:37:25.115 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
332011-03-06 10:37:25.133 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
342011-03-06 10:37:25.150 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
352011-03-06 10:37:25.151 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
362011-03-06 10:37:25.168 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
372011-03-06 10:37:25.169 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
382011-03-06 10:37:25.186 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
392011-03-06 10:37:25.186 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
402011-03-06 10:37:25.204 [mpeg2video @ 0x8a45a640]mpeg_decode_postinit() failure
412011-03-06 10:37:25.676 [mpegts @ 0x8aa81240]Estimating duration from bitrate, this may be inaccurate
422011-03-06 10:37:25.677 [NULL @ 0x8a45a640]start time is not set in av_estimate_timings_from_pts
432011-03-06 10:37:25.936 AFD: Stream #0, has id 0x840 codec id MPEG2VIDEO, type Video, bitrate 20000000 at 0x15e3dac0
442011-03-06 10:37:25.970 VDP: Ignoring profile item 253 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl))
452011-03-06 10:37:25.970 VDP: Ignoring profile item 254 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl))
462011-03-06 10:37:25.971 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
472011-03-06 10:37:25.971 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
482011-03-06 10:37:25.971 VDP: LoadBestPreferences(2048x2048, 0)
492011-03-06 10:37:25.971 VDP: LoadBestPreferences(2048x2048, 60)
502011-03-06 10:37:25.971 VDP: LoadBestPreferences(1920x1080, 60)
512011-03-06 10:37:25.972 AFD: Using 1 CPUs for decoding
522011-03-06 10:37:25.972 AFD: InitVideoCodec() 0xb62200 id(MPEG2VIDEO) type (Video).
532011-03-06 10:37:25.972 AFD: Selected FPS is 29.97 (avg 34.3559 stream 29.97 container 90000 estimated 29.97)
542011-03-06 10:37:25.972 Player(0): detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan
552011-03-06 10:37:25.973 AFD: EIA-708 caption service #1 is in the English language.
562011-03-06 10:37:25.973 AFD: Using ffmpeg for video decoding
572011-03-06 10:37:25.973 AFD: Looking for decoder for MPEG2VIDEO
582011-03-06 10:37:25.973 AFD: Opened codec 0xb62200, id(MPEG2VIDEO) type(Video)
592011-03-06 10:37:25.973 AFD: Stream #1, has id 0x841 codec id AC3, type Audio, bitrate 384000 at 0x22388950
602011-03-06 10:37:25.973 AFD: codec AC3 has 6 channels
612011-03-06 10:37:25.973 AFD: Looking for decoder for AC3
622011-03-06 10:37:25.973 AFD: Opened codec 0xa46800, id(AC3) type(Audio)
632011-03-06 10:37:25.973 AFD: Audio Track #1 is A/V stream #1 and has 6 channels in the English language(6647399).
642011-03-06 10:37:25.973 AFD: Stream #2, has id 0x842 codec id AC3, type Audio, bitrate 192000 at 0x22388b20
652011-03-06 10:37:25.973 AFD: codec AC3 has 1 channels
662011-03-06 10:37:25.973 AFD: Looking for decoder for AC3
672011-03-06 10:37:25.974 AFD: Opened codec 0xa42e00, id(AC3) type(Audio)
682011-03-06 10:37:25.974 AFD: Audio Track #2 is A/V stream #2 and has 1 channels in the English language(6647399).
692011-03-06 10:37:25.974 AFD: Stream #3, has id 0x843 codec id DVB_VBI, type Data, bitrate 0 at 0x2237fe90
702011-03-06 10:37:25.974 AFD: data codec (Data)
712011-03-06 10:37:26.000 AFD: Trying to select audio track (w/lang)
722011-03-06 10:37:26.003 AFD: Selected track 1: English AC3 5.1ch (A/V Stream #1)
732011-03-06 10:37:26.003 AFD: Initializing audio parms from audio track #1
742011-03-06 10:37:26.003 AFD: Audio format changed
75                        from id(NONE)     -1Hz -1ch -1bps     to id( AC3)  48000Hz  6ch 16bps   
762011-03-06 10:37:26.004 AO: Killing AudioOutputDSP
772011-03-06 10:37:26.121 CoreAudioData::CoreAudioData - default device ID = 260
782011-03-06 10:37:26.122 AO: Sample rate 44100 is supported
792011-03-06 10:37:26.122 AO: Sample rate 48000 is supported
802011-03-06 10:37:26.122 AO: Sample rate 96000 is supported
812011-03-06 10:37:26.122 AudioOutputCA::Support 2 channels
822011-03-06 10:37:26.122 AO: 2 channel(s) are supported
832011-03-06 10:37:26.122 AO: AC3 or DTS capable
842011-03-06 10:37:26.122 AO: 6 channel(s) are supported
852011-03-06 10:37:26.122 AO: Killing AudioOutputDSP
862011-03-06 10:37:26.122 AO: Original codec was AC3, signed 16 bit, 48 kHz, 6 channels
872011-03-06 10:37:26.123 AO: enc(1), passthru(0), canAC3(1), canDTS(1), canLPCM(0), configured_channels(6), 6 channels supported(1)
882011-03-06 10:37:26.123 AO: Reencoding decoded AC-3/DTS to AC-3
892011-03-06 10:37:26.123 AO: Creating AC-3 Encoder with sr = 48000, ch = 6
902011-03-06 10:37:26.123 DEnc: Init codecid=AC3, br=448000, sr=48000, ch=6
912011-03-06 10:37:26.123 [ac3 @ 0x8a45a640]No channel layout specified. The encoder will guess the layout, but it might be incorrect.
922011-03-06 10:37:26.124 DigitalEncoder::Init fs=1536, bpf=12 ofb=18432
932011-03-06 10:37:26.124 AO: Audio processing enabled
942011-03-06 10:37:26.124 AO: Opening audio device 'CoreAudio:' ch 2(6) sr 48000 sf signed 16 bit reenc 1
952011-03-06 10:37:26.124 CoreAudioData::FindAC3Stream - found digital format
962011-03-06 10:37:26.124 AudioOutputCA::OpenDevice() Trying Digital.
972011-03-06 10:37:26.129 CoreAudioData::OpenSPDIF - found digital format
982011-03-06 10:37:26.129 CoreAudioData::AudioStreamChangeFormat(261)
992011-03-06 10:37:26.453 AO: Software volume enabled
1002011-03-06 10:37:26.462 AO: Audio fragment size: 0
1012011-03-06 10:37:26.462 AO: Audio Stretch Factor: 1
1022011-03-06 10:37:26.462 AO: Ending Reconfigure()
1032011-03-06 10:37:26.462 AudioPlayer: Enabling Audio
1042011-03-06 10:37:26.462 AO: Reconfigure(): No change -> exiting
1052011-03-06 10:37:26.462 Dec: Selected track #1 in the Unknown language(0)
1062011-03-06 10:37:26.462 Dec: Selected track #1 in the English language(6647399)
1072011-03-06 10:37:26.462 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1082011-03-06 10:37:26.519 Dec: Position map filled from DB to: 163545
1092011-03-06 10:37:26.521 Dec: SyncPositionMap prerecorded, from DB: 10904 entries
1102011-03-06 10:37:26.521 Dec: SyncPositionMap, new totframes: 163545, new length: 5456, posMap size: 10904
1112011-03-06 10:37:26.522 AFD: Position map found
1122011-03-06 10:37:26.522 AFD: Successfully opened decoder for file: "myth://192.168.1.50:6543/2051_20110306055900.mpg". novideo(0)
1132011-03-06 10:37:26.535 VideoOutput: Allowed renderers: quartz-blit,opengl
1142011-03-06 10:37:26.535 VideoOutput: Allowed renderers (filt: ffmpeg): quartz-blit,opengl
1152011-03-06 10:37:26.542 VDP: Ignoring profile item 253 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl))
1162011-03-06 10:37:26.542 VDP: Ignoring profile item 254 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl))
1172011-03-06 10:37:26.543 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
1182011-03-06 10:37:26.543 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
1192011-03-06 10:37:26.543 VDP: LoadBestPreferences(2048x2048, 0)
1202011-03-06 10:37:26.543 VDP: LoadBestPreferences(2048x2048, 60)
1212011-03-06 10:37:26.543 VDP: LoadBestPreferences(1920x1080, 60)
1222011-03-06 10:37:26.543 VideoOutput: Preferred renderer: quartz-blit
1232011-03-06 10:37:26.543 VideoOutput: Trying video renderer: 'quartz-blit'
1242011-03-06 10:37:26.603 VDP: Ignoring profile item 253 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl))
1252011-03-06 10:37:26.604 VDP: Ignoring profile item 254 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,quartz-blit,opengl))
1262011-03-06 10:37:26.604 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
1272011-03-06 10:37:26.604 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
1282011-03-06 10:37:26.604 VDP: LoadBestPreferences(2048x2048, 0)
1292011-03-06 10:37:26.604 VDP: LoadBestPreferences(2048x2048, 60)
1302011-03-06 10:37:26.604 VideoOutWindow::SetPIPState. pip_state: 0]
1312011-03-06 10:37:26.604 SetVideoFrameRate(29.97) - unimplemented?
1322011-03-06 10:37:26.605 VideoOutputQuartz::Init(WxH 1920x1080, aspect=1.77778, winid=5556144
133                        win_bounds(x 0, y0, WxH 1360x768), WId embedid=0)
1342011-03-06 10:37:26.605 Display Rect  left: 0, top: 96, width: 1360, height: 576, aspect: 1.33333
1352011-03-06 10:37:26.605 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1362011-03-06 10:37:26.605 VDP: LoadBestPreferences(1920x1088, 60)
1372011-03-06 10:37:26.605 VideoOutputQuartz::VideoAspectRatioChanged(aspect=1.77778) [was 1.3333]
1382011-03-06 10:37:26.605 Display Rect  left: 0, top: 96, width: 1360, height: 576, aspect: 1.33333
1392011-03-06 10:37:26.605 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1402011-03-06 10:37:26.622 Screen size is 1100 x 620 (mm), aspect 1.77419
1412011-03-06 10:37:26.629 Main window is 1100 x 620 (mm), aspect 1.77419
1422011-03-06 10:37:26.629 VideoOutputQuartz::CreateQuartzBuffers() render: quartz-blit, allowed: quartz-blit
1432011-03-06 10:37:26.629 VDP: SetVideoRenderer(quartz-blit)
1442011-03-06 10:37:26.629 VDP: SetVideoRender(quartz-blit) == GetVideoRenderer()
1452011-03-06 10:37:26.629 VideoOutputQuartz::VProf: rend(quartz-blit) osd(softblend) deint(linearblend,linearblend) filt()
1462011-03-06 10:37:26.762 Main window: Viewport currently 0,0 -> 1360,768
1472011-03-06 10:37:26.770 Main window: Viewport is 1360 x 768
1482011-03-06 10:37:26.770 Main window: Image is 1920 x 1088
1492011-03-06 10:37:26.770 Main window: Scaling to 0.708333 x 0.705882 of original
1502011-03-06 10:37:26.770 Display Rect  left: 0, top: 0, width: 1360, height: 768, aspect: 1.77778
1512011-03-06 10:37:26.770 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1522011-03-06 10:37:26.770 Main window: Viewport is 1360 x 768
1532011-03-06 10:37:26.770 Main window: Image is 1920 x 1088
1542011-03-06 10:37:26.770 Main window: Scaling to 0.708333 x 0.705882 of original
1552011-03-06 10:37:26.776 Over/underscan. V: 0, H: 0
1562011-03-06 10:37:26.776 Display Rect  left: 0, top: 0, width: 1360, height: 768, aspect: 1.77778
1572011-03-06 10:37:26.776 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1582011-03-06 10:37:26.776 Player(0): LoadFilters(''..) -> 0x0
1592011-03-06 10:37:26.781 Player(0): Decoder thread starting.
1602011-03-06 10:37:26.781 AFD: DoFastForward(11293 (0), do discard frames)
1612011-03-06 10:37:26.781 Dec: DoFastForward(11293 (0), do discard frames)
1622011-03-06 10:37:26.782 Dec: FindPosition(11293, search not adjusted) -->
163                        [752:11280(714764532),753:11295(715830868)]
1642011-03-06 10:37:26.783 AFD: SeekReset(11295, 0, do flush, do discard)
1652011-03-06 10:37:26.783 AFD: SeekReset() flushing
1662011-03-06 10:37:26.783 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
1672011-03-06 10:37:26.783 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1682011-03-06 10:37:26.783 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
1692011-03-06 10:37:26.784 Player(0): ClearAfterSeek(0)
1702011-03-06 10:37:26.810 OSD: Base theme size: 1920x1080
1712011-03-06 10:37:26.810 OSD: Scaling factors: 1x1
1722011-03-06 10:37:26.819 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead
173                        Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 132
174                        Name: 'basenormal'      Type: 'fontdef'
1752011-03-06 10:37:26.822 OSD: Loaded window osd_message
1762011-03-06 10:37:26.831 OSD: Loaded window osd_input
1772011-03-06 10:37:26.841 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead
178                        Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 196
179                        Name: 'basesmall'       Type: 'fontdef'
1802011-03-06 10:37:26.844 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead
181                        Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 229
182                        Name: 'basesmalllightblue'      Type: 'fontdef'
1832011-03-06 10:37:26.850 [mpeg2video @ 0x8a45a640]warning: first frame is no keyframe
1842011-03-06 10:37:26.861 Detect Letterbox: YV12 frame format detected
1852011-03-06 10:37:26.861 Detect Letterbox: The source is already in widescreen (aspect: 1.77778)
1862011-03-06 10:37:26.902 OSD: Loaded window program_info
1872011-03-06 10:37:26.908 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead
188                        Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 567
189                        Name: 'basesmall'       Type: 'fontdef'
1902011-03-06 10:37:26.908 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead
191                        Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 600
192                        Name: 'basesmalllightblue'      Type: 'fontdef'
1932011-03-06 10:37:26.943 OSD: Loaded window browse_info
1942011-03-06 10:37:26.949 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead
195                        Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 685
196                        Name: 'basenormal'      Type: 'fontdef'
1972011-03-06 10:37:26.952 OSD: Loaded window osd_status
1982011-03-06 10:37:26.958 MythFontProperties, Error: Failed to load 'CartoGothic Std', got 'Arial' instead
199                        Location: /Users/admin/.mythtv/themes/Arclight/osd.xml @ 901
200                        Name: 'basenormal'      Type: 'fontdef'
2012011-03-06 10:37:26.964 OSD: Loaded window osd_program_editor
2022011-03-06 10:37:26.964 OSD: Loaded OSD: size 1920x1080 offset 0+0
2032011-03-06 10:37:26.964 OSD: Base theme size: 1920x1080
2042011-03-06 10:37:26.964 OSD: Scaling factors: 1x1
2052011-03-06 10:37:27.018 Player(0): ClearAfterSeek(0)
2062011-03-06 10:37:27.026 VDP: GetFilteredDeint() : quartz-blit -> 'linearblend'
2072011-03-06 10:37:27.030 FilterManager: GetFilterInfo(convert) returning: 0x0
2082011-03-06 10:37:27.030 FilterManager: GetFilterInfo(linearblend) returning: 0x224118d0
2092011-03-06 10:37:27.030 VideoOutput: Using deinterlace method linearblend
2102011-03-06 10:37:27.030 VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2112011-03-06 10:37:27.036 AFD: EIA-708 caption service #1 is in the English language.
2122011-03-06 10:37:27.036 AFD: EIA-708 caption service #2 is in the Undetermined language.
2132011-03-06 10:37:27.039 Player(0): Video timing method: USleep with busy wait
2142011-03-06 10:37:27.039 Player(0): Display Refresh Rate: 60.002 Video Frame Rate: 29.971
2152011-03-06 10:37:27.039 playCtx: StartPlaying(): took 0 ms to start player.
2162011-03-06 10:37:27.039 TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok
2172011-03-06 10:37:27.044 TV: Changing from None to WatchingPreRecorded
2182011-03-06 10:37:27.058 TV: DrawUnusedRects() -- begin
2192011-03-06 10:37:27.058 TV: DrawUnusedRects() -- end
2202011-03-06 10:37:27.058 TV: HandleStateChange(0) -- end
2212011-03-06 10:37:27.058 TV: tv->Playback() -- end
2222011-03-06 10:37:27.058 TV: StartTV -- process events begin
2232011-03-06 10:37:27.060 Player(0): Waiting for video buffers...
2242011-03-06 10:37:27.060 AO: Pause 1
2252011-03-06 10:37:27.148 PreviewQueue: Requesting preview for '2051_20110306055900.mpg_0x0_-1s'
2262011-03-06 10:37:27.148 PreviewQueue: Requested preview for '2051_20110306055900.mpg_0x0_-1s'
2272011-03-06 10:37:27.162 AFD: EIA-708 caption service #1 is in the English language.
2282011-03-06 10:37:27.162 AFD: EIA-608 caption service #3 is in the Undetermined language.
2292011-03-06 10:37:27.162 AFD: EIA-708 caption service #2 is in the Undetermined language.
2302011-03-06 10:37:27.163 Dec: Selected track #1 in the Undetermined language(7695972)
2312011-03-06 10:37:27.189 AFD: EIA-708 caption service #1 is in the English language.
2322011-03-06 10:37:27.189 AFD: EIA-608 caption service #1 is in the Undetermined language.
2332011-03-06 10:37:27.189 AFD: EIA-608 caption service #3 is in the Undetermined language.
2342011-03-06 10:37:27.189 AFD: EIA-708 caption service #2 is in the Undetermined language.
2352011-03-06 10:37:27.190 PreviewQueue: Requesting preview for '2051_20110306055900.mpg_0x0_-1s'
2362011-03-06 10:37:27.190 PreviewQueue: Requested preview for '2051_20110306055900.mpg_0x0_-1s'
2372011-03-06 10:37:27.199 AO: Pause 0
2382011-03-06 10:37:27.199 Player(0): progressive frame seen after 2 interlaced  frames
2392011-03-06 10:37:27.201 PreviewQueue: Requesting preview for '2051_20110306055900.mpg_0x0_-1s'
2402011-03-06 10:37:27.201 PreviewQueue: Requested preview for '2051_20110306055900.mpg_0x0_-1s'
2412011-03-06 10:37:27.259 VideoOutput: Created YV12 OSD.
2422011-03-06 10:37:27.380 Player(0): interlaced frame seen after 1 progressive frames
2432011-03-06 10:37:27.380 Player(0): Locking scan to Interlaced.
2442011-03-06 10:37:27.380 Player(0): Enabled deinterlacing
2452011-03-06 10:37:27.385 Player(0): Video is 3.22934 frames behind audio (too slow), dropping frame to catch up.
2462011-03-06 10:37:27.389 Player(0): Video is 6.31068 frames behind audio (too slow), dropping frame to catch up.
2472011-03-06 10:37:27.394 Player(0): Video is 8.37442 frames behind audio (too slow), dropping frame to catch up.
2482011-03-06 10:37:27.451 Player(0): Video is 9.67497 frames behind audio (too slow), dropping frame to catch up.
2492011-03-06 10:37:27.455 Player(0): Video is 10.4781 frames behind audio (too slow), dropping frame to catch up.
2502011-03-06 10:37:27.459 Player(0): Video is 10.8331 frames behind audio (too slow), dropping frame to catch up.
2512011-03-06 10:37:27.463 Player(0): Video is 10.8447 frames behind audio (too slow), dropping frame to catch up.
2522011-03-06 10:37:27.467 Player(0): Video is 10.8458 frames behind audio (too slow), dropping frame to catch up.
2532011-03-06 10:37:27.472 Player(0): Video is 10.5994 frames behind audio (too slow), dropping frame to catch up.
2542011-03-06 10:37:27.476 Player(0): Video is 10.1599 frames behind audio (too slow), dropping frame to catch up.
2552011-03-06 10:37:27.480 Player(0): Video is 9.58299 frames behind audio (too slow), dropping frame to catch up.
2562011-03-06 10:37:27.484 Player(0): Video is 8.90304 frames behind audio (too slow), dropping frame to catch up.
2572011-03-06 10:37:27.488 Player(0): Video is 8.13834 frames behind audio (too slow), dropping frame to catch up.
2582011-03-06 10:37:27.492 Player(0): Video is 7.31757 frames behind audio (too slow), dropping frame to catch up.
2592011-03-06 10:37:27.495 Player(0): Video is 6.45471 frames behind audio (too slow), dropping frame to catch up.
2602011-03-06 10:37:27.499 Player(0): Video is 5.7926 frames behind audio (too slow), dropping frame to catch up.
2612011-03-06 10:37:27.503 Player(0): Video is 5.04876 frames behind audio (too slow), dropping frame to catch up.
2622011-03-06 10:37:27.508 Player(0): Video is 4.23611 frames behind audio (too slow), dropping frame to catch up.
2632011-03-06 10:37:27.512 Player(0): Video is 3.37937 frames behind audio (too slow), dropping frame to catch up.
2642011-03-06 10:37:27.604 PreviewQueue: Requesting preview for '2051_20110306055900.mpg_0x0_-1s'
2652011-03-06 10:37:27.604 PreviewQueue: Requested preview for '2051_20110306055900.mpg_0x0_-1s'
266'video_output' mean = '28361.84', std. dev. = '13209.65', fps = '35.26'
2672011-03-06 10:37:32.563 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2682011-03-06 10:37:32.563 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2692011-03-06 10:37:32.619 TV: HandleStateChange(0) -- begin
2702011-03-06 10:37:32.619 TV: Attempting to change from WatchingPreRecorded to None
2712011-03-06 10:37:32.619 TV: StopStuff() for player ctx 0 -- begin
2722011-03-06 10:37:32.620 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2732011-03-06 10:37:32.620 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2742011-03-06 10:37:32.620 Player(0): StopPlaying - begin
2752011-03-06 10:37:32.630 Player(0): Decoder thread exiting.
2762011-03-06 10:37:32.630 Player(0): Exited decoder loop.
2772011-03-06 10:37:32.716 AO: Killing AudioOutputDSP
2782011-03-06 10:37:32.717 CoreAudioData::AudioStreamChangeFormat(261)
2792011-03-06 10:37:33.029 Player(0): StopPlaying - end
2802011-03-06 10:37:33.029 TV: StopStuff(): stopping ring buffer
2812011-03-06 10:37:33.029 TV: StopStuff(): stopping player
2822011-03-06 10:37:33.029 TV: StopStuff() -- end
2832011-03-06 10:37:33.029 TV: Changing from WatchingPreRecorded to None
2842011-03-06 10:37:33.029 TV: HandleStateChange(0) -- end
2852011-03-06 10:37:33.029 TV: StartTV -- process events end
2862011-03-06 10:37:33.030 TV: StartTV -- process events 2 begin
2872011-03-06 10:37:33.048 TV: StartTV -- process events 2 end
2882011-03-06 10:37:33.048 TV::~TV() -- begin
2892011-03-06 10:37:33.048 TV::~TV() -- lock
2902011-03-06 10:37:33.049 Player(0): StopPlaying - begin
2912011-03-06 10:37:33.049 Player(0): Exited decoder loop.
2922011-03-06 10:37:33.049 Player(0): StopPlaying - end
2932011-03-06 10:37:33.049 PreviewQueue: Requesting preview for '2051_20110306055900.mpg_0x0_-1s'
2942011-03-06 10:37:33.049 PreviewQueue: Requested preview for '2051_20110306055900.mpg_0x0_-1s'
2952011-03-06 10:37:33.059 TV::~TV() -- end
2962011-03-06 10:37:33.060 TV: StartTV -- end
2972