Add additional audio tracks - title #1' failed with error message

Jens Neu <[email protected]> Sun, 02 Jan 2011 13:51:04 +0100
Newsgroups gmane.comp.video.dvdrip.user
Message-ID <[email protected]>
Dear all,

I'm having trouble creating projects containing xvid video with dual ac3 
audio tracks. This used to work fine in times I was on gentoo, but 
stopped working when I switched to Debian Squeeze. Dvdrip and transcode 
are straight from debian-multimedia.org:

dvdrip -version
0.98.11
transcode -version
transcode v1.1.5 (C) 2001-2003 Thomas Oestreich, 2003-2009 Transcode Team

The audio merge fails with error message (full log below):

Job 'Add additional audio tracks - title #1' failed with error message:
Job 'Transcode & merge audio track - title #1' failed with error message:
Job 'Merge audio - title #1, audio track #0' failed with error message:
Command exits with failure code:
Command: execflow -n 19 avimerge -p 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001-01.avi -a 1 -o 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi.merged -i 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi && mv 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi.merged 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi && rm 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001-01.avi && echo 
EXECFLOW_OK

Output: [avilib.c] AVI open: avilib - AVI file has no video data
scanning file /home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi 
for video/audio parameter

projects with single ac3 audio tracks work fine however.
All I found concering this issue is an orphaned Ubuntu Lucid Bug:
https://bugs.launchpad.net/ubuntu/+source/dvdrip/+bug/588223

Any hints where to start digging?

regards
Jens




Log example:
Sun Jan  2 11:49:53 2011 Detected transcode version: 10105
Sun Jan  2 11:50:12 2011 Project file saved to 
'/home/jens/dvdrip-data/gwape.rip'
Sun Jan  2 11:50:12 2011 Project temporary dir 
'/home/jens/dvdrip-data/images/gwape/tmp' created
Sun Jan  2 11:50:12 2011 Project gwape created
Sun Jan  2 11:50:15 2011 Start job 'Read TOC (lsdvd|tcprobe)'
Sun Jan  2 11:50:15 2011 Start job 'Read TOC (lsdvd)'
Sun Jan  2 11:50:15 2011 Executing command: execflow lsdvd -a -n -c -s 
-v -Op \/dev\/sr0 2>/dev/null && echo EXECFLOW_OK
Sun Jan  2 11:50:16 2011 Start job 'Read TOC (tcprobe)'
Sun Jan  2 11:50:16 2011 Successfully read DVD TOC
Sun Jan  2 11:50:16 2011 Mounting DVD at {mount_point}
Sun Jan  2 11:50:16 2011 WARNING: no IFO files found - vobsub feature 
disabled.
Sun Jan  2 11:50:16 2011 Copying IFO files from {src_dir} to 
/home/jens/dvdrip-data/images/gwape/tmp/ifo
Sun Jan  2 11:50:16 2011 Umount {mount_point}: Usage: umount -h | -V
        umount -a [-d] [-f] [-r] [-n] [-v] [-t vfstypes] [-O opts]
        umount [-d] [-f] [-r] [-n] [-v] special | node...
