Transcode bug

Erik Moe emoe at cox.net
Fri Jan 2 03:45:16 UTC 2004 

Looking at the source I realized that transcode has some printf debug code.
It turns out the transcode got an error reading the vob files, but for some
reason it is hanging trying to terminate.

requesting [39253] A=8008 bytes
got audio frame (39243)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=5 [6]
(A) fill=10/11, empty=0 wait=1 locked=0, ready=9, tag=1
(V) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=1
frameid=39253
alloc = 5 [5]
module=tcv_import [option=22, flag=1]
----------------- reading syncinfo (39255)
WAIT (2)
reading frame (39255)
  received [39253] V=518400 bytes

requesting [39254] V=518400 bytes
(V) fill=10/11, empty=0 wait=1 locked=0, ready=9, tag=1
A release=5 [6]
A-  f=9 e=0 w=1 l=0 r=8
(V) fill=10/11, empty=0 wait=0 locked=0, ready=10, tag=2
got frame 0x80b6348 (39244)
(A) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=2
(A) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=1
got audio frame (39244)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=6 [7]
A frameid=39253
A alloc  =5 [5]
A+  f=10 e=1 w=0 l=0 r=9
module=tca_import [option=22, flag=2]
(V) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=1
frameid=39254
alloc = 6 [6]
module=tcv_import [option=22, flag=1]
----------------- reading syncinfo (39256)
WAIT (1)
reading frame (39256)
A release=6 [7]
A-  f=9 e=1 w=0 l=0 r=8
  received [39253] A=8008 bytes

requesting [39254] A=8008 bytes
(V) fill=10/11, empty=1 wait=0 locked=0, ready=9, tag=2
got frame 0x80b63c0 (39245)
(A) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=1
(A) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=2
A frameid=39254
A alloc  =6 [6]
A+  f=10 e=1 w=0 l=1 r=8
module=tca_import [option=22, flag=2]
got audio frame (39245)
module=tcv_export [option=13, flag=1]
[tcdecode] exit code (0)
module=tca_export [option=13, flag=2]
release=7 [8]
  received [39254] V=518400 bytes

requesting [39255] V=518400 bytes
(V) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=1
frameid=39255
alloc = 7 [7]
module=tcv_import [option=22, flag=1]
----------------- reading syncinfo (39257)
read error (0/44)
[import_vob.so] end of stream - failed to sync video frame
(dl_loader.c) video import module error
(decoder.c) video data read failed - end of stream
release=8 [7]
  received [39254] A=8008 bytes

requesting [39255] A=8008 bytes
A release=7 [8]
A-  f=9 e=0 w=1 l=0 r=8
(A) fill=9/11, empty=0 wait=0 locked=1, ready=8, tag=1
(V) fill=9/11, empty=1 wait=0 locked=0, ready=9, tag=2
got frame 0x80b6438 (39246)
A frameid=39255
A alloc  =7 [7]
A+  f=10 e=1 w=0 l=0 r=9
module=tca_import [option=22, flag=2]
(A) fill=10/11, empty=1 wait=0 locked=0, ready=9, tag=2
  received [39255] A=8008 bytes

requesting [39256] A=8008 bytes
got audio frame (39246)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [9]
(A) fill=10/11, empty=0 wait=1 locked=0, ready=9, tag=1
A release=8 [9]
A-  f=9 e=0 w=1 l=0 r=8
(V) fill=8/11, empty=1 wait=0 locked=0, ready=8, tag=2
got frame 0x80b64b0 (39247)
(A) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=2
(A) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=1
got audio frame (39247)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [10]
A frameid=39256
A alloc  =8 [8]
A+  f=10 e=1 w=0 l=0 r=9
module=tca_import [option=22, flag=2]
A release=9 [10]
A-  f=9 e=1 w=0 l=0 r=8
  received [39256] A=8008 bytes

