Opened 14 years ago

Closed 14 years ago

Last modified 14 years ago

#774 closed defect (fixed)

XvMC LiveTV playback broken

Reported by: anonymous Owned by: danielk
Priority: minor Milestone: 0.19
Component: mythtv Version: head
Severity: medium Keywords:
Cc: Ticket locked: no

Description

SVN Version: 8161 MPEG Decoder: mmpeg2 Aggressive Sound Card buffering: on Extra Sound buffering: on

I'm getting dropped frames while watching Live HD channels. Some channels are worse than others.

Mythfrontend log follows:

2005-12-08 20:31:30.034 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2005-12-08 20:31:30.106 NVP: Waiting for prebuffer.. 2 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2005-12-08 20:31:30.178 NVP: Waiting for prebuffer.. 3 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2005-12-08 20:31:30.250 NVP: Waiting for prebuffer.. 4 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2005-12-08 20:31:30.322 NVP: Waiting for prebuffer.. 5 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2005-12-08 20:31:30.372 NVP: ClearAfterSeek()
2005-12-08 20:31:30.372 VideoOutputXv: ClearAfterSeek()
2005-12-08 20:31:30.372 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2005-12-08 20:31:30.372 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2005-12-08 20:31:30.372 detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
2005-12-08 20:31:30.372 Interlaced: Progressive Scan  video_height: 720  fps: 59.9401
2005-12-08 20:31:30.389 AFD: Selected track 1: English AC3 5.1ch (A/V Stream #1)
2005-12-08 20:31:30.389 AFD: Initializing audio parms from audio track #1
2005-12-08 20:31:30.389 AFD: Audio format changed 
2005-12-08 20:31:30.390 Killing AudioOutputDSP
2005-12-08 20:31:30.390 Opening audio device '/dev/dsp'.
2005-12-08 20:31:30.390 Opening OSS audio device '/dev/dsp'.
2005-12-08 20:31:30.391 Audio fragment size: 4096
2005-12-08 20:31:30.391 Audio Stretch Factor: 1
2005-12-08 20:31:30.391 Ending reconfigure
2005-12-08 20:31:30.391 NVP: Enabling Audio
2005-12-08 20:31:30.393 kickoffOutputAudioLoop: pid = 22661
2005-12-08 20:31:30.394 NVP: Waiting for prebuffer.. 6 LAAAAAAAAAAALLLUAAAAAAAAAAAAAAA
2005-12-08 20:31:30.394 OutputAudioLoop: Play Event
2005-12-08 20:31:30.394 Broadcasting free space avail
2005-12-08 20:31:30.403 Broadcasting free space avail
2005-12-08 20:31:30.410 Broadcasting free space avail
2005-12-08 20:31:30.415 _AddSamples bytes=6144, used=1, free=767999, timecode=54695980
2005-12-08 20:31:30.415 _AddSamples bytes=6144, used=6145, free=761855, timecode=54696012
2005-12-08 20:31:30.416 _AddSamples bytes=6144, used=12289, free=755711, timecode=54696044
2005-12-08 20:31:30.417 _AddSamples bytes=6144, used=18433, free=749567, timecode=54696076
2005-12-08 20:31:30.418 _AddSamples bytes=6144, used=24577, free=743423, timecode=54696108
2005-12-08 20:31:30.418 Broadcasting free space avail
2005-12-08 20:31:30.419 Broadcasting free space avail
2005-12-08 20:31:30.419 Broadcasting free space avail
2005-12-08 20:31:30.419 Broadcasting free space avail
2005-12-08 20:31:30.419 audio waiting for space on soundcard: have 100 need 4096
2005-12-08 20:31:30.420 _AddSamples bytes=6144, used=14337, free=753663, timecode=54696140
2005-12-08 20:31:30.421 _AddSamples bytes=6144, used=20481, free=747519, timecode=54696172
2005-12-08 20:31:30.422 _AddSamples bytes=6144, used=26625, free=741375, timecode=54696204
2005-12-08 20:31:30.424 _AddSamples bytes=6144, used=32769, free=735231, timecode=54696236
2005-12-08 20:31:30.424 _AddSamples bytes=6144, used=38913, free=729087, timecode=54696268
2005-12-08 20:31:30.426 _AddSamples bytes=6144, used=45057, free=722943, timecode=54696300
2005-12-08 20:31:30.427 _AddSamples bytes=6144, used=51201, free=716799, timecode=54696332
2005-12-08 20:31:30.428 _AddSamples bytes=6144, used=57345, free=710655, timecode=54696364
2005-12-08 20:31:30.430 _AddSamples bytes=6144, used=63489, free=704511, timecode=54696396
2005-12-08 20:31:30.430 audio waiting for space on soundcard: have 2140 need 4096
2005-12-08 20:31:30.431 _AddSamples bytes=6144, used=69633, free=698367, timecode=54696428
2005-12-08 20:31:30.434 _AddSamples bytes=6144, used=75777, free=692223, timecode=54696460
2005-12-08 20:31:30.435 _AddSamples bytes=6144, used=81921, free=686079, timecode=54696492
2005-12-08 20:31:30.437 _AddSamples bytes=6144, used=88065, free=679935, timecode=54696524
2005-12-08 20:31:30.438 _AddSamples bytes=6144, used=94209, free=673791, timecode=54696556
2005-12-08 20:31:30.438 _AddSamples bytes=6144, used=100353, free=667647, timecode=54696588
2005-12-08 20:31:30.440 _AddSamples bytes=6144, used=106497, free=661503, timecode=54696620
2005-12-08 20:31:30.441 _AddSamples bytes=6144, used=112641, free=655359, timecode=54696652
2005-12-08 20:31:30.442 _AddSamples bytes=6144, used=118785, free=649215, timecode=54696684
2005-12-08 20:31:30.442 Broadcasting free space avail
2005-12-08 20:31:30.442 audio waiting for space on soundcard: have 448 need 4096
2005-12-08 20:31:30.443 _AddSamples bytes=6144, used=120833, free=647167, timecode=54696716
2005-12-08 20:31:30.449 _AddSamples bytes=6144, used=126977, free=641023, timecode=54696748
2005-12-08 20:31:30.454 audio waiting for space on soundcard: have 2628 need 4096
2005-12-08 20:31:30.462 _AddSamples bytes=6144, used=133121, free=634879, timecode=54696780
2005-12-08 20:31:30.466 NVP: Waiting for prebuffer.. 7 UUAAAAAAAAAALULUAAUULULAAAAAAAA
2005-12-08 20:31:30.467 Broadcasting free space avail
2005-12-08 20:31:30.468 audio waiting for space on soundcard: have 1192 need 4096
2005-12-08 20:31:30.478 audio waiting for space on soundcard: have 3176 need 4096
2005-12-08 20:31:30.479 _AddSamples bytes=6144, used=135169, free=632831, timecode=54696812
2005-12-08 20:31:30.493 Broadcasting free space avail
2005-12-08 20:31:30.494 audio waiting for space on soundcard: have 2092 need 4096
2005-12-08 20:31:30.494 _AddSamples bytes=6144, used=137217, free=630783, timecode=54696844
2005-12-08 20:31:30.511 _AddSamples bytes=6144, used=143361, free=624639, timecode=54696876
2005-12-08 20:31:30.519 _AddSamples bytes=6144, used=149505, free=618495, timecode=54696908
2005-12-08 20:31:30.525 Broadcasting free space avail
2005-12-08 20:31:30.525 audio waiting for space on soundcard: have 4092 need 4096
2005-12-08 20:31:30.538 Broadcasting free space avail
2005-12-08 20:31:30.538 audio waiting for space on soundcard: have 2468 need 4096
2005-12-08 20:31:30.543 _AddSamples bytes=6144, used=147457, free=620543, timecode=54696940
2005-12-08 20:31:30.550 Broadcasting free space avail
2005-12-08 20:31:30.550 audio waiting for space on soundcard: have 680 need 4096
2005-12-08 20:31:30.562 audio waiting for space on soundcard: have 2956 need 4096
2005-12-08 20:31:30.566 _AddSamples bytes=6144, used=149505, free=618495, timecode=54696972
2005-12-08 20:31:30.566 NVP: Audio is 6.26782 frames ahead of video,
2005-12-08 20:31:30.576 Broadcasting free space avail
2005-12-08 20:31:30.576 audio waiting for space on soundcard: have 1508 need 4096
2005-12-08 20:31:30.585 _AddSamples bytes=6144, used=151553, free=616447, timecode=54697004
2005-12-08 20:31:30.586 audio waiting for space on soundcard: have 3404 need 4096
2005-12-08 20:31:30.598 _AddSamples bytes=6144, used=157697, free=610303, timecode=54697036
2005-12-08 20:31:30.598 Broadcasting free space avail
2005-12-08 20:31:30.598 audio waiting for space on soundcard: have 1740 need 4096
2005-12-08 20:31:30.609 NVP: Audio is 11.8488 frames ahead of video,
2005-12-08 20:31:30.613 Broadcasting free space avail
2005-12-08 20:31:30.613 audio waiting for space on soundcard: have 532 need 4096
2005-12-08 20:31:30.616 _AddSamples bytes=6144, used=155649, free=612351, timecode=54697068
2005-12-08 20:31:30.626 audio waiting for space on soundcard: have 2892 need 4096
2005-12-08 20:31:30.637 _AddSamples bytes=6144, used=161793, free=606207, timecode=54697100
2005-12-08 20:31:30.638 Broadcasting free space avail
2005-12-08 20:31:30.638 audio waiting for space on soundcard: have 1184 need 4096
2005-12-08 20:31:30.650 audio waiting for space on soundcard: have 3408 need 4096
2005-12-08 20:31:30.654 _AddSamples bytes=6144, used=163841, free=604159, timecode=54697132
2005-12-08 20:31:30.659 NVP: Audio is 15.6899 frames ahead of video,
2005-12-08 20:31:30.662 Broadcasting free space avail
2005-12-08 20:31:30.662 audio waiting for space on soundcard: have 1720 need 4096
2005-12-08 20:31:30.674 audio waiting for space on soundcard: have 4000 need 4096
2005-12-08 20:31:30.676 _AddSamples bytes=6144, used=165889, free=602111, timecode=54697164
2005-12-08 20:31:30.686 Broadcasting free space avail
2005-12-08 20:31:30.686 audio waiting for space on soundcard: have 2224 need 4096
2005-12-08 20:31:30.688 _AddSamples bytes=6144, used=167937, free=600063, timecode=54697196
2005-12-08 20:31:30.698 Broadcasting free space avail
2005-12-08 20:31:30.698 audio waiting for space on soundcard: have 376 need 4096
2005-12-08 20:31:30.707 NVP: Audio is 18.0762 frames ahead of video,
2005-12-08 20:31:30.710 audio waiting for space on soundcard: have 2672 need 4096
2005-12-08 20:31:30.722 Broadcasting free space avail
2005-12-08 20:31:30.722 audio waiting for space on soundcard: have 892 need 4096
2005-12-08 20:31:30.734 audio waiting for space on soundcard: have 3148 need 4096
2005-12-08 20:31:30.746 Broadcasting free space avail
2005-12-08 20:31:30.746 audio waiting for space on soundcard: have 1392 need 4096
2005-12-08 20:31:30.757 NVP: Audio is 19.3864 frames ahead of video,
2005-12-08 20:31:30.758 audio waiting for space on soundcard: have 3684 need 4096
2005-12-08 20:31:30.769 _AddSamples bytes=6144, used=161793, free=606207, timecode=54697228
2005-12-08 20:31:30.770 Broadcasting free space avail
2005-12-08 20:31:30.770 audio waiting for space on soundcard: have 1900 need 4096
2005-12-08 20:31:30.782 Broadcasting free space avail
2005-12-08 20:31:30.782 audio waiting for space on soundcard: have 164 need 4096
2005-12-08 20:31:30.794 audio waiting for space on soundcard: have 2380 need 4096
2005-12-08 20:31:30.806 Broadcasting free space avail
2005-12-08 20:31:30.806 audio waiting for space on soundcard: have 716 need 4096
2005-12-08 20:31:30.810 NVP: Audio is 19.8746 frames ahead of video,
2005-12-08 20:31:30.818 audio waiting for space on soundcard: have 2904 need 4096
2005-12-08 20:31:30.830 Broadcasting free space avail
2005-12-08 20:31:30.830 audio waiting for space on soundcard: have 1152 need 4096
2005-12-08 20:31:30.842 audio waiting for space on soundcard: have 3404 need 4096
2005-12-08 20:31:30.854 Broadcasting free space avail
2005-12-08 20:31:30.854 audio waiting for space on soundcard: have 1668 need 4096
2005-12-08 20:31:30.861 NVP: Audio is 19.7162 frames ahead of video,
2005-12-08 20:31:30.869 Broadcasting free space avail
2005-12-08 20:31:30.869 audio waiting for space on soundcard: have 448 need 4096
2005-12-08 20:31:30.871 _AddSamples bytes=6144, used=143361, free=624639, timecode=54697260
2005-12-08 20:31:30.878 audio waiting for space on soundcard: have 2128 need 4096
2005-12-08 20:31:30.890 Broadcasting free space avail
2005-12-08 20:31:30.890 audio waiting for space on soundcard: have 380 need 4096
2005-12-08 20:31:30.902 audio waiting for space on soundcard: have 2648 need 4096
2005-12-08 20:31:30.909 NVP: Audio is 19.0729 frames ahead of video,
2005-12-08 20:31:30.917 Broadcasting free space avail
2005-12-08 20:31:30.917 audio waiting for space on soundcard: have 1464 need 4096
2005-12-08 20:31:30.926 audio waiting for space on soundcard: have 3152 need 4096
2005-12-08 20:31:30.938 Broadcasting free space avail
2005-12-08 20:31:30.938 audio waiting for space on soundcard: have 1396 need 4096
2005-12-08 20:31:30.950 audio waiting for space on soundcard: have 3684 need 4096
2005-12-08 20:31:30.962 NVP: Audio is 18.111 frames ahead of video,
2005-12-08 20:31:30.962 Broadcasting free space avail
2005-12-08 20:31:30.964 audio waiting for space on soundcard: have 2036 need 4096
2005-12-08 20:31:30.971 _AddSamples bytes=6144, used=133121, free=634879, timecode=54697292
2005-12-08 20:31:30.974 Broadcasting free space avail
2005-12-08 20:31:30.974 audio waiting for space on soundcard: have 120 need 4096
2005-12-08 20:31:30.988 audio waiting for space on soundcard: have 2848 need 4096
2005-12-08 20:31:30.998 Broadcasting free space avail
2005-12-08 20:31:30.998 audio waiting for space on soundcard: have 636 need 4096
2005-12-08 20:31:31.009 NVP: Audio is 16.85 frames ahead of video,
2005-12-08 20:31:31.014 audio waiting for space on soundcard: have 3676 need 4096
2005-12-08 20:31:31.018 _AddSamples bytes=6144, used=131073, free=636927, timecode=54697324
2005-12-08 20:31:31.027 Broadcasting free space avail
2005-12-08 20:31:31.028 audio waiting for space on soundcard: have 2220 need 4096
2005-12-08 20:31:31.038 Broadcasting free space avail
2005-12-08 20:31:31.038 audio waiting for space on soundcard: have 144 need 4096
2005-12-08 20:31:31.050 audio waiting for space on soundcard: have 2396 need 4096
2005-12-08 20:31:31.058 NVP: Audio is 15.4397 frames ahead of video,
2005-12-08 20:31:31.063 Broadcasting free space avail
2005-12-08 20:31:31.063 audio waiting for space on soundcard: have 876 need 4096
2005-12-08 20:31:31.074 audio waiting for space on soundcard: have 2904 need 4096
2005-12-08 20:31:31.086 Broadcasting free space avail
2005-12-08 20:31:31.086 audio waiting for space on soundcard: have 1152 need 4096
2005-12-08 20:31:31.098 audio waiting for space on soundcard: have 3432 need 4096
2005-12-08 20:31:31.110 NVP: Audio is 13.9025 frames ahead of video,
2005-12-08 20:31:31.110 Broadcasting free space avail
2005-12-08 20:31:31.113 audio waiting for space on soundcard: have 1768 need 4096
2005-12-08 20:31:31.120 _AddSamples bytes=6144, used=116737, free=651263, timecode=54697356
2005-12-08 20:31:31.126 Broadcasting free space avail
2005-12-08 20:31:31.126 audio waiting for space on soundcard: have 640 need 4096
2005-12-08 20:31:31.138 audio waiting for space on soundcard: have 2948 need 4096
2005-12-08 20:31:31.150 Broadcasting free space avail
2005-12-08 20:31:31.150 audio waiting for space on soundcard: have 1164 need 4096
2005-12-08 20:31:31.158 NVP: Audio is 12.2251 frames ahead of video,
2005-12-08 20:31:31.162 audio waiting for space on soundcard: have 3532 need 4096
2005-12-08 20:31:31.174 Broadcasting free space avail
2005-12-08 20:31:31.174 audio waiting for space on soundcard: have 1676 need 4096
2005-12-08 20:31:31.186 audio waiting for space on soundcard: have 3932 need 4096
2005-12-08 20:31:31.198 Broadcasting free space avail
2005-12-08 20:31:31.198 audio waiting for space on soundcard: have 2196 need 4096
2005-12-08 20:31:31.210 Broadcasting free space avail
2005-12-08 20:31:31.210 audio waiting for space on soundcard: have 412 need 4096
2005-12-08 20:31:31.211 NVP: Audio is 10.5025 frames ahead of video,
2005-12-08 20:31:31.218 _AddSamples bytes=6144, used=102401, free=665599, timecode=54697388
2005-12-08 20:31:31.222 audio waiting for space on soundcard: have 2656 need 4096
2005-12-08 20:31:31.234 Broadcasting free space avail
2005-12-08 20:31:31.234 audio waiting for space on soundcard: have 916 need 4096
2005-12-08 20:31:31.246 audio waiting for space on soundcard: have 3168 need 4096
2005-12-08 20:31:31.258 Broadcasting free space avail
2005-12-08 20:31:31.258 audio waiting for space on soundcard: have 1432 need 4096
2005-12-08 20:31:31.261 NVP: Audio is 8.67104 frames ahead of video,
2005-12-08 20:31:31.271 audio waiting for space on soundcard: have 3872 need 4096
2005-12-08 20:31:31.282 Broadcasting free space avail
2005-12-08 20:31:31.282 audio waiting for space on soundcard: have 1976 need 4096
2005-12-08 20:31:31.294 Broadcasting free space avail
2005-12-08 20:31:31.294 audio waiting for space on soundcard: have 132 need 4096
2005-12-08 20:31:31.306 audio waiting for space on soundcard: have 2400 need 4096
2005-12-08 20:31:31.309 NVP: Audio is 6.78799 frames ahead of video,
2005-12-08 20:31:31.318 Broadcasting free space avail
2005-12-08 20:31:31.318 audio waiting for space on soundcard: have 688 need 4096
2005-12-08 20:31:31.321 _AddSamples bytes=6144, used=88065, free=679935, timecode=54697420
2005-12-08 20:31:31.330 audio waiting for space on soundcard: have 2936 need 4096
2005-12-08 20:31:31.342 Broadcasting free space avail
2005-12-08 20:31:31.342 audio waiting for space on soundcard: have 1160 need 4096
2005-12-08 20:31:31.355 audio waiting for space on soundcard: have 3676 need 4096
2005-12-08 20:31:31.359 NVP: Audio is 4.91117 frames ahead of video,
2005-12-08 20:31:31.366 Broadcasting free space avail
2005-12-08 20:31:31.367 audio waiting for space on soundcard: have 1832 need 4096
2005-12-08 20:31:31.378 audio waiting for space on soundcard: have 3952 need 4096
2005-12-08 20:31:31.390 Broadcasting free space avail
2005-12-08 20:31:31.391 audio waiting for space on soundcard: have 2304 need 4096
2005-12-08 20:31:31.402 Broadcasting free space avail
2005-12-08 20:31:31.402 audio waiting for space on soundcard: have 400 need 4096
2005-12-08 20:31:31.414 audio waiting for space on soundcard: have 2700 need 4096
2005-12-08 20:31:31.417 _AddSamples bytes=6144, used=77825, free=690175, timecode=54697452
2005-12-08 20:31:31.426 Broadcasting free space avail
2005-12-08 20:31:31.426 audio waiting for space on soundcard: have 948 need 4096
2005-12-08 20:31:31.438 audio waiting for space on soundcard: have 3176 need 4096
2005-12-08 20:31:31.450 Broadcasting free space avail
2005-12-08 20:31:31.450 audio waiting for space on soundcard: have 1468 need 4096
2005-12-08 20:31:31.462 audio waiting for space on soundcard: have 3692 need 4096
2005-12-08 20:31:31.474 Broadcasting free space avail
2005-12-08 20:31:31.474 audio waiting for space on soundcard: have 1992 need 4096
2005-12-08 20:31:31.484 _AddSamples bytes=6144, used=71681, free=696319, timecode=54697484
2005-12-08 20:31:31.486 Broadcasting free space avail
2005-12-08 20:31:31.486 audio waiting for space on soundcard: have 148 need 4096
2005-12-08 20:31:31.498 NVP: Video is 3.34149 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.502 audio waiting for space on soundcard: have 3260 need 4096
2005-12-08 20:31:31.514 Broadcasting free space avail
2005-12-08 20:31:31.514 NVP: Video is 4.0196 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.514 audio waiting for space on soundcard: have 1472 need 4096
2005-12-08 20:31:31.516 _AddSamples bytes=6144, used=69633, free=698367, timecode=54697516
2005-12-08 20:31:31.526 audio waiting for space on soundcard: have 3696 need 4096
2005-12-08 20:31:31.529 NVP: Video is 4.5282 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.539 _AddSamples bytes=6144, used=75777, free=692223, timecode=54697548
2005-12-08 20:31:31.540 Broadcasting free space avail
2005-12-08 20:31:31.540 audio waiting for space on soundcard: have 2300 need 4096
2005-12-08 20:31:31.541 NVP: Video is 4.8797 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.550 Broadcasting free space avail
2005-12-08 20:31:31.550 audio waiting for space on soundcard: have 180 need 4096
2005-12-08 20:31:31.552 NVP: Video is 5.06839 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.562 _AddSamples bytes=6144, used=73729, free=694271, timecode=54697580
2005-12-08 20:31:31.562 audio waiting for space on soundcard: have 2420 need 4096
2005-12-08 20:31:31.563 NVP: Video is 5.12 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.572 NVP: Video is 5.06875 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.574 Broadcasting free space avail
2005-12-08 20:31:31.574 NVP: Video is 4.92543 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.574 audio waiting for space on soundcard: have 704 need 4096
2005-12-08 20:31:31.576 NVP: Video is 4.59318 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.577 NVP: Video is 4.11922 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.579 NVP: Video is 3.53893 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.584 _AddSamples bytes=6144, used=75777, free=692223, timecode=54697612
2005-12-08 20:31:31.586 audio waiting for space on soundcard: have 2932 need 4096
2005-12-08 20:31:31.597 _AddSamples bytes=6144, used=81921, free=686079, timecode=54697644
2005-12-08 20:31:31.598 Broadcasting free space avail
2005-12-08 20:31:31.598 audio waiting for space on soundcard: have 1188 need 4096
2005-12-08 20:31:31.610 audio waiting for space on soundcard: have 3444 need 4096
2005-12-08 20:31:31.615 _AddSamples bytes=6144, used=83969, free=684031, timecode=54697676
2005-12-08 20:31:31.622 Broadcasting free space avail
2005-12-08 20:31:31.622 audio waiting for space on soundcard: have 1684 need 4096
2005-12-08 20:31:31.634 audio waiting for space on soundcard: have 3964 need 4096
2005-12-08 20:31:31.646 Broadcasting free space avail
2005-12-08 20:31:31.646 audio waiting for space on soundcard: have 2208 need 4096
2005-12-08 20:31:31.658 NVP: Video is 3.30186 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.660 NVP: Video is 3.39046 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.662 NVP: Video is 3.23215 frames ahead of audio, dropping frame.
2005-12-08 20:31:31.665 _AddSamples bytes=6144, used=81921, free=686079, timecode=54697708

Attachments (1)

mythfe-012706.log (46.3 KB) - added by Mark de Jong <dejongm@…> 14 years ago.

Download all attachments as: .zip

Change History (32)

comment:1 Changed 14 years ago by Mark de Jong <dejongm@…>

Updated to latest SVN (8198). It still exhibits same symptoms. I've tried all mpeg decoders (Standard,libmpeg2,XvMC) all have pretty much the same problem.

Setting "Use video as timebase" and/or "OpenGL sync" make no difference.

Not sure what else to provide with regards to logs. I'll supply whatevers needed.

--Mark

comment:2 Changed 14 years ago by danielk

Resolution: worksforme
Status: newclosed

Are you using nvidia drivers? If yes, see the messages in mythtv-dev/mythtv-users about the latest drivers.

If that is not your problem, please isolate the revision where your problems begin.

Also what CPU @ what speed do you have? Have you tried disabling aggressive sound card buffering?

comment:3 Changed 14 years ago by Mark de Jong <dejongm@…>

Resolution: worksforme
Status: closedreopened

Yes. I'm using the nvidia drivers but not the ones with the issues. Just upgraded to 7676 and still have the same problems. Yes. I have tried disabling agessive sound card buffering.

Some more data points. I've just upgraded to SVN 8212. I just tried recording the problem channel first and then watched it in "Watch recordings." The problem goes away. Works with all decoders. Live TV is the problem.. I know LiveTV still a work in progress. Again, whatever you need, I pass your way.

I'm running both frontend/backend on a p4 2.8GHz. I get 40-50% CPU time watch DVB w/o XvMC.

comment:4 Changed 14 years ago by Mark de Jong <dejongm@…>

New datapoint. My current SVN is 8274. I'm not sure if anything has changed but I have noticed that it seems when there are lost frames when the OSD is up after a channel change, playback is smooth once the OSD is gone. When there are no lost frames during the OSD, once the OSD goes away, the video looses frames.

Again, this does not happen with all my channels. I notice it on two of the five channels I have.

comment:5 Changed 14 years ago by danielk

Milestone: 0.19unknown
Owner: changed from Isaac Richards to danielk
Status: reopenednew
Version: head

Hmmm, do you know how to profile? If not there is a guide for MythTV profiling at:

http://www.cuymedia.net/doxygen-dev-docs/html/group__profiling__mythtv.html

If you list the top 20 items on the list when this occurs it should help locate the problem. Make sure to enable profiling only after the problem begins, and stop it soon after it goes away.

comment:6 Changed 14 years ago by Mark de Jong <dejongm@…>

Thanks for the direction. I'll try it out and report back as soon as I get some results.

comment:7 Changed 14 years ago by danielk

Resolution: invalid
Status: newclosed

This ticket needs a profile.

comment:8 Changed 14 years ago by sberlo9@…

Resolution: invalid
Status: closedreopened

I too am having this problem. I use FC4 kernel 2.6.13 - nVidia GeForce? 6200 (tried 7676 and newest drivers), SVN 8331, HD5000 capture card. The problem seems to occur MUCH more on 720p channels than 1080i, and if I tell Mythtv setup that there is no sound device (leave it blank), the video seems like it is in slow motion. Very strange. This is my first attempt at profiling before, so hopefully this is what you need. If there is any other info I can provide, let me know!!

CPU: CPU with timer interrupt, speed 0 MHz (estimated) Profiling through timer interrupt samples % app name symbol name 5560 53.4513 vmlinux mwait_idle 735 7.0659 libmythtv-0.19.so.0.19.0 mmxext_idct 648 6.2296 libmythtv-0.19.so.0.19.0 get_non_intra_block 344 3.3071 libmythtv-0.19.so.0.19.0 MC_put_o_8_mmxext 310 2.9802 libmythtv-0.19.so.0.19.0 MC_put_xy_16_mmxext 296 2.8456 libmythtv-0.19.so.0.19.0 MC_put_o_16_mmxext 209 2.0092 libmythtv-0.19.so.0.19.0 slice_intra_DCT 207 1.9900 libmythtv-0.19.so.0.19.0 mpeg2_slice 158 1.5189 libmythtv-0.19.so.0.19.0 mpeg2_idct_add_mmxext 147 1.4132 libmythtv-0.19.so.0.19.0 motion_fr_frame_420 115 1.1056 libmythtv-0.19.so.0.19.0 MC_put_y_16_mmxext 104 0.9998 nvidia_drv.so _nv001143X 86 0.8268 libc-2.3.5.so memcpy 83 0.7979 libmythtv-0.19.so.0.19.0 MC_put_x_16_mmxext 80 0.7691 libmythtv-0.19.so.0.19.0 MC_avg_xy_16_mmxext 79 0.7595 libmythtv-0.19.so.0.19.0 AvFormatDecoder::MpegPreProcessPkt?(AVStream*, AVPacket*) 71 0.6826 libmythtv-0.19.so.0.19.0 mpeg2_parse 56 0.5384 libqt-mt.so.3.3.4 (no symbols) 56 0.5384 vmlinux flexcop_pci_isr 54 0.5191 libmythtv-0.19.so.0.19.0 MC_put_xy_8_mmxext 49 0.4711 vmlinux handle_IRQ_event 48 0.4614 libmythavcodec-0.19.so.0.19.0 ff_mpeg1_find_frame_end 41 0.3942 libmythtv-0.19.so.0.19.0 MC_put_x_8_mmxext 41 0.3942 nvidia (no symbols) 40 0.3845 libmythtv-0.19.so.0.19.0 MC_avg_o_8_mmxext 40 0.3845 libmythtv-0.19.so.0.19.0 MC_put_y_8_mmxext 38 0.3653 libc-2.3.5.so _int_malloc 36 0.3461 libmythtv-0.19.so.0.19.0 motion_reuse_420 31 0.2980 libmythtv-0.19.so.0.19.0 MC_avg_x_16_mmxext 29 0.2788 libmythtv-0.19.so.0.19.0 i686.get_pc_thunk.bx 28 0.2692 libc-2.3.5.so free 26 0.2500 libmythtv-0.19.so.0.19.0 MC_avg_o_16_mmxext 26 0.2500 libmythtv-0.19.so.0.19.0 motion_zero_420 24 0.2307 libmythtv-0.19.so.0.19.0 MC_avg_y_16_mmxext 20 0.1923 libmythtv-0.19.so.0.19.0 mpeg2_idct_copy_mmxext 19 0.1827 libmythtv-0.19.so.0.19.0 MC_avg_xy_8_mmxext 19 0.1827 libstdc++.so.6.0.5 (no symbols) 18 0.1730 libmythtv-0.19.so.0.19.0 MC_avg_x_8_mmxext 18 0.1730 vmlinux get_offset_pmtmr 15 0.1442 vmlinux copy_to_user_ll 14 0.1346 libmythtv-0.19.so.0.19.0 MC_avg_y_8_mmxext 13 0.1250 libc-2.3.5.so memalign 11 0.1057 libc-2.3.5.so malloc 11 0.1057 libc-2.3.5.so malloc_consolidate 9 0.0865 libc-2.3.5.so _int_memalign 9 0.0865 libmythtv-0.19.so.0.19.0 .plt 9 0.0865 reiserfs (no symbols) 8 0.0769 uhci_hcd (no symbols) 8 0.0769 vmlinux ide_end_request 7 0.0673 ivtv (no symbols) 7 0.0673 libc-2.3.5.so _int_free 7 0.0673 libmythtv-0.19.so.0.19.0 DTVRecorder::FindKeyframes?(TSPacket const*) 7 0.0673 vmlinux schedule 6 0.0577 libpthread-2.3.5.so pthread_mutex_lock 6 0.0577 libpthread-2.3.5.so pthread_mutex_unlock 6 0.0577 vmlinux copy_from_user_ll 5 0.0481 libata (no symbols) 5 0.0481 libc-2.3.5.so tzfile_compute 5 0.0481 libmythavformat-0.19.so.0.19.0 mpegts_push_data 5 0.0481 vmlinux delay_pmtmr 4 0.0385 bash (no symbols) 4 0.0385 jbd (no symbols) 4 0.0385 libmythavcodec-0.19.so.0.19.0 av_parser_parse 4 0.0385 libmythavformat-0.19.so.0.19.0 av_read_frame_internal 4 0.0385 libmythavformat-0.19.so.0.19.0 url_fseek 3 0.0288 libc-2.3.5.so i686.get_pc_thunk.bx 3 0.0288 libmyth-0.19.so.0.19.0 .plt 3 0.0288 libmythavcodec-0.19.so.0.19.0 ff_combine_frame 3 0.0288 libmythavcodec-0.19.so.0.19.0 mpegvideo_parse 3 0.0288 libmythavformat-0.19.so.0.19.0 read_packet 3 0.0288 libmythtv-0.19.so.0.19.0 ProgramInfo::UpdateInUseMark?(bool) 3 0.0288 libmythtv-0.19.so.0.19.0 std::_Rb_tree<VideoFrame_ const*, std::pair<VideoFrame_ const* const, unsigned int>, std::_Select1st<std::pair<VideoFrame_ const* const, unsigned int> >, std::less<VideoFrame_ const*>, std::allocator<std::pair<VideoFrame_ const* const, unsigned int> > >::lower_bound(VideoFrame_ const* const&) 3 0.0288 snd_emu10k1 (no symbols) 3 0.0288 snd_pcm (no symbols) 3 0.0288 vmlinux d_lookup 3 0.0288 vmlinux do_softirq 3 0.0288 vmlinux add_wait_queue 3 0.0288 vmlinux do_select 3 0.0288 vmlinux fget 3 0.0288 vmlinux inode_update_time 3 0.0288 vmlinux ioread8 3 0.0288 vmlinux pci_bus_read_config_word 2 0.0192 ext3 (no symbols) 2 0.0192 libX11.so.6.2 (no symbols) 2 0.0192 libc-2.3.5.so getenv 2 0.0192 libc-2.3.5.so memset 2 0.0192 libmythavformat-0.19.so.0.19.0 .plt 2 0.0192 libmythavformat-0.19.so.0.19.0 av_destruct_packet 2 0.0192 libmythavformat-0.19.so.0.19.0 compute_pkt_fields 2 0.0192 libmythavformat-0.19.so.0.19.0 get_buffer 2 0.0192 libmythavformat-0.19.so.0.19.0 handle_packet 2 0.0192 libmythavformat-0.19.so.0.19.0 url_ftell 2 0.0192 libmythtv-0.19.so.0.19.0 AvFormatDecoder::GetFrame?(int) 2 0.0192 libmythtv-0.19.so.0.19.0 DeviceReadBuffer::fill_ringbuffer() 2 0.0192 libmythtv-0.19.so.0.19.0 NuppelVideoPlayer::ReleaseNextVideoFrame?(VideoFrame_*, long long) 2 0.0192 libmythtv-0.19.so.0.19.0 VideoBuffers::ReleaseFrame?(VideoFrame_*) 2 0.0192 libmythtv-0.19.so.0.19.0 VideoBuffers::size(BufferType?) const 2 0.0192 libmythtv-0.19.so.0.19.0 mpeg2_init_fbuf 2 0.0192 nvidia_drv.so _nv000970X 2 0.0192 via_rhine (no symbols) 2 0.0192 vmlinux block_prepare_write 2 0.0192 vmlinux link_path_walk 2 0.0192 vmlinux wake_up 2 0.0192 vmlinux wake_up_bit 2 0.0192 vmlinux bit_waitqueue 2 0.0192 vmlinux buffered_rmqueue 2 0.0192 vmlinux dput 2 0.0192 vmlinux fget_light 2 0.0192 vmlinux find_vma 2 0.0192 vmlinux path_lookup 2 0.0192 vmlinux sock_poll 2 0.0192 vmlinux system_call 2 0.0192 vmlinux unlock_page 2 0.0192 vmlinux vfs_read 1 0.0096 ISO8859-1.so (no symbols) 1 0.0096 Xorg BlockHandler? 1 0.0096 Xorg Dispatch 1 0.0096 dm_mod (no symbols) 1 0.0096 ipv6 (no symbols) 1 0.0096 ld-2.3.5.so do_lookup_x 1 0.0096 ld-2.3.5.so process_envvars 1 0.0096 libc-2.3.5.so libc_enable_asynccancel 1 0.0096 libc-2.3.5.so mktime_internal 1 0.0096 libc-2.3.5.so offtime 1 0.0096 libc-2.3.5.so tz_convert 1 0.0096 libc-2.3.5.so _dl_addr 1 0.0096 libc-2.3.5.so _int_realloc 1 0.0096 libc-2.3.5.so fwrite 1 0.0096 libc-2.3.5.so gettimeofday 1 0.0096 libc-2.3.5.so mblen 1 0.0096 libc-2.3.5.so memmove 1 0.0096 libc-2.3.5.so mktime 1 0.0096 libc-2.3.5.so putc 1 0.0096 libc-2.3.5.so strcmp 1 0.0096 libdl-2.3.5.so .fini 1 0.0096 libgcc_s-4.0.1-20050727.so.1 (no symbols) 1 0.0096 libm-2.3.5.so feholdexcept 1 0.0096 libmyth-0.19.so.0.19.0 AudioOutputBase::GetAudiotime?() 1 0.0096 libmyth-0.19.so.0.19.0 AudioOutputBase::_AddSamples(void*, bool, int, long long) 1 0.0096 libmyth-0.19.so.0.19.0 AudioOutputBase::audiofree(bool) 1 0.0096 libmythavcodec-0.19.so.0.19.0 a52_syncinfo 1 0.0096 libmythavcodec-0.19.so.0.19.0 av_fast_realloc 1 0.0096 libmythavformat-0.19.so.0.19.0 av_read_packet 1 0.0096 libmythavformat-0.19.so.0.19.0 mpegts_read_packet 1 0.0096 libmythtv-0.19.so.0.19.0 BusyWaitVideoSync::AdvanceTrigger?() 1 0.0096 libmythtv-0.19.so.0.19.0 DVBRecorder::ProcessTSPacket(TSPacket const&) 1 0.0096 libmythtv-0.19.so.0.19.0 DeviceReadBuffer::HandlePausing?() 1 0.0096 libmythtv-0.19.so.0.19.0 NuppelVideoPlayer::AutoCommercialSkip?() 1 0.0096 libmythtv-0.19.so.0.19.0 NuppelVideoPlayer::GetNextVideoFrame?(bool) 1 0.0096 libmythtv-0.19.so.0.19.0 OSD::Display() 1 0.0096 libmythtv-0.19.so.0.19.0 QMap<int, PIDFilterManager>::operator[](int const&) 1 0.0096 libmythtv-0.19.so.0.19.0 QMap<unsigned int, unsigned int>::operator[](unsigned int const&) 1 0.0096 libmythtv-0.19.so.0.19.0 RingBuffer::ReadBufAvail?() const 1 0.0096 libmythtv-0.19.so.0.19.0 RingBuffer::ReadFromBuf?(void*, int) 1 0.0096 libmythtv-0.19.so.0.19.0 SectionTracker::AddSection?(tablehead const*) 1 0.0096 libmythtv-0.19.so.0.19.0 VideoBuffers::queue(BufferType?) const 1 0.0096 libmythtv-0.19.so.0.19.0 VideoOutput::ShowPip?(VideoFrame_*, NuppelVideoPlayer?*) 1 0.0096 libmythtv-0.19.so.0.19.0 VideoOutputXv::hasIDCTAcceleration() const 1 0.0096 libmythtv-0.19.so.0.19.0 VideoSync::UpdateNexttrigger?() 1 0.0096 libmythtv-0.19.so.0.19.0 finalize_matrix 1 0.0096 libmythtv-0.19.so.0.19.0 mpeg2_header_picture_finalize 1 0.0096 libmythtv-0.19.so.0.19.0 mpeg2_header_slice_start 1 0.0096 libmythtv-0.19.so.0.19.0 mpeg2_parse_header 1 0.0096 libmythtv-0.19.so.0.19.0 std::_Rb_tree<unsigned char const*, std::pair<unsigned char const* const, void*>, std::_Select1st<std::pair<unsigned char const* const, void*> >, std::less<unsigned char const*>, std::allocator<std::pair<unsigned char const* const, void*> > >::lower_bound(unsigned char const* const&) 1 0.0096 libpthread-2.3.5.so i686.get_pc_thunk.bx 1 0.0096 libpthread-2.3.5.so pthread_cond_broadcast@@GLIBC_2.3.2 1 0.0096 libpthread-2.3.5.so pthread_mutex_trylock 1 0.0096 libpthread-2.3.5.so pthread_rwlock_rdlock 1 0.0096 libpthread-2.3.5.so read 1 0.0096 mysqld (no symbols) 1 0.0096 mythfrontend QMapPrivate<int, int>::clear(QMapNode<int, int>*) 1 0.0096 oprofiled (no symbols) 1 0.0096 vmlinux alloc_pages 1 0.0096 vmlinux find_get_block 1 0.0096 vmlinux generic_file_aio_write_nolock 1 0.0096 vmlinux might_sleep 1 0.0096 vmlinux mod_page_state 1 0.0096 vmlinux mod_timer 1 0.0096 vmlinux set_page_dirty_nobuffers 1 0.0096 vmlinux _atomic_dec_and_lock 1 0.0096 vmlinux activate_page 1 0.0096 vmlinux blk_rq_map_sg 1 0.0096 vmlinux cfq_kick_queue 1 0.0096 vmlinux copy_to_user 1 0.0096 vmlinux cpu_idle 1 0.0096 vmlinux del_timer 1 0.0096 vmlinux do_page_fault 1 0.0096 vmlinux do_poll 1 0.0096 vmlinux do_pollfd 1 0.0096 vmlinux do_wp_page 1 0.0096 vmlinux dvb_dmxdev_buffer_read 1 0.0096 vmlinux find_lock_page 1 0.0096 vmlinux find_or_create_page 1 0.0096 vmlinux free_hot_cold_page 1 0.0096 vmlinux generic_file_buffered_write 1 0.0096 vmlinux inotify_inode_queue_event 1 0.0096 vmlinux kfree 1 0.0096 vmlinux kmem_cache_alloc 1 0.0096 vmlinux kmem_cache_free 1 0.0096 vmlinux math_state_restore 1 0.0096 vmlinux mempool_alloc 1 0.0096 vmlinux permission 1 0.0096 vmlinux pte_alloc_map 1 0.0096 vmlinux remove_wait_queue 1 0.0096 vmlinux rw_verify_area 1 0.0096 vmlinux schedule_timeout 1 0.0096 vmlinux shrink_cache 1 0.0096 vmlinux shrink_list 1 0.0096 vmlinux sock_sendmsg 1 0.0096 vmlinux sys_gettimeofday 1 0.0096 vmlinux sys_poll 1 0.0096 vmlinux sys_write 1 0.0096 vmlinux test_set_page_writeback 1 0.0096 vmlinux tty_ldisc_deref 1 0.0096 vmlinux unix_poll 1 0.0096 vmlinux unix_stream_sendmsg 1 0.0096 vmlinux unqueue_me 1 0.0096 vmlinux usb_hcd_irq 1 0.0096 vmlinux zap_pte_range

comment:9 Changed 14 years ago by dejongm@…

what --event did you use and for how long did you profile? I'm not getting any data.

thx, Mark

comment:10 Changed 14 years ago by sberlo9@…

I didn't use --event. I pretty much followed the instructions from the link above. I did notice, after looking through that page today, that I did not use make -j2 -k as it says to do when recompiling - not sure what that does, but I just did the normal 'make'. I let it run for 15-30 sec. The problem is a constant one for me, not just when the OSD is on - although it does seem worse when the OSD is on. Sorry I'm probably not much help! I have never profiled before - luckily for me it just worked ;)