Sun Jan  2 11:50:17 2011 Job 'Read TOC (lsdvd|tcprobe)' finished
Sun Jan  2 11:50:17 2011 Job 'Read TOC (tcprobe)' finished
Sun Jan  2 11:50:17 2011 Job 'Read TOC (lsdvd)' finished
Sun Jan  2 11:50:21 2011 Start job 'Rip selected title(s) / chapter(s)'
Sun Jan  2 11:50:21 2011 Start job 'Process title #1'
Sun Jan  2 11:50:21 2011 Start job 'Rip - title #1'
Sun Jan  2 11:50:21 2011 Executing command: rm -f 
/home/jens/dvdrip-data/images/gwape/vob/001//gwape-???.vob && execflow 
-n 19 tccat -t dvd -T 1,-1,1 -i \/dev\/sr0 | dvdrip-splitpipe -f 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-nav.log 1024 
/home/jens/dvdrip-data/images/gwape/vob/001//gwape vob  | tcextract -a 2 
-x ac3 -t vob | tcdecode -x ac3 | tcscan -x pcm && echo EXECFLOW_OK
Sun Jan  2 11:52:18 2011 Rip - title #1: 10% done.
Sun Jan  2 11:53:56 2011 Rip - title #1: 20% done.
Sun Jan  2 11:55:11 2011 Rip - title #1: 30% done.
Sun Jan  2 11:56:17 2011 Rip - title #1: 40% done.
Sun Jan  2 11:57:18 2011 Rip - title #1: 50% done.
Sun Jan  2 11:58:17 2011 Rip - title #1: 60% done.
Sun Jan  2 11:59:11 2011 Rip - title #1: 70% done.
Sun Jan  2 12:00:03 2011 Rip - title #1: 80% done.
Sun Jan  2 12:00:57 2011 Rip - title #1: 90% done.
Sun Jan  2 12:01:43 2011 Executing command: execflow tcprobe -H 25 -i 
/home/jens/dvdrip-data/images/gwape/vob/001/ && echo EXECFLOW_OK
Sun Jan  2 12:01:43 2011 Program stream units calculated
Sun Jan  2 12:01:43 2011 Start job 'Process preview frame - title #1'
Sun Jan  2 12:01:43 2011 Start job 'Grab preview - title #1'
Sun Jan  2 12:01:44 2011 Executing command: mkdir -m 0775 
/home/jens/dvdrip-data/images/gwape/tmp/dvdrip5544.snap; cd 
/home/jens/dvdrip-data/images/gwape/tmp/dvdrip5544.snap; execflow tccat 
-i 
\/home\/jens\/dvdrip\-data\/images\/girl\-with\-a\-pearl\-earring\/vob\/001\/ 
  -t vob -S 1370159 -d 0 | tcdemux -s 0x80 -x mpeg2 -S 0 -M 0 -d 0 -P 