requesting [39257] A=8008 bytes
(V) fill=7/11, empty=1 wait=0 locked=0, ready=7, tag=2
got frame 0x80b6000 (39248)
(A) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=1
(A) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=2
A frameid=39257
A alloc  =9 [9]
A+  f=10 e=1 w=0 l=1 r=8
module=tca_import [option=22, flag=2]
got audio frame (39248)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [0]
  received [39257] A=8008 bytes

requesting [39258] A=8008 bytes
A release=10 [0]
A-  f=9 e=0 w=1 l=0 r=8
(A) fill=9/11, empty=0 wait=0 locked=1, ready=8, tag=1
(V) fill=6/11, empty=1 wait=0 locked=0, ready=6, tag=2
got frame 0x80b6078 (39249)
A frameid=39258
A alloc  =10 [10]
A+  f=10 e=1 w=0 l=0 r=9
module=tca_import [option=22, flag=2]
(A) fill=10/11, empty=1 wait=0 locked=0, ready=9, tag=2
  received [39258] A=8008 bytes

requesting [39259] A=8008 bytes
got audio frame (39249)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [1]
(A) fill=10/11, empty=0 wait=1 locked=0, ready=9, tag=1
A release=0 [1]
A-  f=9 e=0 w=1 l=0 r=8
(V) fill=5/11, empty=1 wait=0 locked=0, ready=5, tag=2
got frame 0x80b60f0 (39250)
(A) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=2
(A) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=1
got audio frame (39250)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [2]
A frameid=39259
A alloc  =0 [0]
A+  f=10 e=1 w=0 l=0 r=9
module=tca_import [option=22, flag=2]
A release=1 [2]
A-  f=9 e=1 w=0 l=0 r=8
  received [39259] A=8008 bytes

requesting [39260] A=8008 bytes
(V) fill=4/11, empty=1 wait=0 locked=0, ready=4, tag=2
got frame 0x80b6168 (39251)
(A) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=1
(A) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=2
A frameid=39260
A alloc  =1 [1]
A+  f=10 e=1 w=0 l=1 r=8
module=tca_import [option=22, flag=2]
got audio frame (39251)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [3]
  received [39260] A=8008 bytes

requesting [39261] A=8008 bytes
A release=2 [3]
A-  f=9 e=0 w=1 l=0 r=8
(A) fill=9/11, empty=0 wait=0 locked=1, ready=8, tag=1
(V) fill=3/11, empty=1 wait=0 locked=0, ready=3, tag=2
got frame 0x80b61e0 (39252)
A frameid=39261
A alloc  =2 [2]
A+  f=10 e=1 w=0 l=0 r=9
module=tca_import [option=22, flag=2]
(A) fill=10/11, empty=1 wait=0 locked=0, ready=9, tag=2
  received [39261] A=8008 bytes

requesting [39262] A=8008 bytes
got audio frame (39252)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [4]
(A) fill=10/11, empty=0 wait=1 locked=0, ready=9, tag=1
A release=3 [4]
A-  f=9 e=0 w=1 l=0 r=8
(V) fill=2/11, empty=1 wait=0 locked=0, ready=2, tag=2
got frame 0x80b6258 (39253)
(A) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=2
(A) fill=9/11, empty=0 wait=0 locked=0, ready=9, tag=1
got audio frame (39253)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [5]
A frameid=39262
A alloc  =3 [3]
A+  f=10 e=1 w=0 l=0 r=9
module=tca_import [option=22, flag=2]
A release=4 [5]
A-  f=9 e=1 w=0 l=0 r=8
  received [39262] A=8008 bytes

requesting [39263] A=8008 bytes
(V) fill=1/11, empty=1 wait=0 locked=0, ready=1, tag=2
got frame 0x80b62d0 (39254)
(A) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=1
(A) fill=9/11, empty=0 wait=1 locked=0, ready=8, tag=2
A frameid=39263
A alloc  =4 [4]
A+  f=10 e=1 w=0 l=1 r=8
module=tca_import [option=22, flag=2]
got audio frame (39254)
module=tcv_export [option=13, flag=1]
module=tca_export [option=13, flag=2]
release=8 [6]
  received [39263] A=8008 bytes

