Ticket #5552: mfe022goodvideo.txt

File mfe022goodvideo.txt, 13.7 KB (added by scram69@…, 8 years ago)

normal 0.22 AC3 passthrough playback

Line 
12009-11-17 20:38:29.299 TV: StartTV() -- begin
22009-11-17 20:38:29.299 TV: ctor
32009-11-17 20:38:29.430 TV: DrawUnusedRects() -- begin
42009-11-17 20:38:29.430 TV: DrawUnusedRects() -- end
52009-11-17 20:38:29.447 TV: DrawUnusedRects() -- begin
62009-11-17 20:38:29.447 TV: DrawUnusedRects() -- end
72009-11-17 20:38:29.511 TV: tv->Playback() -- begin
82009-11-17 20:38:29.530 TV: tv->Playback() -- end
92009-11-17 20:38:29.530 TV: StartTV -- process events begin
102009-11-17 20:38:29.571 TV: HandleStateChange(0) -- begin
112009-11-17 20:38:29.571 TV: Attempting to change from None to Watching WatchingPreRecorded
122009-11-17 20:38:29.586 RingBuf(myth://192.168.1.50:6543/2091_20091117150000.mpg): OpenFile(myth://192.168.1.50:6543/2091_20091117150000.mpg, 12)
132009-11-17 20:38:29.626 RingBuf(myth://192.168.1.50:6543/2091_20091117150000.mpg): CalcReadAheadThresh(0 KB)
14                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
152009-11-17 20:38:29.676 TV: StartPlayer(0, Watching WatchingPreRecorded, main) -- begin
162009-11-17 20:38:30.427 AFD: Stream #0, has id 0x800 codec id MPEG2VIDEO, type Video, bitrate 38810400 at 0x14fd71e0
172009-11-17 20:38:30.432 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(kerneldeint,linearblend) filt()
182009-11-17 20:38:30.432 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(kerneldeint,linearblend) filt()
192009-11-17 20:38:30.432 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(onefield,onefield) filt()
202009-11-17 20:38:30.432 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
212009-11-17 20:38:30.433 VDP: LoadBestPreferences(2048x2048, 0)
222009-11-17 20:38:30.433 VDP: LoadBestPreferences(2048x2048, 60)
232009-11-17 20:38:30.433 VDP: LoadBestPreferences(1920x1080, 60)
242009-11-17 20:38:30.433 Using 2 CPUs for decoding
252009-11-17 20:38:30.433 AFD: InitVideoCodec() 0x901800 id(MPEG2VIDEO) type (Video).
262009-11-17 20:38:30.433 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan
272009-11-17 20:38:30.433 AFD: Using ffmpeg for video decoding
282009-11-17 20:38:30.433 AFD: Looking for decoder for MPEG2VIDEO
292009-11-17 20:38:30.433 AFD: Opened codec 0x901800, id(MPEG2VIDEO) type(Video)
302009-11-17 20:38:30.433 AFD: Stream #1, has id 0x801 codec id AC3, type Audio, bitrate 384000 at 0x165621c0
312009-11-17 20:38:30.433 AFD: codec AC3 has 6 channels
322009-11-17 20:38:30.433 AFD: Looking for decoder for AC3
332009-11-17 20:38:30.434 AFD: Opened codec 0x906a00, id(AC3) type(Audio)
342009-11-17 20:38:30.434 AFD: Audio Track #1 is A/V stream #1 and has 6 channels in the English language(6647399).
352009-11-17 20:38:30.434 AFD: Stream #2, has id 0x802 codec id AC3, type Audio, bitrate 192000 at 0x165660c0
362009-11-17 20:38:30.434 AFD: codec AC3 has 2 channels
372009-11-17 20:38:30.434 AFD: Looking for decoder for AC3
382009-11-17 20:38:30.435 AFD: Opened codec 0x906e00, id(AC3) type(Audio)
392009-11-17 20:38:30.435 AFD: Audio Track #2 is A/V stream #2 and has 2 channels in the French language(6713957).
402009-11-17 20:38:30.435 AFD: Stream #3, has id 0x803 codec id AC3, type Audio, bitrate 192000 at 0x16566280
412009-11-17 20:38:30.435 AFD: codec AC3 has 2 channels
422009-11-17 20:38:30.435 AFD: Looking for decoder for AC3
432009-11-17 20:38:30.435 AFD: Opened codec 0x902c00, id(AC3) type(Audio)
442009-11-17 20:38:30.435 AFD: Audio Track #3 is A/V stream #3 and has 2 channels in the Unknown language(6648684).
452009-11-17 20:38:30.435 RingBuf(myth://192.168.1.50:6543/2091_20091117150000.mpg): CalcReadAheadThresh(0 KB)
46                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
472009-11-17 20:38:30.436 AFD: Trying to select audio track (w/lang)
482009-11-17 20:38:30.436 AFD: Trying to select audio track (wo/lang)
492009-11-17 20:38:30.436 AFD: Selected track 1: English AC3 5.1ch (A/V Stream #1)
502009-11-17 20:38:30.436 AFD: Initializing audio parms from audio track #1
512009-11-17 20:38:30.436 AFD: Audio format changed digital passthrough id( AC3) 48000Hz  2ch  16bps pt
52                        from id(NONE)    -1Hz -1ch   0bps    ; id(NONE)    -1Hz -1ch   0bps   
53                        to   id( AC3) 48000Hz  6ch  16bps pt ; id( AC3) 48000Hz  2ch  16bps pt
542009-11-17 20:38:30.441 CoreAudioData::CoreAudioData - default device ID = 260
552009-11-17 20:38:30.442 AO: Killing AudioOutputDSP
562009-11-17 20:38:30.442 Opening audio device 'CoreAudio:'. ch 2(2) sr 48000
572009-11-17 20:38:30.442 CoreAudioData::FindAC3Stream - found digital format
582009-11-17 20:38:30.442 AudioOutputCA::OpenDevice() Trying Digital.
592009-11-17 20:38:30.447 CoreAudioData::OpenSPDIF - found digital format
602009-11-17 20:38:30.448 CoreAudioData::AudioStreamChangeFormat(261)
612009-11-17 20:38:30.791 AO: Audio fragment size: 0
622009-11-17 20:38:30.792 AO: Audio Stretch Factor: 1
632009-11-17 20:38:30.792 Audio Codec Used: not set
642009-11-17 20:38:30.793 AO: Ending reconfigure
652009-11-17 20:38:30.793 AO: Killing AudioOutputDSP
662009-11-17 20:38:30.793 CoreAudioData::AudioStreamChangeFormat(261)
672009-11-17 20:38:31.118 Opening audio device 'CoreAudio:'. ch 2(2) sr 48000
682009-11-17 20:38:31.118 CoreAudioData::FindAC3Stream - found digital format
692009-11-17 20:38:31.118 AudioOutputCA::OpenDevice() Trying Digital.
702009-11-17 20:38:31.123 CoreAudioData::AudioStreamChangeFormat(261)
712009-11-17 20:38:31.448 AO: Audio fragment size: 0
722009-11-17 20:38:31.457 AO: Audio Stretch Factor: 1
732009-11-17 20:38:31.457 Audio Codec Used: AC3
742009-11-17 20:38:31.458 AO: Ending reconfigure
752009-11-17 20:38:31.458 Dec: Trying to select track (w/lang)
762009-11-17 20:38:31.458 Dec: Selecting first track
772009-11-17 20:38:31.458 Dec: Selected track #1 in the Unknown language(0)
782009-11-17 20:38:31.458 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
792009-11-17 20:38:31.669 Position map filled from DB to: 107670
802009-11-17 20:38:31.671 Dec: SyncPositionMap prerecorded, from DB: 7179 entries
812009-11-17 20:38:31.671 Dec: SyncPositionMap, new totframes: 107670, new length: 3592, posMap size: 7179
822009-11-17 20:38:31.671 AFD: Position map found
832009-11-17 20:38:31.671 AFD: Successfully opened decoder for file: "myth://192.168.1.50:6543/2091_20091117150000.mpg". novideo(0)
842009-11-17 20:38:31.691 AO: no change exiting
852009-11-17 20:38:31.692 AO: Pause 1
862009-11-17 20:38:31.692 VideoOutput: Allowed renderers: quartz-blit,opengl
872009-11-17 20:38:31.692 VideoOutput: Allowed renderers (filt: ffmpeg): quartz-blit,opengl
882009-11-17 20:38:31.697 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(kerneldeint,linearblend) filt()
892009-11-17 20:38:31.697 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(kerneldeint,linearblend) filt()
902009-11-17 20:38:31.697 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(onefield,onefield) filt()
912009-11-17 20:38:31.697 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
922009-11-17 20:38:31.697 VDP: LoadBestPreferences(2048x2048, 0)
932009-11-17 20:38:31.697 VDP: LoadBestPreferences(2048x2048, 60)
942009-11-17 20:38:31.697 VDP: LoadBestPreferences(1920x1080, 60)
952009-11-17 20:38:31.697 VideoOutput: Preferred renderer: quartz-blit
962009-11-17 20:38:31.697 VideoOutput: Trying video renderer: 'quartz-blit'
972009-11-17 20:38:31.732 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(kerneldeint,linearblend) filt()
982009-11-17 20:38:31.732 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(2) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(kerneldeint,linearblend) filt()
992009-11-17 20:38:31.732 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(onefield,onefield) filt()
1002009-11-17 20:38:31.732 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
1012009-11-17 20:38:31.732 VDP: LoadBestPreferences(2048x2048, 0)
1022009-11-17 20:38:31.732 VDP: LoadBestPreferences(2048x2048, 60)
1032009-11-17 20:38:31.755 VDP: SetVideoRenderer(quartz-blit)
1042009-11-17 20:38:31.755 VDP: SetVideoRender(quartz-blit) == GetVideoRenderer()
1052009-11-17 20:38:31.755 VideoOutWindow::SetPIPState. pip_state: 0]
1062009-11-17 20:38:31.755 VideoOutputQuartz::Init(WxH 1920x1080, aspect=1.77778, winid=6768720
107                        win_bounds(x 0, y0, WxH 1360x768), WId embedid=0)
1082009-11-17 20:38:31.755 Display Rect  left: 0, top: 96, width: 1360, height: 576, aspect: 1.33333
1092009-11-17 20:38:31.755 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1102009-11-17 20:38:31.755 VDP: LoadBestPreferences(1920x1088, 60)
1112009-11-17 20:38:31.755 VideoOutputQuartz::VideoAspectRatioChanged(aspect=1.77778) [was 1.3333]
1122009-11-17 20:38:31.755 Display Rect  left: 0, top: 96, width: 1360, height: 576, aspect: 1.33333
1132009-11-17 20:38:31.755 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1142009-11-17 20:38:31.758 Screen size is 1100 x 620 (mm), aspect 1.77419
1152009-11-17 20:38:31.777 Main window is 1100 x 620 (mm), aspect 1.77419
1162009-11-17 20:38:31.777 VideoOutputQuartz::CreateQuartzBuffers() render: quartz-blit, allowed: quartz-blit
1172009-11-17 20:38:31.777 VDP: SetVideoRenderer(quartz-blit)
1182009-11-17 20:38:31.777 VDP: SetVideoRender(quartz-blit) == GetVideoRenderer()
1192009-11-17 20:38:31.777 VideoOutputQuartz::VProf: rend(quartz-blit) osd(softblend) deint(kerneldeint,linearblend) filt()
1202009-11-17 20:38:31.951 Main window: Viewport currently 0,0 -> 1360,768
1212009-11-17 20:38:31.952 Main window: Viewport is 1360 x 768
1222009-11-17 20:38:31.952 Main window: Image is 1920 x 1088
1232009-11-17 20:38:31.952 Main window: Scaling to 0.708333 x 0.705882 of original
1242009-11-17 20:38:31.952 Display Rect  left: 0, top: 0, width: 1360, height: 768, aspect: 1.77778
1252009-11-17 20:38:31.952 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
1262009-11-17 20:38:31.952 Main window: Viewport is 1360 x 768
1272009-11-17 20:38:31.952 Main window: Image is 1920 x 1088
1282009-11-17 20:38:31.952 Main window: Scaling to 0.708333 x 0.705882 of original
1292009-11-17 20:38:31.955 Over/underscan. V: 0.01, H: 0.01
1302009-11-17 20:38:31.955 Display Rect  left: 0, top: 0, width: 1360, height: 768, aspect: 1.77778
1312009-11-17 20:38:31.955 Video Rect    left: 19, top: 11, width: 1882, height: 1058, aspect: 1.77778
1322009-11-17 20:38:31.955 SetVideoFrameRate(29.97)
1332009-11-17 20:38:31.956 NVP(1): LoadFilters(''..) -> 0x0
1342009-11-17 20:38:31.958 OSD Theme Dimensions W: 1280 H: 720
1352009-11-17 20:38:33.849 playCtx: StartDecoderThread(): took 2157 ms to start player.
1362009-11-17 20:38:33.849 NVP(1): ClearAfterSeek(1)
1372009-11-17 20:38:33.849 AO: Pause 1
1382009-11-17 20:38:33.849 TV: StartPlayer(0, Watching WatchingPreRecorded, main) -- end ok
1392009-11-17 20:38:33.851 VideoOutputQuartz::GetDisplayInfo() [returning 60]
1402009-11-17 20:38:33.851 VDP: GetFilteredDeint() : quartz-blit -> 'kerneldeint'
1412009-11-17 20:38:33.856 FilterManager: GetFilterInfo(convert) returning: 0x0
1422009-11-17 20:38:33.856 FilterManager: GetFilterInfo(kerneldeint) returning: 0x2657c5a0
1432009-11-17 20:38:33.861 TV: Changing from None to Watching WatchingPreRecorded
1442009-11-17 20:38:33.864 TV: HandleStateChange(0) -- end
1452009-11-17 20:38:33.086 KernelDeint: Created threads.
1462009-11-17 20:38:33.864 Using deinterlace method kerneldeint
1472009-11-17 20:38:33.870 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
1482009-11-17 20:38:33.871 OpenGLVideoSync()
1492009-11-17 20:38:33.871 ~OpenGLVideoSync() -- closing opengl vsync
1502009-11-17 20:38:33.871 VideoOutputQuartz::GetDisplayInfo() [returning 60]
1512009-11-17 20:38:33.871 Using audio as timebase
1522009-11-17 20:38:33.871 Video timing method: USleep with busy wait
1532009-11-17 20:38:33.871 Refresh rate: 16666, frame interval: 33366
1542009-11-17 20:38:33.871 NVP(1): Waiting for prebuffer..  0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAALL
1552009-11-17 20:38:33.881 AFD: DoFastForward(1160 (1), do discard frames)
1562009-11-17 20:38:33.881 Dec: DoFastForward(1160 (1), do discard frames)
1572009-11-17 20:38:33.881 Dec: FindPosition(1160, search not adjusted) -->
158                        [77:1155(65018296),78:1170(65871064)]
1592009-11-17 20:38:33.887 AFD: SeekReset(1170, 0, do flush, do discard)
1602009-11-17 20:38:33.887 AFD: SeekReset() flushing
1612009-11-17 20:38:33.887 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAUA
1622009-11-17 20:38:33.887 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1632009-11-17 20:38:33.887 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
1642009-11-17 20:38:33.888 NVP(1): ClearAfterSeek(0)
1652009-11-17 20:38:34.004 NVP(1): Waiting for prebuffer..  1 uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
1662009-11-17 20:38:34.137 NVP(1): Waiting for prebuffer..  2 uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
1672009-11-17 20:38:34.275 AO: Pause 0
168'video_output' mean = '32736.95', std. dev. = '3083.87', fps = '30.55'
169'video_output' mean = '33364.46', std. dev. = '127.19', fps = '29.97'
1702009-11-17 20:38:43.831 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
1712009-11-17 20:38:43.831 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
1722009-11-17 20:38:43.954 TV: HandleStateChange(0) -- begin
1732009-11-17 20:38:43.955 TV: Attempting to change from Watching WatchingPreRecorded to None
1742009-11-17 20:38:43.955 TV: StopStuff() for player ctx 0 -- begin
1752009-11-17 20:38:43.955 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
1762009-11-17 20:38:43.955 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
1772009-11-17 20:38:43.955 TV: StopStuff(): stopping ring buffer
1782009-11-17 20:38:43.958 NVP(1): Exited decoder loop.
1792009-11-17 20:38:43.980 TV: StopStuff(): stopping player
1802009-11-17 20:38:43.981 TV: StopStuff() -- end
1812009-11-17 20:38:43.981 TV: Changing from Watching WatchingPreRecorded to None