Steve

comment:11 Changed 14 years ago by Mark de Jong <dejongm@…>

I've recompiled both mythv and oprofile and just seem to be getting nowhere. I've sampled up to 2 minutes but nothing.. This is my /var/lib/oprofile/oprofiled.log:

oprofiled started Wed Dec 21 21:42:28 2005
kernel pointer size: 4
Received SIGHUP.

Wed Dec 21 21:43:25 2005

Nr. sample dumps: 65
Nr. non-backtrace samples: 217310
Nr. kernel samples: 592
Nr. lost samples (no kernel/user): 0
Nr. lost kernel samples: 0
Nr. incomplete code structs: 0
Nr. samples lost due to sample file open failure: 0
Nr. samples lost due to no permanent mapping: 32
Nr. event lost due to buffer overflow: 0
Nr. samples lost due to no mapping: 48
Nr. backtraces skipped due to no file mapping: 0
Nr. samples lost due to no mm: 13
Nr. samples lost cpu buffer overflow: 352923
Nr. samples received: 2035941
Nr. backtrace aborted: 0
Nr. samples lost cpu buffer overflow: 215109
Nr. samples received: 1501057
Nr. backtrace aborted: 0
oprofiled stopped Wed Dec 21 21:43:25 2005

Any idea?

comment:12 Changed 14 years ago by sberlo9@…

