[mythtv-users] [Fwd: Extremely (!) Choppy Video/Audio After Fast Forward/Rewind (HD) - Transcode Error?] Please Help

Martin Lynch martin.lynch at shaw.ca
Thu Jan 31 23:00:23 UTC 2008 

Anyone??? Getting desperate...

-------- Original Message --------
Subject: 	Extremely (!) Choppy Video/Audio After Fast Forward/Rewind 
(HD) - Transcode Error?
Date: 	Mon, 28 Jan 2008 00:20:33 -0800
From: 	Martin Lynch <martin.lynch at shaw.ca>
Reply-To: 	martin.lynch at shaw.ca
To: 	mythtv-users at mythtv.org



For the last few weeks, all HD shows I record play perfectly until I 
fast forward or rewind (or a commercial skips). After that, the video is 
EXTREMELY choppy and the audio and video get completely out of sync. 
There is nothing I seem able to do except exit the recording and start 
again. Even when I disable commercial skip, at some random point in the 
recording the audio will make a loud and high-pitched pop and the 
choppiness begins.

One thing I noticed is that transcoding now fails on any HD program. 
Sometimes the error message refers to "error code: 252". Other times 
it's "159". I've searched everywhere I can think to search and cannot 
find out what these error codes refer to.

Meanwhile, kern.log reports:

kernel: [320954.701614] mythtranscode[25615]: segfault at 
0000000001ac1000 rip 00002b3d50ad2665 rsp 00007fff5b576088 error 6

I've checked and repaired mythconverg (no repairs made and all seems 
OK). I've tried different transcoder settings. I've tried different 
playback settings. I've gone for long walks to quell the urge to 
physically assault my machine. I've rebooted, re-queued, re-recorded.

Here's the output from mythfrontend -v playback:

$ mythfrontend -v playback
...
2008-01-27 18:03:08.394 MythPlugin::init() dlerror: 
/usr/local/lib/mythtv/plugins/libmythdvd.so: undefined symbol: 
_ZN18ConfigurationGroup6byNameE7QString
2008-01-27 18:03:08.394 Unable to initialize plugin 'mythdvd'.
...
2008-01-27 18:03:12.825 Failed to run 'cdrecord --scanbus -dev=ATAPI'
...
SIP listening on IP Address 192.168.1.100:5060 NAT address 192.168.1.100
SIP: Cannot register; proxy, username or password not set
....

