Ticket #6102: tv.txt

File tv.txt, 17.5 KB (added by mlists@…, 12 years ago)

detailed output

Line 
12009-01-09 20:40:31.379 Connected to database 'mythconverg' at host: localhost
22009-01-09 20:40:31.419 TV: tv->LiveTV() -- begin
32009-01-09 20:40:31.428 TV: tv->LiveTV() -- end
42009-01-09 20:40:31.428 TV: StartTV -- process events begin
52009-01-09 20:40:31.449 TV: HandleStateChange(0) -- begin
62009-01-09 20:40:31.449 TV: Attempting to change from None to WatchingLiveTV
72009-01-09 20:40:31.450 Using protocol version 43
82009-01-09 20:40:31.452 Spawning LiveTV Recorder -- begin
92009-01-09 20:40:33.047 Spawning LiveTV Recorder -- end
102009-01-09 20:40:33.050 LiveTVChain(live-tv-2009-01-09T20:40:31): ReloadAll(): Added new recording
112009-01-09 20:40:33.068 We have a playbackURL(/myth/1126_20090109204031.mpg) & cardtype(HDPVR)
122009-01-09 20:40:33.068 RingBuf(/myth/1126_20090109204031.mpg): OpenFile(/myth/1126_20090109204031.mpg, 12)
132009-01-09 20:40:37.035 RingBuf(/myth/1126_20090109204031.mpg): CalcReadAheadThresh(0 KB)
14                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
152009-01-09 20:40:37.035 We have a RingBuffer
162009-01-09 20:40:37.036 TV: StartRecorder(): took 1 ms to start recorder.
172009-01-09 20:40:37.036 TV: StartPlayer(0, WatchingLiveTV, main) -- begin
182009-01-09 20:40:37.245 [h264 @ 0xb6ee7030]mmco: unref short failure
192009-01-09 20:40:37.246 AFD: Stream #0, has id 0x4113 codec id H264, type Video, bitrate 0 at 0x0x9890e60
202009-01-09 20:40:37.252 VDP: Accepting: cmp(>= 1280 720) dec(vdpau) cpus(2) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt()
212009-01-09 20:40:37.267 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(2) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt()
222009-01-09 20:40:37.267 VDP: Rejecting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(onefield,onefield) filt()
23                        deinterlacer onefield is not supported w/renderer quartz-blit (supported: linearblend,kerneldeint,greedyhdeint,greedyhdoubleprocessdeint,yadifdeint,yadifdoubleprocessdeint,none)
242009-01-09 20:40:37.267 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
252009-01-09 20:40:37.267 VDP: LoadBestPreferences(2048x2048, 0)
262009-01-09 20:40:37.268 VDP: LoadBestPreferences(2048x2048, 60)
272009-01-09 20:40:37.268 VDP: LoadBestPreferences(1280x720, 60)
282009-01-09 20:40:37.358 VDPAU WARNING: H.264 VDPAU GPU decode not fully supported - playback may fail.
292009-01-09 20:40:37.369 VDP: Accepting: cmp(>= 1280 720) dec(vdpau) cpus(2) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt()
302009-01-09 20:40:37.369 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(2) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt()
312009-01-09 20:40:37.369 VDP: Rejecting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(onefield,onefield) filt()
32                        deinterlacer onefield is not supported w/renderer quartz-blit (supported: linearblend,kerneldeint,greedyhdeint,greedyhdoubleprocessdeint,yadifdeint,yadifdoubleprocessdeint,none)
332009-01-09 20:40:37.369 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
342009-01-09 20:40:37.369 VDP: LoadBestPreferences(2048x2048, 0)
352009-01-09 20:40:37.370 VDP: LoadBestPreferences(2048x2048, 60)
362009-01-09 20:40:37.370 VDP: LoadBestPreferences(1280x720, 60)
372009-01-09 20:40:37.370 Using 1 CPUs for decoding
382009-01-09 20:40:37.370 AFD: InitVideoCodec() 0x98544e0 id(H264_VDPAU) type (Video).
392009-01-09 20:40:37.370 detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
402009-01-09 20:40:37.370 AFD: Using vdpau for video decoding
412009-01-09 20:40:37.370 AFD: Looking for decoder for H264_VDPAU
422009-01-09 20:40:37.371 AFD: Opened codec 0x98544e0, id(H264_VDPAU) type(Video)
432009-01-09 20:40:37.371 AFD: Stream #1, has id 0x4352 codec id AC3, type Audio, bitrate 192000 at 0x0x988b250
442009-01-09 20:40:37.371 AFD: codec AC3 has 2 channels
452009-01-09 20:40:37.371 AFD: Looking for decoder for AC3
462009-01-09 20:40:37.372 AFD: Opened codec 0x82ce1b0, id(AC3) type(Audio)
472009-01-09 20:40:37.377 RingBuf(/myth/1126_20090109204031.mpg): CalcReadAheadThresh(0 KB)
48                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
492009-01-09 20:40:37.385 Opening audio device 'default'. ch 2(2) sr 48000
502009-01-09 20:40:37.386 Opening ALSA audio device 'default'.
512009-01-09 20:40:37.409 Dec: Trying to select track (w/lang)
522009-01-09 20:40:37.409 Dec: Selecting first track
532009-01-09 20:40:37.409 Dec: Selected track #1 in the Unknown language(0)
542009-01-09 20:40:37.409 Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
552009-01-09 20:40:37.411 Position map filled from DB to: 0
562009-01-09 20:40:37.411 Dec: SyncPositionMap watchingrecording, from DB: 1 entries
572009-01-09 20:40:37.411 NVP(0): Filling position map from 1 to end
582009-01-09 20:40:37.412 Dec: SyncPositionMap watchingrecording no entries from encoder, try DB
592009-01-09 20:40:37.413 Position map filled from DB to: 0
602009-01-09 20:40:37.414 Dec: SyncPositionMap watchingrecording total: 1 entries
612009-01-09 20:40:37.414 Dec: SyncPositionMap, new totframes: 0, new length: 0, posMap size: 1
622009-01-09 20:40:37.414 AFD: Partial position map found
632009-01-09 20:40:37.414 AFD: Successfully opened decoder for file: "/myth/1126_20090109204031.mpg". novideo(0)
642009-01-09 20:40:37.474 VDPAU WARNING: H.264 VDPAU GPU decode not fully supported - playback may fail.
652009-01-09 20:40:37.485 VideoOutput: Allowed renderers: vdpau
662009-01-09 20:40:37.485 VideoOutput: Allowed renderers (filt: vdpau): vdpau
672009-01-09 20:40:37.489 VDP: Accepting: cmp(>= 1280 720) dec(vdpau) cpus(2) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt()
682009-01-09 20:40:37.490 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(2) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt()
692009-01-09 20:40:37.490 VDP: Rejecting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(onefield,onefield) filt()
70                        deinterlacer onefield is not supported w/renderer quartz-blit (supported: linearblend,kerneldeint,greedyhdeint,greedyhdoubleprocessdeint,yadifdeint,yadifdoubleprocessdeint,none)
712009-01-09 20:40:37.490 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
722009-01-09 20:40:37.490 VDP: LoadBestPreferences(2048x2048, 0)
732009-01-09 20:40:37.490 VDP: LoadBestPreferences(2048x2048, 60)
742009-01-09 20:40:37.491 VDP: LoadBestPreferences(1280x720, 60)
752009-01-09 20:40:37.491 VideoOutput: Preferred renderer: vdpau
762009-01-09 20:40:37.491 VideoOutput: Trying video renderer: 'vdpau'
772009-01-09 20:40:37.508 VDP: Accepting: cmp(>= 1280 720) dec(vdpau) cpus(2) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt()
782009-01-09 20:40:37.508 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(2) rend(vdpau) osd(vdpau) osdfade(enabled) deint(none,none) filt()
792009-01-09 20:40:37.509 VDP: Rejecting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(onefield,onefield) filt()
80                        deinterlacer onefield is not supported w/renderer quartz-blit (supported: linearblend,kerneldeint,greedyhdeint,greedyhdoubleprocessdeint,yadifdeint,yadifdoubleprocessdeint,none)
812009-01-09 20:40:37.509 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
822009-01-09 20:40:37.509 VDP: LoadBestPreferences(2048x2048, 0)
832009-01-09 20:40:37.509 VDP: LoadBestPreferences(2048x2048, 60)
842009-01-09 20:40:37.514 VideoOutputXv: ctor
852009-01-09 20:40:37.515 VideoOutWindow::SetPIPState. pip_state: 0]
862009-01-09 20:40:37.516 VideoOutputXv: Creating XJ_gc
872009-01-09 20:40:37.516 VideoOutputXv: XJ_screen:     '0x988c3f0'
882009-01-09 20:40:37.517 VideoOutputXv: XJ_screen_num: '0'
892009-01-09 20:40:37.517 VideoOutputXv: XJ_curwin:     '33562469'
902009-01-09 20:40:37.517 VideoOutputXv: XJ_win:        '33562469'
912009-01-09 20:40:37.517 VideoOutputXv: XJ_root:       '259'
922009-01-09 20:40:37.517 VideoOutputXv: XJ_gc:         '0x8282690'
932009-01-09 20:40:37.527 Snapping width to avoid scaling: width: 1280, left: 0
942009-01-09 20:40:37.527 Display Rect  left: 0, top: 90, width: 1280, height: 540, aspect: 1.33333
952009-01-09 20:40:37.527 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
962009-01-09 20:40:37.527 VDP: LoadBestPreferences(1280x720, 60)
972009-01-09 20:40:37.549 GLCtx: Created window and GLX context.
982009-01-09 20:40:37.549 GLCtx: GLX Version: 1.2
992009-01-09 20:40:37.549 GLCtx: Direct rendering: Yes
1002009-01-09 20:40:37.579 GLCtx: OpenGL vendor  : NVIDIA Corporation
1012009-01-09 20:40:37.582 GLCtx: OpenGL renderer: GeForce 8400 GS/PCI/SSE2/3DNOW!
1022009-01-09 20:40:37.583 GLCtx: OpenGL version : 2.1.2 NVIDIA 180.22
1032009-01-09 20:40:37.583 GLCtx: Max texture size: 8192 x 8192
1042009-01-09 20:40:37.584 VideoOutputXv: Pixel dimensions: Screen 1280x720, window 1280x720
1052009-01-09 20:40:37.589 VideoOutputXv: Estimated display dimensions: 325x183 mm  Aspect: 1.77596
1062009-01-09 20:40:37.590 VideoOutputXv: Estimated window dimensions: 325x183 mm  Aspect: 1.77596
1072009-01-09 20:40:37.640 VDPAU WARNING: H.264 VDPAU GPU decode not fully supported - playback may fail.
1082009-01-09 20:40:37.647 VideoOutputXv: InitSetupBuffers() render: vdpau, allowed: vdpau
1092009-01-09 20:40:37.711 VDPAU: Version 0
1102009-01-09 20:40:37.712 VDPAU: Information Unknown
1112009-01-09 20:40:37.810 VideoOutputXv: Created VDPAU context (GPU decode)
1122009-01-09 20:40:37.810 VDP: SetVideoRenderer(vdpau)
1132009-01-09 20:40:37.810 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
1142009-01-09 20:40:37.815 VDPAU: Created OSD (1280x720)
1152009-01-09 20:40:37.815 VideoOutputXv: PictureAttributes:
1162009-01-09 20:40:37.815 Snapping height to avoid scaling: height: 720, top: 0
1172009-01-09 20:40:37.816 Snapping width to avoid scaling: width: 1280, left: 0
1182009-01-09 20:40:37.816 Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
1192009-01-09 20:40:37.816 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
1202009-01-09 20:40:37.820 New DB connection, total: 3
1212009-01-09 20:40:37.821 Connected to database 'mythconverg' at host: localhost
1222009-01-09 20:40:37.824 Over/underscan. V: 0, H: 0
1232009-01-09 20:40:37.824 Snapping height to avoid scaling: height: 720, top: 0
1242009-01-09 20:40:37.825 Snapping width to avoid scaling: width: 1280, left: 0
1252009-01-09 20:40:37.825 Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
1262009-01-09 20:40:37.825 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
1272009-01-09 20:40:37.825 VDP: LoadBestPreferences(1280x720, 59.9401)
1282009-01-09 20:40:37.827 NVP(0): LoadFilters(''..) -> 0x0
1292009-01-09 20:40:37.829 OSD Theme Dimensions W: 640 H: 480
1302009-01-09 20:40:38.557 playCtx: StartDecoderThread(): took 1139 ms to start player.
1312009-01-09 20:40:38.557 NVP(0): ClearAfterSeek(1)
1322009-01-09 20:40:38.557 VideoOutputXv: ClearAfterSeek()
1332009-01-09 20:40:38.557 VideoOutputXv: DiscardFrames(0)
1342009-01-09 20:40:38.557 TV: StartPlayer(0, WatchingLiveTV, main) -- end ok
1352009-01-09 20:40:38.557 TV: Changing from None to WatchingLiveTV
1362009-01-09 20:40:38.558 TV: State is LiveTV & mctx == ctx
1372009-01-09 20:40:38.560 TV: UpdateOSDInput done
1382009-01-09 20:40:38.560 TV: UpdateLCD done
1392009-01-09 20:40:38.561 TV: ITVRestart done
1402009-01-09 20:40:38.562 TV: HandleStateChange(0) -- end
1412009-01-09 20:40:38.557 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA
1422009-01-09 20:40:38.572 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAA -- done
1432009-01-09 20:40:38.572 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAA -- done()
1442009-01-09 20:40:38.574 VDP: GetFilteredDeint() : vdpau -> 'none'
1452009-01-09 20:40:38.577 Realtime priority would require SUID as root.
1462009-01-09 20:40:38.583 DPMS Deactivated
1472009-01-09 20:40:38.585 [h264_vdpau @ 0xb6ee7030]mmco: unref short failure
1482009-01-09 20:40:38.617 VDPAU: Created VDPAU decoder (9 ref frames)
1492009-01-09 20:40:38.618 [h264_vdpau @ 0xb6ee7030]B picture before any references, skipping
1502009-01-09 20:40:38.618 [h264_vdpau @ 0xb6ee7030]decode_slice_header error
1512009-01-09 20:40:38.618 [h264_vdpau @ 0xb6ee7030]no frame!
1522009-01-09 20:40:38.618 AFD Error: Unknown decoding error
1532009-01-09 20:40:38.618 [h264_vdpau @ 0xb6ee7030]B picture before any references, skipping
1542009-01-09 20:40:38.619 [h264_vdpau @ 0xb6ee7030]decode_slice_header error
1552009-01-09 20:40:38.619 [h264_vdpau @ 0xb6ee7030]no frame!
1562009-01-09 20:40:38.619 AFD Error: Unknown decoding error
1572009-01-09 20:40:38.619 [h264_vdpau @ 0xb6ee7030]B picture before any references, skipping
1582009-01-09 20:40:38.619 [h264_vdpau @ 0xb6ee7030]decode_slice_header error
1592009-01-09 20:40:38.620 [h264_vdpau @ 0xb6ee7030]no frame!
1602009-01-09 20:40:38.620 AFD Error: Unknown decoding error
1612009-01-09 20:40:38.622 [h264_vdpau @ 0xb6ee7030]mmco: unref short failure
1622009-01-09 20:40:38.675 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
1632009-01-09 20:40:38.675 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
1642009-01-09 20:40:38.676 Using audio as timebase
1652009-01-09 20:40:38.676 Video timing method: RTC
1662009-01-09 20:40:38.676 Refresh rate: 16662, frame interval: 16683
1672009-01-09 20:40:38.676 NVP(0): progressive frame seen after 2 interlaced  frames
1682009-01-09 20:40:38.723 AO: dropping back audio_buffer_unused
1692009-01-09 20:40:38.734 VDP: GetFilteredDeint() : vdpau -> 'none'
1702009-01-09 20:40:38.734 Disabled deinterlacing
1712009-01-09 20:40:38.781 AO: dropping back audio_buffer_unused
172'video_output' mean = '16493.78', std. dev. = '3319.09', fps = '60.63'
173'video_output' mean = '16664.52', std. dev. = '346.16', fps = '60.01'
174'video_output' mean = '16671.03', std. dev. = '561.88', fps = '59.98'
1752009-01-09 20:40:45.316 NVP(0): 400 progressive frames seen.
176'video_output' mean = '16687.10', std. dev. = '300.06', fps = '59.93'
177'video_output' mean = '16677.17', std. dev. = '313.97', fps = '59.96'
178'video_output' mean = '16675.97', std. dev. = '293.12', fps = '59.97'
179'video_output' mean = '16687.22', std. dev. = '360.94', fps = '59.93'
1802009-01-09 20:40:51.990 NVP(0): 800 progressive frames seen.
181'video_output' mean = '16676.75', std. dev. = '276.07', fps = '59.96'
182'video_output' mean = '16677.12', std. dev. = '296.83', fps = '59.96'
183'video_output' mean = '16686.37', std. dev. = '345.94', fps = '59.93'
184'video_output' mean = '16664.76', std. dev. = '312.90', fps = '60.01'
1852009-01-09 20:40:58.662 NVP(0): 1200 progressive frames seen.
186'video_output' mean = '16686.56', std. dev. = '281.95', fps = '59.93'
187'video_output' mean = '16676.93', std. dev. = '282.24', fps = '59.96'
188'video_output' mean = '16676.86', std. dev. = '277.82', fps = '59.96'
189'video_output' mean = '16686.39', std. dev. = '298.84', fps = '59.93'
1902009-01-09 20:41:05.337 NVP(0): 1600 progressive frames seen.
191'video_output' mean = '16676.68', std. dev. = '312.21', fps = '59.96'
192'video_output' mean = '16658.17', std. dev. = '400.19', fps = '60.03'
193'video_output' mean = '16680.85', std. dev. = '287.40', fps = '59.95'
194'video_output' mean = '16677.22', std. dev. = '272.07', fps = '59.96'
1952009-01-09 20:41:12.009 NVP(0): 2000 progressive frames seen.
196'video_output' mean = '16676.45', std. dev. = '311.63', fps = '59.96'
197'video_output' mean = '16674.65', std. dev. = '318.75', fps = '59.97'
198'video_output' mean = '16664.05', std. dev. = '399.61', fps = '60.01'
199'video_output' mean = '16680.24', std. dev. = '366.12', fps = '59.95'
2002009-01-09 20:41:18.683 NVP(0): 2400 progressive frames seen.
201'video_output' mean = '16670.69', std. dev. = '325.68', fps = '59.99'
202'video_output' mean = '16670.04', std. dev. = '333.15', fps = '59.99'
203'video_output' mean = '16674.52', std. dev. = '314.36', fps = '59.97'
204'video_output' mean = '16646.86', std. dev. = '575.32', fps = '60.07'
2052009-01-09 20:41:25.356 NVP(0): 2800 progressive frames seen.
206'video_output' mean = '16659.03', std. dev. = '339.10', fps = '60.03'
207'video_output' mean = '16680.19', std. dev. = '372.72', fps = '59.95'
208'video_output' mean = '16670.65', std. dev. = '559.96', fps = '59.99'
209'video_output' mean = '16640.22', std. dev. = '386.68', fps = '60.10'
2102009-01-09 20:41:32.030 NVP(0): 3200 progressive frames seen.
211'video_output' mean = '16669.34', std. dev. = '326.22', fps = '59.99'
212'video_output' mean = '16672.28', std. dev. = '727.63', fps = '59.98'
213'video_output' mean = '16674.78', std. dev. = '527.09', fps = '59.97'
214'video_output' mean = '16676.60', std. dev. = '670.24', fps = '59.96'
2152009-01-09 20:41:38.703 NVP(0): 3600 progressive frames seen.
216'video_output' mean = '16670.65', std. dev. = '706.84', fps = '59.99'
217'video_output' mean = '16668.01', std. dev. = '744.87', fps = '60.00'
218'video_output' mean = '16670.74', std. dev. = '557.83', fps = '59.99'
219'video_output' mean = '16664.19', std. dev. = '737.44', fps = '60.01'
2202009-01-09 20:41:45.377 NVP(0): 4000 progressive frames seen.
221'video_output' mean = '16679.77', std. dev. = '610.44', fps = '59.95'
222'video_output' mean = '16678.78', std. dev. = '758.50', fps = '59.96'
223'video_output' mean = '16674.87', std. dev. = '539.05', fps = '59.97'
224'video_output' mean = '16674.46', std. dev. = '434.13', fps = '59.97'
2252009-01-09 20:41:52.048 NVP(0): 4400 progressive frames seen.
226'video_output' mean = '16671.10', std. dev. = '746.25', fps = '59.98'
227'video_output' mean = '16663.42', std. dev. = '680.04', fps = '60.01'
228'video_output' mean = '16674.83', std. dev. = '700.70', fps = '59.97'
229'video_output' mean = '16676.50', std. dev. = '729.88', fps = '59.96'
2302009-01-09 20:41:58.723 NVP(0): 4800 progressive frames seen.
231'video_output' mean = '16674.06', std. dev. = '781.34', fps = '59.97'
232'video_output' mean = '16670.93', std. dev. = '737.86', fps = '59.98'
233'video_output' mean = '16670.33', std. dev. = '727.46', fps = '59.99'
234