[mythtv-users] ABC Playback problems
Craig Treleaven
ctreleaven at cogeco.ca
Fri Oct 19 15:14:42 UTC 2007
Hi:
I've tried a changing settings, tried a bunch of stuff from the
archive, and I still have a problem playing HDTV material recorded
from ABC. Sorry for the length, I wanted to include some details.
The problem-
My wife is unhappy! She wants to watch Grey's Anatomy and Private Practice.
Audio lags behind video by about 2 seconds on high-def material
(720P) recorded from ABC (maddeningly, standard def commercials in
the same file play OK--the lag begins again when the program resumes).
VLC (0.8.6) plays the file OK.
Local PBS also broadcasts 720P but recordings play OK.
Environment-
Myth 0.20.2
FE/BE - Mac Intel Core 2 Duo 1.83 GHz mini under OSX 10.4.10,
recording from HDHomerun, OTA source
FE - Mac Intel Core Duo 1.66 GHz mini under OSX 10.4.10
Tried with no success-
Deinterlacing - off/Bob/Linear/One-field
MPEG2 decoder - standard/libmpeg2
Enable gamma correction for video - on/off
User vector enhance color space conversion - on/off
MythFrontend log extracts (remote FE)-
...
2007-10-19 10:16:01.832 mythfrontend version: 0.20.20070821-1 www.mythtv.org
2007-10-19 10:16:01.832 Enabled verbose msgs: important general libav playback
...
2007-10-19 10:16:02.196 Using screen 0, 1280x1024 at 0,0
2007-10-19 10:16:02.198 Switching to square mode (G.A.N.T.)
2007-10-19 10:16:02.212 Using the Qt painter
...
2007-10-19 10:16:22.790 TV: Attempting to change from None to
WatchingPreRecorded
2007-10-19 10:16:22.794
RingBuf(myth://192.168.2.222:6543/1071_20070926220100.mpg):
OpenFile(myth://192.168.2.222:6543/1071_20070926220100.mpg, 12)
2007-10-19 10:16:22.840
RingBuf(myth://192.168.2.222:6543/1071_20070926220100.mpg):
CalcReadAheadThresh(4000 KB)
-> threshhold(402 KB) min read(32 KB) blk size(64 KB)
mpegts_add_stream: at pid 0x31 with type 2
mpegts_add_stream: stream #0, has id 0x31 and codec MPEG2VIDEO, type
Video at 0x14e9430
mpegts_add_stream: at pid 0x34 with type 129
mpegts_add_stream: stream #1, has id 0x34 and codec AC3, type Audio
at 0x19bf2610
0: start_time: 3402.038 duration: 323.734
1: start_time: 3402.038 duration: 323.726
stream: start_time: 37800.417 duration: 3597.051 bitrate=15383 kb/s
2007-10-19 10:16:26.527 AFD: Stream #0, has id 0x49 codec id
MPEG2VIDEO, type Video, bitrate 65000000 at 0x0x14e9430
2007-10-19 10:16:26.530 detectInterlace(Detect Scan, Interlaced Scan,
59.9401, 720) ->Progressive Scan
2007-10-19 10:16:26.531 AFD: Looking for decoder for MPEG2VIDEO
2007-10-19 10:16:26.531 AFD: Opened codec 0x1885a00, id(MPEG2VIDEO) type(Video)
2007-10-19 10:16:26.531 AFD: Stream #1, has id 0x52 codec id AC3,
type Audio, bitrate 384000 at 0x0x19bf2610
2007-10-19 10:16:26.531 AFD: Looking for decoder for AC3
2007-10-19 10:16:26.532 AFD: Opened codec 0x1884a00, id(AC3) type(Audio)
2007-10-19 10:16:26.726
RingBuf(myth://192.168.2.222:6543/1071_20070926220100.mpg):
CalcReadAheadThresh(65384 KB)
-> threshhold(2650 KB) min read(32 KB) blk
size(512 KB)
2007-10-19 10:16:26.910 Dec: Trying to select track (w/lang)
2007-10-19 10:16:26.910 Dec: Selecting first track
2007-10-19 10:16:26.910 Dec: Selected track #1 in the Unknown language(0)
2007-10-19 10:16:26.911 Resyncing position map. posmapStarted = 0
livetv(0) watchingRec(0)
2007-10-19 10:16:27.115 Position map filled from DB to: 119045
2007-10-19 10:16:27.116 SyncPositionMap prerecorded, from DB: 6282 entries
2007-10-19 10:16:27.117 SyncPositionMap, new totframes: 119045, new
length: 1986, posMap size: 6282
Input #0, mpegts, from 'myth://192.168.2.222:6543/1071_20070926220100.mpg':
Duration: 00:59:57.0, start: 37800.417233, bitrate: 15383 kb/s
Stream #0.0[0x31], 1/90000: Video: mpeg2video, yuv420p, 1280x720,
1001/60000, 65000 kb/s, 59.94 fps(r)
Stream #0.1[0x34], 1/90000: Audio: ac3, 48000 Hz, stereo, 384 kb/s
2007-10-19 10:16:27.117 AFD: Position map found
2007-10-19 10:16:27.117 AFD: Successfully opened decoder for file:
"myth://192.168.2.222:6543/1071_20070926220100.mpg". novideo(0)
2007-10-19 10:16:27.138 VideoOutputQuartz::Init(width=1280,
height=720, aspect=1.77778, winid=24
winx=0, winy=0, winw=1280, winh=1024, WId embedid=0)
2007-10-19 10:16:27.139 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
2007-10-19 10:16:27.139
VideoOutputQuartz::VideoAspectRatioChanged(aspect=1.77778) [was
1.97799e-23]
2007-10-19 10:16:27.139 Snapping width to avoid scaling: width: 1280, left: 0
2007-10-19 10:16:27.139 Display Rect left: 0, top: 128, width: 1280,
height: 768, aspect: 1.33333
2007-10-19 10:16:27.139 Video Rect left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2007-10-19 10:16:27.150 Created data @0x24201000->0x24352802
...
2007-10-19 10:16:22.790 TV: Attempting to change from None to
WatchingPreRecorded
2007-10-19 10:16:22.794
RingBuf(myth://192.168.2.222:6543/1071_20070926220100.mpg):
OpenFile(myth://192.168.2.222:6543/1071_20070926220100.mpg, 12)
2007-10-19 10:16:22.840
RingBuf(myth://192.168.2.222:6543/1071_20070926220100.mpg):
CalcReadAheadThresh(4000 KB)
-> threshhold(402 KB) min read(32 KB) blk size(64 KB)
mpegts_add_stream: at pid 0x31 with type 2
mpegts_add_stream: stream #0, has id 0x31 and codec MPEG2VIDEO, type
Video at 0x14e9430
mpegts_add_stream: at pid 0x34 with type 129
mpegts_add_stream: stream #1, has id 0x34 and codec AC3, type Audio
at 0x19bf2610
0: start_time: 3402.038 duration: 323.734
1: start_time: 3402.038 duration: 323.726
stream: start_time: 37800.417 duration: 3597.051 bitrate=15383 kb/s
2007-10-19 10:16:26.527 AFD: Stream #0, has id 0x49 codec id
MPEG2VIDEO, type Video, bitrate 65000000 at 0x0x14e9430
2007-10-19 10:16:26.530 detectInterlace(Detect Scan, Interlaced Scan,
59.9401, 720) ->Progressive Scan
2007-10-19 10:16:26.531 AFD: Looking for decoder for MPEG2VIDEO
2007-10-19 10:16:26.531 AFD: Opened codec 0x1885a00, id(MPEG2VIDEO) type(Video)
2007-10-19 10:16:26.531 AFD: Stream #1, has id 0x52 codec id AC3,
type Audio, bitrate 384000 at 0x0x19bf2610
2007-10-19 10:16:26.531 AFD: Looking for decoder for AC3
2007-10-19 10:16:26.532 AFD: Opened codec 0x1884a00, id(AC3) type(Audio)
2007-10-19 10:16:26.726
RingBuf(myth://192.168.2.222:6543/1071_20070926220100.mpg):
CalcReadAheadThresh(65384 KB)
-> threshhold(2650 KB) min read(32 KB) blk
size(512 KB)
2007-10-19 10:16:26.910 Dec: Trying to select track (w/lang)
2007-10-19 10:16:26.910 Dec: Selecting first track
2007-10-19 10:16:26.910 Dec: Selected track #1 in the Unknown language(0)
2007-10-19 10:16:26.911 Resyncing position map. posmapStarted = 0
livetv(0) watchingRec(0)
2007-10-19 10:16:27.115 Position map filled from DB to: 119045
2007-10-19 10:16:27.116 SyncPositionMap prerecorded, from DB: 6282 entries
2007-10-19 10:16:27.117 SyncPositionMap, new totframes: 119045, new
length: 1986, posMap size: 6282
Input #0, mpegts, from 'myth://192.168.2.222:6543/1071_20070926220100.mpg':
Duration: 00:59:57.0, start: 37800.417233, bitrate: 15383 kb/s
Stream #0.0[0x31], 1/90000: Video: mpeg2video, yuv420p, 1280x720,
1001/60000, 65000 kb/s, 59.94 fps(r)
Stream #0.1[0x34], 1/90000: Audio: ac3, 48000 Hz, stereo, 384 kb/s
2007-10-19 10:16:27.117 AFD: Position map found
2007-10-19 10:16:27.117 AFD: Successfully opened decoder for file:
"myth://192.168.2.222:6543/1071_20070926220100.mpg". novideo(0)
2007-10-19 10:16:27.138 VideoOutputQuartz::Init(width=1280,
height=720, aspect=1.77778, winid=24
winx=0, winy=0, winw=1280, winh=1024, WId embedid=0)
2007-10-19 10:16:27.139 Over/underscan. V: 0, H: 0, XOff: 0, YOff: 0
2007-10-19 10:16:27.139
VideoOutputQuartz::VideoAspectRatioChanged(aspect=1.77778) [was
1.97799e-23]
2007-10-19 10:16:27.139 Snapping width to avoid scaling: width: 1280, left: 0
2007-10-19 10:16:27.139 Display Rect left: 0, top: 128, width: 1280,
height: 768, aspect: 1.33333
2007-10-19 10:16:27.139 Video Rect left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2007-10-19 10:16:27.150 Created data @0x24201000->0x24352802
...
2007-10-19 10:16:29.387 NVP: Waiting for prebuffer.. 2
AUuLAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-10-19 10:16:29.453 NVP: Waiting for prebuffer.. 3
AUuLAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-10-19 10:16:29.520 NVP: Waiting for prebuffer.. 4
AUuLAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-10-19 10:16:29.586 NVP: Waiting for prebuffer.. 5
AUUuLAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-10-19 10:16:29.652 NVP: Waiting for prebuffer.. 6
AUUUUUUUUULLAAAAAAAAAAAAAAAAAAA
2007-10-19 10:16:29.682 NVP: progressive frame seen after 2 interlaced frames
2007-10-19 10:16:29.768 NVP: Video is 4.67542 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:29.768 Disabled deinterlacing
2007-10-19 10:16:29.772 NVP: Video is 8.82635 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:29.811 NVP: Video is 12.3891 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:29.848 NVP: Video is 15.241 frames ahead of audio,
doubling video frame interval to slow down.
[mpeg2video @ 0xc3313008]mb incr damaged
[mpeg2video @ 0xc3313008]Warning MVs not available
[mpeg2video @ 0xc3313008]concealing 160 DC, 160 AC, 160 MV errors
2007-10-19 10:16:29.870 Dec: Selected track #1 in the Unknown language(0)
2007-10-19 10:16:29.885 NVP: Video is 17.3049 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:29.922 NVP: Video is 19.0477 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:29.959 NVP: Video is 20.2949 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:29.996 NVP: Video is 21.455 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.032 NVP: Video is 22.2652 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.069 NVP: Video is 23.0826 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.105 NVP: Video is 23.6357 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.142 NVP: Video is 24.2604 frames ahead of audio,
doubling video frame interval to slow down.
...
2007-10-19 10:16:30.620 NVP: Video is 28.7772 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.657 NVP: Video is 29.1505 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.694 NVP: Video is 29.3854 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.731 NVP: Video is 29.7415 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.768 NVP: Video is 29.9486 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.805 NVP: Video is 30 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.841 NVP: Video is 30 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.878 NVP: Video is 30 frames ahead of audio,
doubling video frame interval to slow down.
2007-10-19 10:16:30.914 NVP: Video is 30 frames ahead of audio,
doubling video frame interval to slow down.
Observations-
I changed the setting to use the OpenGL painter but the log still
says QT? Do I have to stop/start the backend for that to take effect?
After prebuffer (10:16:29.652), video is already ahead 4 frames in
.116 seconds (at 10:16:29.768). At P60, it should only have played 7
frames at that point.
By 10:16:30.805, or 1.153 seconds into playback, video is ahead by 30
frames. After this, the log continues to report 30 frames ahead but
in reality it must be 100 or more.
I've omitted many MPEG2Video messages. My log was over 4,000 lines
for less than two minutes of playback.
Thoughts?
Craig
More information about the mythtv-users
mailing list