My /var/lib/oprofile/oprofiled.log file looks very similar to yours. The data from above comes from the output of opreport. I ran 'opcontrol --reset ; opcontrol --start' right after starting Live TV and seeing the problem, then 'opcontrol --shutdown' after 15-30 secs, and then ' opreport --merge tgid -l --image-path=/usr/local/bin > report.txt' to generate the report, which is what is posted above (BTW, I realize now it looks like a mess - something with the LF/CR's in what I copied & pasted - let me know if I should re-post it in a more legible format!). Hope that helps! Steve

comment:13 Changed 14 years ago by danielk

Resolution: invalid
Status: reopenedclosed

Ok, it looks like there hasn't been any spike in CPU usage. If the you are using OpenGL VSync with the framerate matching the video frame rate, then video frames are getting dropped simply because your audio device is playing the sound more slowly than it should.

Since you've use video as timebase, and have extra audio buffering, then the next thing to do is install a new sound card. Unfortunately, this type of hardware issues are pretty much impossible to fix remotely.

FYI If you use bob-deinterlace the dropped frames should be less noticable unless an OSD is on screen.

[marking as invalid, broken hardware].

comment:14 Changed 14 years ago by Mark de Jong <dejongm@…>

Resolution: invalid
Status: closedreopened

Sorry. Please play again.. :) Not sure if you were refering to my post or Steves. This doesn't explain why it works when watching a recorded program and not live TV. Also, new data point: Pressing pause, and unpausing make the problem go away.