2008-01-27 18:03:21.523 
RingBuf(/mnt/raid/mythtv/recordings/1214_20080127120000.mpg): 
OpenFile(/mnt/raid/mythtv/recordings/1214_20080127120000.mpg, 12)
2008-01-27 18:03:21.538 
RingBuf(/mnt/raid/mythtv/recordings/1214_20080127120000.mpg): 
CalcReadAheadThresh(11157 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2008-01-27 18:03:21.770 AFD: Stream #0, has id 0x1984 codec id 
MPEG2VIDEO, type Video, bitrate 25000000 at 0x0x91e1e0
2008-01-27 18:03:21.772 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) 
rend(xv-blit) osd(softblend) osdfade(enabled) 
deint(bobdeint,linearblend) filt()
2008-01-27 18:03:21.772 VDP: LoadBestPreferences(2048x2048, 0)
2008-01-27 18:03:21.772 VDP: LoadBestPreferences(2048x2048, 60)
2008-01-27 18:03:21.772 VDP: LoadBestPreferences(1920x1080, 60)
2008-01-27 18:03:21.795 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) 
rend(xv-blit) osd(softblend) osdfade(enabled) 
deint(bobdeint,linearblend) filt()
...
2008-01-27 18:03:21.858 AFD: InitVideoCodec() 0x91e2e0 id(MPEG2VIDEO) 
type (Video).
2008-01-27 18:03:21.859 detectInterlace(Detect Scan, Interlaced Scan, 
29.97, 1088) ->Interlaced Scan
2008-01-27 18:03:21.859 AFD: Using ffmpeg for video decoding
2008-01-27 18:03:21.859 AFD: Looking for decoder for MPEG2VIDEO
2008-01-27 18:03:21.859 AFD: Opened codec 0x91e2e0, id(MPEG2VIDEO) 
type(Video)
2008-01-27 18:03:21.859 AFD: Stream #1, has id 0x1985 codec id AC3, type 
Audio, bitrate 384000 at 0x0x91e860
2008-01-27 18:03:21.859 AFD: Looking for decoder for AC3
2008-01-27 18:03:21.859 AFD: Opened codec 0x91ad70, id(AC3) type(Audio)
2008-01-27 18:03:21.859 
RingBuf(/mnt/raid/mythtv/recordings/1214_20080127120000.mpg): 
CalcReadAheadThresh(11157 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2008-01-27 18:03:21.861 Opening ALSA audio device 'default'.
2008-01-27 18:03:22.268 Dec: Trying to select track (w/lang)
2008-01-27 18:03:22.268 Dec: Selecting first track
2008-01-27 18:03:22.268 Dec: Selected track #1 in the Unknown language(0)
2008-01-27 18:03:22.268 Resyncing position map. posmapStarted = 0 
livetv(0) watchingRec(0)
2008-01-27 18:03:22.399 Position map filled from DB to: 377597
2008-01-27 18:03:22.400 SyncPositionMap prerecorded, from DB: 25879 entries
2008-01-27 18:03:22.401 SyncPositionMap, new totframes: 377597, new 
length: 12599, posMap size: 25879
2008-01-27 18:03:22.401 AFD: Position map found
2008-01-27 18:03:22.401 AFD: Successfully opened decoder for file: 
"/mnt/raid/mythtv/recordings/1214_20080127120000.mpg". novideo(0)
2008-01-27 18:03:22.469 VideoOutput: Allowed renderers: 
opengl,xv-blit,xshm,xlib
2008-01-27 18:03:22.469 VideoOutput: Allowed renderers (filt: ffmpeg): 
xlib,xshm,xv-blit,opengl
2008-01-27 18:03:22.470 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) 
rend(xv-blit) osd(softblend) osdfade(enabled) 
deint(bobdeint,linearblend) filt()
2008-01-27 18:03:22.470 VDP: LoadBestPreferences(2048x2048, 0)
2008-01-27 18:03:22.470 VDP: LoadBestPreferences(2048x2048, 60)
2008-01-27 18:03:22.471 VDP: LoadBestPreferences(1920x1088, 60)
2008-01-27 18:03:22.471 VideoOutput: Preferred renderer: xv-blit
2008-01-27 18:03:22.471 VideoOutput: Trying video renderer: xv-blit
2008-01-27 18:03:22.471 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) 
rend(xv-blit) osd(softblend) osdfade(enabled) 
deint(bobdeint,linearblend) filt()
2008-01-27 18:03:22.471 VDP: LoadBestPreferences(2048x2048, 0)
2008-01-27 18:03:22.471 VDP: LoadBestPreferences(2048x2048, 60)
2008-01-27 18:03:22.476 VideoOutputXv: ctor
2008-01-27 18:03:22.477 XOff: 0, YOff: -5
2008-01-27 18:03:22.477 VDP: LoadBestPreferences(1920x1088, 60)
...
2008-01-27 18:03:22.484 VideoOutputXv: InitSetupBuffers() render: 
xv-blit, allowed: opengl,xv-blit,xshm,xlib
2008-01-27 18:03:22.485 VideoOutputXv: @ j=3 Looking for flag[s]: 
XvInputMask XvImageMask
2008-01-27 18:03:22.485 VideoOutputXv: Adaptor#0: NV17 Video Texture has 
flag[s]: XvInputMask XvImageMask
2008-01-27 18:03:22.485 VideoOutputXv: Grabbed xv port 355
2008-01-27 18:03:22.485 VideoOutputXv: XVideo surface found on port 355
2008-01-27 18:03:22.485 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video 
Texture'
2008-01-27 18:03:22.485 VideoOutputXv: XVideo Format #0 is 'YUY2'
2008-01-27 18:03:22.485 VideoOutputXv: XVideo Format #1 is 'YV12'
2008-01-27 18:03:22.485 VideoOutputXv: XVideo Format #2 is 'UYVY'
2008-01-27 18:03:22.485 VideoOutputXv: XVideo Format #3 is 'I420'
2008-01-27 18:03:22.485 VideoOutputXv: Using XVideo Format 'I420'
2008-01-27 18:03:22.485 VideoOutputXv: CreateShmImages(32): video_dim: 
1920x1088
...
2008-01-27 18:03:22.679 VDP: SetVideoRenderer(xv-blit)
2008-01-27 18:03:22.679 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
X Error: BadMatch (invalid parameter attributes) 8
  Major opcode:  141
  Minor opcode:  14
  Resource id:  0x20d
