[mythtv-users] video plays back too fast

[Henning]

Hi guys,

On my MythTV (my first install so I might be doing something wrong!) setup,
recorded video simply plays back way too fast (but audio plays at normal
speed so gets completely desynced). I've been working at this for about 2
days now, so I've tried everything I could think of and made a number of
observations.

The frontend and backend are the same system. Here are its specs:
Celeron D 2.66 CPU
2GB RAM
7200RPM SATA HD
Intel motherboard with onboard sound and video (915)
BT878 based tuner

It's PAL, South Africa but I'm afraid I don't know much beyond that. Please
ask if any more info is needed here.

Video seems to play back at about 2 - 3x real time. This includes "live" tv,
which means it plays, pauses to build up buffer, plays a bit more, etc.
(again, all the while sound continues at normal speed (although it does seem
to pause along with the video))

CPU is at 50-60% idle while watching live tv.

On the recording selection screen, the preview video is perfect (normal
speed) which leads me to believe the problem may be related to sound (as the
preview window doesn't bother with sound) but that could of course be a red
herring.

I've tried with and without xvmc, opengl vsync, use video as timebase
I've tried kernel alsa and seperate built alsa drivers
I've tried resolutions and compression all the way from the top to the
bottom
I've tried with and without interlacing (bobdeint seemed to improve things
slightly, but it's definitely still too fast)

Playing TV in realtime (with tvtime) works flawlessly

Finally, the logs. First the backend:

Session management error: Authentication Rejected, reason : None of the
authentication protocols specified are supported and host-based
authentication failed
2006-05-08 21:03:27.744 Using runtime prefix 2006-05-08 21:03:27.760 New DB connection, total: 1
2006-05-08 21:03:27.767 Connected to database 'mythconverg' at host:
localhost
2006-05-08 21:03:27.771 Current Schema Version: 1123
Starting up as the master server.
2006-05-08 21:03:27.781 New DB connection, total: 2
2006-05-08 21:03:27.781 Connected to database 'mythconverg' at host:
localhost
2006-05-08 21:03:27.802 New DB connection, total: 3
2006-05-08 21:03:27.802 Connected to database 'mythconverg' at host:
localhost
2006-05-08 21:03:27.821 New DB scheduler connection
2006-05-08 21:03:27.821 Connected to database 'mythconverg' at host:
localhost
2006-05-08 21:03:27.830 mythbackend version: 0.19.20060121-2 www.mythtv.org
2006-05-08 21:03:27.831 Enabled verbose msgs: important general
2006-05-08 21:03:27.831 AutoExpire: Found 1 recorders w/max rate of 72
MiB/min
2006-05-08 21:03:27.832 AutoExpire: Required Free Space: 2.1 GB w/freq: 10
min
2006-05-08 21:03:30.138 Reschedule requested for id -1.
2006-05-08 21:03:30.191 Scheduled 10 items in 0.1 2006-05-08 21:03:30.196 Seem to be woken up by USER
2006-05-08 21:03:37.822 Running HouseKeeping
2006-05-08 21:03:41.571 MainServer::HandleAnnounce Monitor
2006-05-08 21:03:41.571 adding: vmserver as a client (events: 0)
2006-05-08 21:03:41.623 MainServer::HandleAnnounce Monitor
2006-05-08 21:03:41.623 adding: vmserver as a client (events: 1)
2006-05-08 21:03:41.635 MainServer::HandleAnnounce Playback
2006-05-08 21:03:41.635 adding: vmserver as a client (events: 0)
2006-05-08 21:03:41.649 TVRec(1): Changing from None to WatchingLiveTV
v4l2: unable to handle these sampling parameters
2006-05-08 21:03:41.785 NVR(/dev/v4l/video0) Error: Can't open vbi device:
'/dev/v4l/vbi0'
strange error flushing buffer ...
2006-05-08 21:04:06.265 TVRec(1): Changing from WatchingLiveTV to None
2006-05-08 21:04:06.637 Finished recording Kitchen Confidential "Aftermath":
channel 1003

And the frontend:

Session management error: Authentication Rejected, reason : None of the
authentication protocols specified are supported and host-based
authentication failed
2006-05-08 21:03:38.205 Using runtime prefix 2006-05-08 21:03:38.226 New DB connection, total: 1
2006-05-08 21:03:38.233 Connected to database 'mythconverg' at host:
localhost
2006-05-08 21:03:38.235 Total desktop dim: 1024x768, with 1 screen[s].
2006-05-08 21:03:38.238 Using screen 0, 1024x768 at 0,0
2006-05-08 21:03:38.243 Current Schema Version: 1123
2006-05-08 21:03:38.244 mythfrontend version: 0.19.20060121-2 www.mythtv.org
2006-05-08 21:03:38.244 Enabled verbose msgs: important general
2006-05-08 21:03:38.354 lang0: eng
2006-05-08 21:03:38.354 lang: eng
2006-05-08 21:03:38.360 lang1: afr
2006-05-08 21:03:38.360 lang: afr
2006-05-08 21:03:38.480 Total desktop dim: 1024x768, with 1 screen[s].
2006-05-08 21:03:38.481 Using screen 0, 1024x768 at 0,0
2006-05-08 21:03:38.483 Switching to square mode (G.A.N.T.)
2006-05-08 21:03:38.813 Registering Internal as a media playback plugin.
2006-05-08 21:03:41.513 New DB connection, total: 2
2006-05-08 21:03:41.514 Connected to database 'mythconverg' at host:
localhost
2006-05-08 21:03:41.547 Connecting to backend server: 127.0.0.1:6543 (try 1
of 5)
2006-05-08 21:03:41.560 Using protocol version 26
2006-05-08 21:03:41.599 TV: Attempting to change from None to WatchingLiveTV
2006-05-08 21:03:41.611 Using protocol version 26
2006-05-08 21:03:41.771 RingBuf(/mnt/store/1003_20060508210341.nuv): Invalid
file (fd 13) when opening '/mnt/store/1003_20060508210341.nuv'. 12 retries
remaining.
2006-05-08 21:03:42.385 Opening audio device '/dev/sound/dsp'.
2006-05-08 21:03:42.385 Opening OSS audio device '/dev/sound/dsp'.
2006-05-08 21:03:42.755 TV: Changing from None to WatchingLiveTV
2006-05-08 21:03:42.786 Using realtime priority.
2006-05-08 21:03:42.809 Video timing method: DRM
2006-05-08 21:03:43.236 NVP: prebuffering pause
2006-05-08 21:03:43.827 NVP: prebuffering pause
2006-05-08 21:03:44.344 NVP: prebuffering pause
2006-05-08 21:03:44.928 NVP: prebuffering pause
2006-05-08 21:03:45.492 NVP: prebuffering pause
2006-05-08 21:03:46.024 NVP: prebuffering pause
2006-05-08 21:03:46.593 NVP: prebuffering pause
2006-05-08 21:03:46.951 rebuffering (433 32768)
2006-05-08 21:03:47.089 NVP: prebuffering pause
2006-05-08 21:03:47.639 NVP: prebuffering pause
2006-05-08 21:03:48.231 NVP: prebuffering pause
2006-05-08 21:03:48.905 NVP: prebuffering pause
2006-05-08 21:03:49.415 NVP: prebuffering pause
2006-05-08 21:03:50.006 NVP: prebuffering pause
2006-05-08 21:03:50.585 NVP: prebuffering pause
2006-05-08 21:03:51.094 NVP: prebuffering pause
2006-05-08 21:03:51.685 NVP: prebuffering pause
2006-05-08 21:03:52.428 NVP: prebuffering pause
2006-05-08 21:03:52.951 NVP: prebuffering pause
2006-05-08 21:03:53.405 NVP: prebuffering pause
2006-05-08 21:03:53.928 NVP: prebuffering pause
2006-05-08 21:03:54.369 NVP: prebuffering pause
2006-05-08 21:03:54.892 NVP: prebuffering pause
2006-05-08 21:03:55.428 NVP: prebuffering pause
2006-05-08 21:03:56.006 NVP: prebuffering pause
2006-05-08 21:03:56.585 NVP: prebuffering pause
2006-05-08 21:03:57.162 NVP: prebuffering pause
2006-05-08 21:03:57.685 NVP: prebuffering pause
2006-05-08 21:03:58.265 NVP: prebuffering pause
2006-05-08 21:03:58.782 NVP: prebuffering pause
2006-05-08 21:03:59.364 NVP: prebuffering pause
2006-05-08 21:04:00.025 NVP: prebuffering pause
2006-05-08 21:04:00.428 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:00.437 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:00.438 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:00.443 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:00.450 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:00.455 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:00.644 NVP: prebuffering pause
2006-05-08 21:04:00.966 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:00.972 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:00.972 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:01.153 NVP: prebuffering pause
2006-05-08 21:04:01.526 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:01.531 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:01.537 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:01.732 NVP: prebuffering pause
2006-05-08 21:04:02.033 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.038 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.043 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.045 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.095 rebuffering (433 32768)
2006-05-08 21:04:02.323 NVP: prebuffering pause
2006-05-08 21:04:02.570 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.576 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.580 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.808 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.814 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.819 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.824 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.825 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.830 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:02.835 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:03.038 NVP: prebuffering pause
2006-05-08 21:04:03.350 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:03.355 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:03.548 NVP: prebuffering pause
2006-05-08 21:04:03.789 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:03.799 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:03.805 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:03.812 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:03.812 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:03.988 NVP: prebuffering pause
2006-05-08 21:04:04.315 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:04.322 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:04.327 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:04.500 NVP: prebuffering pause
2006-05-08 21:04:04.892 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:04.901 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:04.902 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:04.907 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:05.103 NVP: prebuffering pause
2006-05-08 21:04:05.398 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:05.403 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:05.410 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:05.612 NVP: prebuffering pause
2006-05-08 21:04:05.981 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:05.992 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:05.997 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:06.002 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:06.002 NVP::AddAudioData():p3: Audio buffer overflow, audio
data lost!
2006-05-08 21:04:06.204 NVP: prebuffering pause
2006-05-08 21:04:06.208 TV: Attempting to change from WatchingLiveTV to None
2006-05-08 21:04:06.685 TV: Changing from WatchingLiveTV to None

Please let me know if anything is missing, I'd be happy to post more info.

Many thanks,
Henning
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://mythtv.org/pipermail/mythtv-users/attachments/20060508/2857c258/attachment.htm