[mythtv-users] Transcode crashes: irrecoverable error in NVR::WriteAudio

glen martin lists at locutory.org
Tue Apr 18 22:26:41 UTC 2006


Noob error I suppose (I hope), but i don't see it in google or my
archive of this list.

Transcode is crashing.

How I got here:  I'm suspecting a bus bandwidth problem is causing
problems with playback on some HD recordings (including the classic
prebuffering pause). To test that theory, I thought to transcode those
programs to lower definition (since I don't have a 1080 monitor anyway).

I set up the autotranscode on the recording profile, set what I wanted
High Quality transcode to do (in this case, setting the encoder to MPEG4
and the resolution to my window size, and so forth). When I figure out
how I'll reset the encoder to MPEG2, so I can continue to benefit from
my hardware acceleration.

Myth version is 0.19.20060121-2

The stream is the MPEG2 that comes from a HDTV card, an AirStar HD5000
in this case. Transcode seems to get started, but there are a pile of
"GetNextFreeFrame() is getting a busy frame" errors for a few minutes
and then the transcode fails.  Auto-transcode, or restarted from the
Jobs window, or started manually from the Recorded list, all do the same
thing (which is good, I suppose).

Any pointers?

Thanks in advance

Here's the mythbackend log:

2006-04-18 14:42:42.124 transcode: Transcode Starting
2006-04-18 14:42:44.357 Using runtime prefix = /usr
2006-04-18 14:42:45.294 New DB connection, total: 1
2006-04-18 14:42:45.496 Enabled verbose msgs: important
2006-04-18 14:42:45.511 New DB connection, total: 2
2006-04-18 14:42:45.829 Using protocol version 26
2006-04-18 14:42:45.844 adding: test as a client (events: 0)
2006-04-18 14:42:45.979 RingBuf(/video/1051_20060415090000.mpg):
OpenFile(/video/1051_20060415090000.mpg, 12)
0: start_time: 7575.385 duration: 161.793
1: start_time: 7575.385 duration: 161.793
stream: start_time: 84170.941 duration: 1797.696 bitrate=17477 kb/s
2006-04-18 14:42:46.749 AFD: Stream #0, has id 0x49 codec id MPEG2VIDEO,
type Video at 0x0x80ce390
2006-04-18 14:42:46.749 detectInterlace(Detect Scan, Detect Scan, 29.97,
1088) ->Interlaced Scan
2006-04-18 14:42:46.750 Interlaced: Interlaced Scan  video_height: 1088 
fps: 29.97
2006-04-18 14:42:46.751 AFD: Looking for decoder for MPEG2VIDEO
2006-04-18 14:42:46.752 AFD: Stream #1, has id 0x52 codec id AC3, type
Audio at 0x0x80ce200
2006-04-18 14:42:46.753 AFD: Looking for decoder for AC3
2006-04-18 14:42:46.754 RingBuf(/video/1051_20060415090000.mpg):
CalcReadAheadThresh(19184 KB)
                         -> threshhold(702 KB) min read(32 KB) blk
size(512 KB)
2006-04-18 14:42:46.754 Resyncing position map. posmapStarted = 0
livetv(0) watchingRec(0)
2006-04-18 14:42:46.878 Position map filled from DB to: 53805
2006-04-18 14:42:46.879 SyncPositionMap prerecorded, from DB: 3588 entries
2006-04-18 14:42:46.879 SyncPositionMap, new totframes: 53805, new
length: 1795, posMap size: 3588
2006-04-18 14:42:46.880 AFD: Position map found
2006-04-18 14:42:46.880 AFD: Successfully opened decoder for file:
"/video/1051_20060415090000.mpg". novideo(0)
2006-04-18 14:42:46.884 New DB connection, total: 3
2006-04-18 14:42:47.083 RecBase(/dev/video): SetRingBuffer(0x80e4d48)
'/video/1051_20060415090000.mpg.tmp'
2006-04-18 14:42:47.086 VideoOutputNull()
2006-04-18 14:42:47.097 Image size. dispxoff 0, dispyoff: 0, dispwoff:
0, disphoff: 0
2006-04-18 14:42:47.097 Image size. imgx 0, imgy: 0, imgw: 1920, imgh: 1080
2006-04-18 14:42:47.104 Created data @0xb294f020->0xb2c4c022
2006-04-18 14:42:47.108 Created data @0xb2651020->0xb294e022
2006-04-18 14:42:47.113 Created data @0xb2353020->0xb2650022
<snip>
2006-04-18 14:42:47.310 Created data @0xad289020->0xad586022
2006-04-18 14:42:47.316 Created data @0xacf8b020->0xad288022
2006-04-18 14:42:47.322 Created data @0xacc8d020->0xacf8a022
2006-04-18 14:42:47.322 Image size. dispxoff 0, dispyoff: 0, dispwoff:
0, disphoff: 0
2006-04-18 14:42:47.323 Image size. imgx 0, imgy: 0, imgw: 1920, imgh: 1080
2006-04-18 14:42:47.323 NVP: ClearAfterSeek()
2006-04-18 14:42:49.040 Guessing at new AV sync values
2006-04-18 14:42:50.146 GetNextFreeFrame() is getting a busy frame
P.       UUUUUUUUUUUUUUUUUUUUUUUUUUUUUUL
2006-04-18 14:42:50.292 GetNextFreeFrame() is getting a busy frame
P.       LUUUUUUUUUUUUUUUUUUUUUUUUUUUUUu
2006-04-18 14:42:50.340 GetNextFreeFrame() is getting a busy frame
P.       LUUUUUUUUUUUUUUUUUUUUUUUUUUUUUu
2006-04-18 14:42:50.390 GetNextFreeFrame() is getting a busy frame
P.       LUUUUUUUUUUUUUUUUUUUUUUUUUUUUUU
<snip>
2006-04-18 14:49:01.357 GetNextFreeFrame() is getting a busy frame
P.       UUUUUUUUUUUUUUUUUUUUULUUUUUUUUU
2006-04-18 14:49:01.499 GetNextFreeFrame() is getting a busy frame
P.       UUUUUUUUUUUUUUUUUUUUUuUULUUUUUU
2006-04-18 14:49:01.553 GetNextFreeFrame() is getting a busy frame
P.       UUUUUUUUUUUUUUUUUUUUUuUULUUUUUU
2006-04-18 14:49:01.677 GetNextFreeFrame() is getting a busy frame
P.       UUUUUUUUUUUUUUUUUUUUUUUULUUUUUU
2006-04-18 14:49:01.741 NVR: lame error '-1'
2006-04-18 14:49:01.742 Transcode: Encountered irrecoverable error in
NVR::WriteAudio
2006-04-18 14:49:01.828 ~VideoOutputNull()
2006-04-18 14:49:01.966 Unknown socket closing
2006-04-18 14:49:02.088 transcode: Transcode Errored




More information about the mythtv-users mailing list