2008-01-27 18:03:22.696 VideoOutputXv: Chromakeying not possible with 
this XVideo port.
...
2008-01-27 18:03:26.162 TV: StartPlayer(): took 4531 ms to start player.
2008-01-27 18:03:26.163 TV: Changing from None to WatchingPreRecorded
2008-01-27 18:03:26.162 VideoBuffers::DiscardFrames(0): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-01-27 18:03:26.173 VideoBuffers::DiscardFrames(0): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-01-27 18:03:26.173 VideoOutputXv: DiscardFrames() 3: 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:26.194 Using realtime priority.
2008-01-27 18:03:26.214 AFD: DoFastForward(122182 (1), do discard frames)
2008-01-27 18:03:26.215 Dec: DoFastForward(122182 (1), do discard frames)
2008-01-27 18:03:26.214 VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
2008-01-27 18:03:26.215 AFD: SeekReset(122189, 0, do flush, do discard)
2008-01-27 18:03:26.215 AFD: SeekReset() flushing
2008-01-27 18:03:26.215 VideoOutputXv: DiscardFrames(1)
2008-01-27 18:03:26.215 VideoBuffers::DiscardFrames(1): 
UAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-01-27 18:03:26.215 VideoBuffers::DiscardFrames(): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:26.215 VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-01-27 18:03:26.215 VideoOutputXv: DiscardFrames() 3: 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:26.215 NVP: ClearAfterSeek(0)
2008-01-27 18:03:26.225 Using deinterlace method bobdeint
2008-01-27 18:03:26.326 nVidiaVideoSync: VBlank ioctl did not work, 
unimplemented in this driver?
2008-01-27 18:03:26.327 DRMVideoSync: Could not open device 
/dev/dri/card0, No such file or directory
2008-01-27 18:03:26.327 OpenGLVideoSync()
2008-01-27 18:03:26.327 ~OpenGLVideoSync() -- begin
2008-01-27 18:03:26.327 ~OpenGLVideoSync() -- end
2008-01-27 18:03:26.327 RTCVideoSync: Could not set RTC frequency, 
Permission denied.
2008-01-27 18:03:26.327 Set video sync frame interval to 33366
2008-01-27 18:03:26.327 Video sync method can't support double framerate 
(refresh rate too low for bob deint)
2008-01-27 18:03:26.327 Set video sync frame interval to 33366
2008-01-27 18:03:26.327 VDP: GetFilteredDeint(linearblend) : xv-blit -> 
'linearblend'
2008-01-27 18:03:26.328 Using deinterlace method linearblend
2008-01-27 18:03:26.328 Using audio as timebase
2008-01-27 18:03:26.328 Video timing method: USleep with busy wait
2008-01-27 18:03:26.328 Refresh rate: 16685, frame interval: 33366
2008-01-27 18:03:26.328 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-01-27 18:03:26.462 NVP: Waiting for prebuffer.. 1 
AAUUUUUuULLAAAAAAAAAAAAAAAAAAAA
2008-01-27 18:03:26.476 Dec: Selected track #1 in the Unknown language(0)
2008-01-27 18:03:26.626 NVP: Video is 30 frames ahead of audio,
                        doubling video frame interval to slow down.
-----------------------------------------
Snipped about 400 lines of this
-----------------------------------------
2008-01-27 18:03:35.034 WriteAudio: buffer underrun
2008-01-27 18:03:35.088 NVP: Play(  3.0, normal 0, unpause audio 1)
2008-01-27 18:03:35.100 NVP: Video is 30 frames ahead of audio,
                        doubling video frame interval to slow down.