--Mark

comment:15 Changed 14 years ago by sberlo9@…

My issue may be a little different. Pressing pause and unpausing does nothing for my problem. However, I have a remote frontend PC running the same distro/kernel/nvidia card, etc. and it plays smoother 720p content without the 'video is ahead of audio' messages (just some prebuffering pauses becuase it is a P4 1.7Ghz - not quite enough for HD!) Since there is a CT4380 SBLive in the working frontend and a SB0100 SBLive in the non-working one, I swapped cards to see if the problem follows the sound card and it did not. I also tried the onboard card and that did not help either. This is where I get confused: I have a copy of SVN 8205 that I *thought* worked well and I installed that - same problem with 720p video! I will try and go back farther and install an older SVN than 8205 to see if I can see about when the problem starts. Also, recorded 720p shows seem to have the problem all of a sudden, which leads me to believe that I have done something to cause this - they used to play fine! Anyway, I will try some more things to see if I can pinpoint it. If anyone has suggestions on what I should try that would help solve this, let me know!!

Steve

comment:16 Changed 14 years ago by danielk

Milestone: unknown0.20
Resolution: invalid
Status: reopenedclosed

A P4 1.7 Ghz is about 1100 Mhz to slow for 1080i, and LiveTV does consume more resources than watching a pre-recorder program.