/dev/null | tcextract -t vob -a 0 -x mpeg2 -d 0 | ffmpeg -r 25.000 -i - 
-an -r 1 -ss '0.360' -vframes 1 snapshot%03d.png  && execflow convert 
-size 720x576 
/home/jens/dvdrip-data/images/gwape/tmp/dvdrip5544.snap/snapshot*.png 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-orig.jpg && 
execflow convert -size 720x576 
/home/jens/dvdrip-data/images/gwape/tmp/dvdrip5544.snap/snapshot*.png 
gray:/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-orig.raw 
&& rm -r /home/jens/dvdrip-data/images/gwape/tmp/dvdrip5544.snap && echo 
EXECFLOW_OK
Sun Jan  2 12:01:44 2011 Job 'Rip - title #1' finished
Sun Jan  2 12:01:44 2011 Start job 'Apply preset & make previews - title #1'
Sun Jan  2 12:01:44 2011 Start job 'Apply Clip & Zoom preset - title #1'
Sun Jan  2 12:01:44 2011 Start job 'Generate preview thumbnails - title #1'
Sun Jan  2 12:01:44 2011 Executing command: execflow dvdrip-thumb 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-orig.jpg 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-clip1.jpg 0 0 
0 0 && execflow dvdrip-thumb 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-clip1.jpg 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-zoom.jpg 640 
360 && execflow dvdrip-thumb 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-zoom.jpg 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-clip2.jpg 44 8 
44 8 && echo EXECFLOW_OK
Sun Jan  2 12:01:44 2011 Job 'Apply Clip & Zoom preset - title #1' finished
Sun Jan  2 12:01:44 2011 Job 'Grab preview - title #1' finished
Sun Jan  2 12:01:44 2011 Generate preview thumbnails - title #1: 30% done.
Sun Jan  2 12:01:44 2011 Generate preview thumbnails - title #1: 60% done.
Sun Jan  2 12:01:44 2011 Generate preview thumbnails - title #1: 100% done.
Sun Jan  2 12:01:44 2011 Project file saved to 
'/home/jens/dvdrip-data/images/gwape/tmp/backup.rip'
Sun Jan  2 12:01:44 2011 Job 'Rip selected title(s) / chapter(s)' finished
Sun Jan  2 12:01:44 2011 Job 'Process title #1' finished
Sun Jan  2 12:01:44 2011 Job 'Process preview frame - title #1' finished
Sun Jan  2 12:01:45 2011 Job 'Apply preset & make previews - title #1' 
finished
Sun Jan  2 12:01:45 2011 Job 'Generate preview thumbnails - title #1' 
finished
Sun Jan  2 12:01:52 2011 Start job 'Generate preview thumbnails - title #1'
Sun Jan  2 12:01:52 2011 Executing command: execflow dvdrip-thumb 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-orig.jpg 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-clip1.jpg 0 0 
0 0 && execflow dvdrip-thumb 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-clip1.jpg 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-zoom.jpg 1024 
576 && execflow dvdrip-thumb 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-zoom.jpg 
/home/jens/dvdrip-data/images/gwape/tmp/gwape-001-preview-clip2.jpg 72 6 
72 10 && echo EXECFLOW_OK
Sun Jan  2 12:01:52 2011 Generate preview thumbnails - title #1: 30% done.
Sun Jan  2 12:01:52 2011 Generate preview thumbnails - title #1: 60% done.
Sun Jan  2 12:01:52 2011 Generate preview thumbnails - title #1: 100% done.
Sun Jan  2 12:01:52 2011 Job 'Generate preview thumbnails - title #1' 
finished
Sun Jan  2 12:02:29 2011 Start job 'Transcode - title #1'
Sun Jan  2 12:02:29 2011 Start job 'Transcode multipass - title #1'
Sun Jan  2 12:02:29 2011 Start job 'Transcode video - title #1, pass 1'
Sun Jan  2 12:02:29 2011 Executing command: mkdir -m 0775 -p 
'/home/jens/dvdrip-data/images/gwape/tmp' && cd 
/home/jens/dvdrip-data/images/gwape/tmp && mkdir -p 
/home/jens/dvdrip-data/images/gwape/avi/001 && execflow -n 19 transcode 
-H 10 -a 2 -x vob,null -i 
\/home\/jens\/dvdrip\-data\/images\/girl\-with\-a\-pearl\-earring\/vob\/001\/ 
-w 2000,50 -A -N 0x2000 -f 25.000 -Y 72,10,72,6 -X 0,38,8 -R 1 -y 
xvid,null -o /dev/null --progress_meter 2 --progress_rate 25 && echo 
EXECFLOW_OK
Sun Jan  2 12:05:57 2011 Transcode video - title #1, pass 1: 10% done.
Sun Jan  2 12:09:07 2011 Transcode video - title #1, pass 1: 20% done.
Sun Jan  2 12:12:21 2011 Transcode video - title #1, pass 1: 30% done.
Sun Jan  2 12:15:30 2011 Transcode video - title #1, pass 1: 40% done.
Sun Jan  2 12:18:42 2011 Transcode video - title #1, pass 1: 50% done.
Sun Jan  2 12:21:58 2011 Transcode video - title #1, pass 1: 60% done.
Sun Jan  2 12:25:09 2011 Transcode video - title #1, pass 1: 70% done.
Sun Jan  2 12:28:16 2011 Transcode video - title #1, pass 1: 80% done.
Sun Jan  2 12:31:18 2011 Transcode video - title #1, pass 1: 90% done.
Sun Jan  2 12:33:19 2011 Start job 'Transcode video - title #1, pass 2'
Sun Jan  2 12:33:19 2011 Executing command: mkdir -m 0775 -p 
'/home/jens/dvdrip-data/images/gwape/tmp' && cd 
/home/jens/dvdrip-data/images/gwape/tmp && mkdir -p 
/home/jens/dvdrip-data/images/gwape/avi/001 && execflow -n 19 transcode 
-H 10 -a 2 -x vob -i 
\/home\/jens\/dvdrip\-data\/images\/girl\-with\-a\-pearl\-earring\/vob\/001\/ 
-w 2000,50 -A -N 0x2000 -f 25.000 -Y 72,10,72,6 -X 0,38,8 -R 2 -y xvid 
-o /home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi 
--progress_meter 2 --progress_rate 25 && echo EXECFLOW_OK
Sun Jan  2 12:33:19 2011 Job 'Transcode video - title #1, pass 1' finished
Sun Jan  2 12:34:20 2011 Start job 'Add additional audio tracks - title #1'
Sun Jan  2 12:34:20 2011 Start job 'Transcode & merge audio track - 
title #1'
Sun Jan  2 12:34:20 2011 Start job 'Transcode audio - title #1, track #0'
Sun Jan  2 12:34:20 2011 Executing command: mkdir -p 
/home/jens/dvdrip-data/images/gwape/avi/001 && execflow -n 19 transcode 
  -H 10 -u 50 -a 0 -y raw -o 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001-01.avi -x null,vob 