requesting [39264] A=8008 bytes
A release=5 [6]
A-  f=9 e=0 w=1 l=0 r=8
(A) fill=9/11, empty=0 wait=0 locked=1, ready=8, tag=1
(encoder.c) export terminated - buffer(s) empty

(V) fill=0/11, empty=1 wait=0 locked=0, ready=0, tag=0
(A) fill=9/11, empty=0 wait=0 locked=1, ready=8, tag=0

(decoder.c) sleeping for 6 seconds to cool down
A frameid=39264
A alloc  =5 [5]
A+  f=10 e=1 w=0 l=0 r=9
module=tca_import [option=22, flag=2]
  received [39264] A=8008 bytes

requesting [39265] A=8008 bytes
(A) fill=10/11, empty=0 wait=1 locked=0, ready=9, tag=1
(decoder.c) import stop requested by client=134795264 (main=134795264)
import status=0
(decoder.c) video thread already terminated
(decoder.c) A/V import canceled (134795264) (134795264)
(decoder.c) video thread exit (ret_code=0) (status_code=13)
(decoder.c) audio thread exit (ret_code=0) (status_code=1)
(decoder.c) vframe_list_lock=0
(decoder.c) aframe_list_lock=BUSY
module=tcv_import [option=23, flag=1]
module=tca_import [option=23, flag=2]

Erik Moe
emoe at cox.net