2008-01-27 18:03:35.089 NVP: Changing speed to 3
2008-01-27 18:03:35.128 
RingBuf(/mnt/raid/mythtv/recordings/1214_20080127120000.mpg): 
CalcReadAheadThresh(11157 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2008-01-27 18:03:35.129 NVP: DoPlay: rate: 29.97 speed: 3 skip: 1 => new 
interval 11122
2008-01-27 18:03:35.129 VDP: LoadBestPreferences(1920x1088, 89.9101)
2008-01-27 18:03:35.129 Set video sync frame interval to 11122
2008-01-27 18:03:35.689 NVP: Play(  1.0, normal 1, unpause audio 1)
2008-01-27 18:03:35.696 NVP: Changing speed to 1
2008-01-27 18:03:35.696 
RingBuf(/mnt/raid/mythtv/recordings/1214_20080127120000.mpg): 
CalcReadAheadThresh(11157 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2008-01-27 18:03:35.696 NVP: DoPlay: rate: 29.97 speed: 1 skip: 1 => new 
interval 33366
2008-01-27 18:03:35.696 VDP: LoadBestPreferences(1920x1088, 29.97)
2008-01-27 18:03:35.699 Set video sync frame interval to 33366
2008-01-27 18:03:35.699 NVP: Stretch Factor 1, allow passthru
2008-01-27 18:03:35.710 NVP: Video is 30 frames ahead of audio,
                        doubling video frame interval to slow down.
2008-01-27 18:03:35.743 VideoOutputXv: UpdatePauseFrame() 
UuLAUAUAUAUAUAAUAUAUAUAUAUAUUUU
2008-01-27 18:03:35.745 AFD: DoRewind(122272, do discard frames)
2008-01-27 18:03:35.745 Dec: DoRewind(122272 (122380), do discard frames)
2008-01-27 18:03:35.745 AFD: SeekReset(122259, 0, do flush, do discard)
2008-01-27 18:03:35.745 AFD: SeekReset() flushing
2008-01-27 18:03:35.745 VideoOutputXv: DiscardFrames(1)
2008-01-27 18:03:35.745 VideoBuffers::DiscardFrames(1): 
UUAAUAUAUAUAUAAUAUAUAUAUAUAUUUU
2008-01-27 18:03:35.745 VideoBuffers::DiscardFrames(): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:35.745 VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-01-27 18:03:35.745 VideoOutputXv: DiscardFrames() 3: 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:35.745 NVP: ClearAfterSeek(1)
2008-01-27 18:03:35.745 VideoOutputXv: ClearAfterSeek()
...
2008-01-27 18:03:35.746 WriteAudio: buffer underrun
2008-01-27 18:03:35.754 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-01-27 18:03:35.898 NVP: Video is 30 frames ahead of audio,
                        doubling video frame interval to slow down.
-------------------------------------------
Snipped another 400 lines of this
-------------------------------------------
2008-01-27 18:03:41.110 WriteAudio: buffer underrun
2008-01-27 18:03:41.115 TV: StopStuff(): stopping player[s] (1/2)
2008-01-27 18:03:41.115 TV: StopStuff(): stopping player[s] (2/2)
2008-01-27 18:03:41.123 NVP: Exited decoder loop.
2008-01-27 18:03:41.176 NVP: Video is 30 frames ahead of audio,
                        doubling video frame interval to slow down.
2008-01-27 18:03:41.176 VideoOutputXv: dtor
2008-01-27 18:03:41.176 VideoOutputXv: DiscardFrames(1)
2008-01-27 18:03:41.176 VideoBuffers::DiscardFrames(1): 
UULUUUUUUUUUUuUUUUUUUAUUUUUUUUU
2008-01-27 18:03:41.177 VideoBuffers::DiscardFrames(): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:41.177 VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-01-27 18:03:41.177 VideoOutputXv: DiscardFrames() 3: 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:41.177 VideoOutputXv: DiscardFrames(1)
2008-01-27 18:03:41.177 VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-01-27 18:03:41.177 VideoBuffers::DiscardFrames(): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:41.177 VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-01-27 18:03:41.177 VideoOutputXv: DiscardFrames() 3: 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-01-27 18:03:41.181 VideoOutputXv: Closing XVideo port 355
2008-01-27 18:03:41.221 TV: StopStuff() -- end
2008-01-27 18:03:41.221 TV: Changing from WatchingPreRecorded to None
Destroying SipFsm object

Anyone have any suggestions? Also, what are the suggested 
transcoders/settings for HD?

I noticed:

X Error: BadMatch (invalid parameter attributes) 8
  Major opcode:  141
  Minor opcode:  14
  Resource id:  0x20d

Some time ago but again couldn't find any info on what this means.

My system:

Asus M2N32-SLI Deluxe
4 X 1GB OCZ RAM
AMD X2 6400+
1 X 320GB Seagate HDD which mythtv resides on
2 X 320GB Seagate HDD in a RAID 0 array which recordings reside on
NVidia 7600 GS
Firewire connection to Motorola 6200 HD STB
Connected via DVI to 37" 1080p monitor

I'm running the SVN version of myth.

My configuration log reads:

 ./configure --prefix=/mnt/raid/mythtv --disable-lirc 
--disable-joystick-menu --disable-dbox2 --disable-hdhomerun 
--enable-xvmc-opengl --enable-opengl-video --enable-opengl-vsync 
--with-bindings=perl

More information about the mythtv-users mailing list