-i 
\/home\/jens\/dvdrip\-data\/images\/girl\-with\-a\-pearl\-earring\/vob\/001\/ 
-f 25.000 -A -N 0x2000 --progress_meter 2 --progress_rate 25 && echo 
EXECFLOW_OK
Sun Jan  2 12:34:20 2011 Job 'Transcode multipass - title #1' finished
Sun Jan  2 12:34:20 2011 Job 'Transcode video - title #1, pass 2' finished
Sun Jan  2 12:34:27 2011 Transcode audio - title #1, track #0: 10% done.
Sun Jan  2 12:34:34 2011 Transcode audio - title #1, track #0: 20% done.
Sun Jan  2 12:34:42 2011 Transcode audio - title #1, track #0: 30% done.
Sun Jan  2 12:34:49 2011 Transcode audio - title #1, track #0: 40% done.
Sun Jan  2 12:34:57 2011 Transcode audio - title #1, track #0: 50% done.
Sun Jan  2 12:35:04 2011 Transcode audio - title #1, track #0: 60% done.
Sun Jan  2 12:35:11 2011 Transcode audio - title #1, track #0: 70% done.
Sun Jan  2 12:35:19 2011 Transcode audio - title #1, track #0: 80% done.
Sun Jan  2 12:35:25 2011 Transcode audio - title #1, track #0: 90% done.
Sun Jan  2 12:35:32 2011 Start job 'Merge audio - title #1, audio track #0'
Sun Jan  2 12:35:32 2011 Executing command: execflow -n 19 avimerge -p 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001-01.avi -a 1 -o 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi.merged -i 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi && mv 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi.merged 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi && rm 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001-01.avi && echo 
EXECFLOW_OK
Sun Jan  2 12:35:32 2011 Job 'Transcode audio - title #1, track #0' finished
Sun Jan  2 12:35:32 2011 Job 'Transcode - title #1' exited with error: 
Job 'Add additional audio tracks - title #1' failed with error message:
Job 'Transcode & merge audio track - title #1' failed with error message:
Job 'Merge audio - title #1, audio track #0' failed with error message:
Command exits with failure code:
Command: execflow -n 19 avimerge -p 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001-01.avi -a 1 -o 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi.merged -i 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi && mv 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi.merged 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi && rm 
/home/jens/dvdrip-data/images/gwape/avi/001/gwape-001-01.avi && echo 
EXECFLOW_OK

Output: [avilib.c] AVI open: avilib - AVI file has no video data
scanning file /home/jens/dvdrip-data/images/gwape/avi/001/gwape-001.avi 
for video/audio parameter

--------------------------------------------------------------------------------

--------------------------------------------------------------------------------

--------------------------------------------------------------------------------
Sun Jan  2 12:35:32 2011 Job 'Transcode - title #1' cancelled
Sun Jan  2 12:35:32 2011 Job 'Add additional audio tracks - title #1' 
finished
Sun Jan  2 12:35:32 2011 Job 'Transcode & merge audio track - title #1' 
finished
Sun Jan  2 12:35:32 2011 Job 'Merge audio - title #1, audio track #0' 
finished