> -----Original Message-----
> From: owner-freebsd-ports-bugs at freebsd.org
> [mailto:owner-freebsd-ports-bugs at freebsd.org]On Behalf Of Erik Moe
> Sent: Thursday, January 01, 2004 6:10 PM
> To: freebsd-ports-bugs at freebsd.org
> Subject: Transcode bug
>
>
> I'm running transcode 0.6.10 under dvdrip2.  About 20% into the rip,
> transcode freezes.  Attaching to the process with gdb yields the following
> information:
>
> (gdb) info threads
>   5 Process 83813, Thread 5  0x280d2aac in _thread_kern_sched ()
>    from /usr/lib/libc_r.so.5
>   4 Process 83813, Thread 4  0x280d2aac in _thread_kern_sched ()
>    from /usr/lib/libc_r.so.5
>   3 Process 83813, Thread 3  0x280d2aac in _thread_kern_sched ()
>    from /usr/lib/libc_r.so.5
>   2 Process 83813, Thread 2  0x280d2aac in _thread_kern_sched ()
>    from /usr/lib/libc_r.so.5
> * 1 Process 83813, Thread 1  0x2812d883 in poll () from /usr/lib/libc.so.5
> (gdb) bt
> #0  0x2812d883 in poll () from /usr/lib/libc.so.5
> #1  0x280d3651 in _thread_kern_sched_state_unlock () from
> /usr/lib/libc_r.so.5
> #2  0x280d3050 in _thread_kern_scheduler () from /usr/lib/libc_r.so.5
> (gdb) thread 2
> [Switching to thread 2 (Process 83813, Thread 2)]#0  0x280d2aac in
> _thread_kern_sched () from /usr/lib/libc_r.so.5
> (gdb) bt
> #0  0x280d2aac in _thread_kern_sched () from /usr/lib/libc_r.so.5
> #1  0x280d3283 in _thread_kern_sched_state_unlock () from
> /usr/lib/libc_r.so.5
> #2  0x280d6ae7 in _pthread_cond_wait () from /usr/lib/libc_r.so.5
> #3  0x280d6bee in pthread_cond_wait () from /usr/lib/libc_r.so.5
> #4  0x0805a874 in process_aframe ()
> (gdb) thread 3
> [Switching to thread 3 (Process 83813, Thread 3)]#0  0x280d2aac in
> _thread_kern_sched () from /usr/lib/libc_r.so.5
> (gdb) bt
> #0  0x280d2aac in _thread_kern_sched () from /usr/lib/libc_r.so.5
> #1  0x280d3283 in _thread_kern_sched_state_unlock () from
> /usr/lib/libc_r.so.5
> #2  0x280d6ae7 in _pthread_cond_wait () from /usr/lib/libc_r.so.5
> #3  0x280d6bee in pthread_cond_wait () from /usr/lib/libc_r.so.5
> #4  0x0805a5d4 in process_vframe ()
> #5  0x0807fd96 in _fini ()
> #6  0x00647664 in ?? ()
> Error accessing memory address 0x626f76: Bad address.
> (gdb) thread 4
> [Switching to thread 4 (Process 83813, Thread 4)]#0  0x280d2aac in
> _thread_kern_sched () from /usr/lib/libc_r.so.5
> (gdb) bt
> #0  0x280d2aac in _thread_kern_sched () from /usr/lib/libc_r.so.5
> #1  0x280d3283 in _thread_kern_sched_state_unlock () from
> /usr/lib/libc_r.so.5
> #2  0x280d6da9 in _pthread_cond_timedwait () from /usr/lib/libc_r.so.5
> #3  0x280cb1b9 in _thread_gc () from /usr/lib/libc_r.so.5
> #4  0x280ca3fe in _thread_start () from /usr/lib/libc_r.so.5
> (gdb) thread 5
> [Switching to thread 5 (Process 83813, Thread 5)]#0  0x280d2aac in
> _thread_kern_sched () from /usr/lib/libc_r.so.5
> (gdb) bt
> #0  0x280d2aac in _thread_kern_sched () from /usr/lib/libc_r.so.5
> #1  0x280d3218 in _thread_kern_sched_state () from /usr/lib/libc_r.so.5
> #2  0x280c7e6b in sigwait () from /usr/lib/libc_r.so.5
> #3  0x0804e4e4 in signal_thread ()
> (gdb)
>
> Trancode is invoked with the following parameters:
>
> mkdir -m 0775 -p '/u02/dvdrip/db/Pirates/tmp' && cd
> /u02/dvdrip/db/Pirates/tmp && dr_exec transcode -H 10 -a 0 -x vob,null -i
> /u02/dvdrip/db/Pirates/vob/001 -w 1224 -F mpeg4 -b 128,0,0 -s
> 1.439 --a52_drc_off -V  -f 24,1 -g 720x480 -M 2 -Y 42,0,46,0 -B 15,10,8 -R
> 1 -y ffmpeg,null --psu_mode --nav_seek
> /u02/dvdrip/db/Pirates/tmp/Pirates-001-nav.log --no_split  -o
> /dev/null --print_status 20 && echo DVDRIP_SUCCESS
> mkdir -m 0775 -p '/u02/dvdrip/db/Pirates/tmp' && cd
> /u02/dvdrip/db/Pirates/tmp && dr_exec transcode -H 10 -a 0 -x vob -i
> /u02/dvdrip/db/Pirates/vob/001 -w 1224 -F mpeg4 -b 128,0,0 -s
> 1.439 --a52_drc_off -V  -f 24,1 -g 720x480 -M 2 -Y 42,0,46,0 -B 15,10,8 -R
> 2 -y ffmpeg --psu_mode --nav_seek
> /u02/dvdrip/db/Pirates/tmp/Pirates-001-nav.log --no_split  -o
> /u02/dvdrip/db/Pirates/avi/001/Pirates-001.avi --print_status 20 && echo
> DVDRIP_SUCCESS
>
> I'm running FreeBSD 5.1-RELEASE-p11.  Don't know if this is a generic
> trancode bug or FreeBSD specific.
>
> Erik Moe
> emoe at cox.net
>
> _______________________________________________
> freebsd-ports-bugs at freebsd.org mailing list
> http://lists.freebsd.org/mailman/listinfo/freebsd-ports-bugs
> To unsubscribe, send any mail to
> "freebsd-ports-bugs-unsubscribe at freebsd.org"

More information about the freebsd-ports-bugs mailing list