comment:17 Changed 14 years ago by anonymous

Resolution: invalid
Status: closedreopened

I feel like my ticket's been hijacked. :) I've got a 2.8 Ghz and HD works fine, except with _some live TV . As stated earlier, the problem goes away when I pause/unpause.

comment:18 Changed 14 years ago by sberlo9@…

Hmmm..... First, I apologize if I have hijacked your ticket - my symptoms seem like they are very similar to yours and I was hoping to help find the problem. Second, I see where I may not have been very clear so I will try to clarify. I have 2 Myth boxes. One is a 3Ghz P4 that has been plenty fast for live TV (both 720p and 1080i) in the past - somewhere *after* the live TV rewrite (it worked in version since the rewrite, just not recent versions), it started having the problems described on this ticket. The slower 1.7Ghz PC will play the 720p content *better* than the faster PC - So, I moved the soundcard from the 1.7Ghz PC to the faster PC (which didn't fix anything) to prove that the soundcard was not the issue. Having said the above, I strongly believe that this is *not* a hardware problem. I'll be happy to try things and post results if you want.

comment:19 Changed 14 years ago by anonymous

Very interesting. I get this when I attempt to view an HD channel on my Nexus-s (like PBSHD on AMC3). Normal SD streams work fine, however. I changed out for a budget card and the problem went away (and was replace with a dozen more). I just assumed it was because the Nexus can't handle the stream. Some people say it can and others say it can't I don't know who to believe. It would be nice if I could use my Nexus again for all channels, SD and HD.

comment:20 Changed 14 years ago by Mark de Jong <dejongm@…>

New datapoint: SVN 8474. I don't need to use XVMC but I like to use it to relieve the proc of some cycles. I tried XvMC again and initially when tuning a DVB channel I get a lot of prebuffering pauses. But when I pause/unpause the prebuffering pauses dramatically decrease and playback is fine.

Since pausing/unpausing also fixes the dropped frames issue, I figure this may vary likely be related. Is it maybe the audio and video isn't synced upon tuning to the channel and pausing to stream puts them in sync. I'm only guessing of course, but that seems to be the issue.

Again, whatever I can do to assist in fixing this I'll be happy to help the best I can.

comment:21 Changed 14 years ago by rob.r@…

I think I'm having similar issues to those described here. I have the problem primarily with 720p streams. 1080i ones usually play fine (but may flare up with some prebuffering pauses). Happens on all 3 of my frontends (all NVidia cards ... tried versions 7676 and latest Nvidia drivers), 3ghz P4s. The CPU usage doesn't seem to be the issue. It can even happen with the PVR-250 sdtv streams. It seems like a synchronization mechanism is "flapping" while trying to get in sync. I've tried just about every option ... enabling/disabling interlacing, sync to video, opengl, all without any significant improvement.

comment:22 Changed 14 years ago by Markjan deJong <dejongm@…>

Given, that things have gotten worse, can I attach a sample mpeg for you guys too work with to see if you can reproduce on your end? Is so, how much would you need and can I dd the specified length off the file in question?

comment:23 Changed 14 years ago by rob.r@…

On my boxes, I found a strange "fix" for my stuttering problems.

In mythtv/libs/libmythtv/NuppelVideoPlayer.cpp

near line 4719 change:

if (videoOutput->EnoughDecodedFrames?())

to:

if (videoOutput->EnoughPrebufferedFrames?())

(or just remove the ifdef block so it always reads EnoughPrebufferedFrames?())

comment:24 Changed 14 years ago by danielk

Rob, that fix is something that was rolled back because it caused problems on slow processors for non-HDTV playback. But this would indicate that ChromaKey? OSD would help XvMC (ticket #512), and perhaps the patch in #900 would help everyone else.

comment:25 Changed 14 years ago by rob.r@…

I've recently started using Chroma Key OSD, and that works very well for me, but doesn't seem to have much effect one way or the other on my prebuffering pause problem.

On my systems, with the SVN version (8696, before xvmc broke, anyway), one of two things happens: Either it gets in a good stride and is able to play 720p video great for a long time, or it struggles, prebuffering pause over and over sometimes several times a second. I can sometimes pause and unpause (or do a seek) and it will begin working correctly again. CPU usage is around 35%. Since there's more problems with 720p than 1080i, I think it's more of a synchronization problem, and not a CPU load issue.

All I'm patching is that one line of code, not enabling the whole "FAST_RESTART" warp audio thing. That tells me that "unpausing" must be too latent on my system for some reason.

Changed 14 years ago by Mark de Jong <dejongm@…>

Attachment: mythfe-012706.log added

comment:26 Changed 14 years ago by Mark de Jong <dejongm@…>

Attached latest FE log from SVN 8742. Robs code change did not have any effect.

comment:27 Changed 14 years ago by danielk

Milestone: 0.200.19
Summary: Dropped frames on playback while playing DVBXvMC LiveTV playback broken

comment:28 Changed 14 years ago by danielk

Resolution: fixed
Status: reopenedclosed

(In [8769]) Fixes #774.

The "Extra Audio Bufferring" option was not being passed to a decoder unless the video output needed to change. Fixing this combined with the prebuffering fix from rob.r appears to fix the problem entirely. I also added an override for the extra audio buffering so that it is always applied if you use XvMC output; it also prints a message if you do not have the setting enabled by default and are using XvMC.

comment:29 Changed 14 years ago by danielk

(In [8770]) References #774.

comment:30 Changed 14 years ago by anonymous

Daniel, thanks for getting my tweak committed. However, don't think it went in quite the way you intended:

You've added:

if ((videoOutput->hasMCAcceleration()
videoOutput->hasIDCTAcceleration()

videoOutput->hasVLDAcceleration()) &&

(videoOutput->EnoughDecodedFrames?()))

{

SetPrebuffering?(false);

}

#if FAST_RESTART

if (videoOutput->EnoughPrebufferedFrames?())

SetPrebuffering?(false);

#else

if (videoOutput->EnoughDecodedFrames?())

SetPrebuffering?(false);

#endif

where I think you meant to put "EnoughPrebufferedFrames?()" in the "is MC-enabled?" if block. Otherwise it we end up with "if EnoughDecodedFrames?()" whether MC is enabled or not.

comment:31 Changed 14 years ago by danielk

(In [8797]) References #774. Properly apply rob.r's fix...

Note: See TracTickets for help on using tickets.