Re: lsdvd/tcprobe fails?

Noah Romer <[email protected]> Tue, 05 May 2009 09:38:54 -0700
Newsgroups gmane.comp.video.dvdrip.user
Message-ID <[email protected]>
David Carlo wrote:
> Jörn, updating AnyEvent fixed the problem on my Centos 5.3 box.  It's 
> been bugging me for a while (no pun intended).  Thanks for the great 
> work on dvdrip!

Ok, I went ahead and updated my AnyEvent to 4.4 (using CPAN, as the 
latest rpm was 4.151). This appears to fix the lsdvd problem. It now 
successfully scans the toc, rips the selected tracks and grabs preview 
frames for clip and zoom. However, it's now hanging at 99.35% complete 
on the transcode task. The log shows no indication of problems, and ps 
shows processes still running, they're just not doing anything.

`ps aufx`:
klevin   31299  0.0  1.6 172336 68848 ?        S    May04   0:17 
/usr/bin/perl /usr/bin/dvdrip
klevin    1418  0.0  0.0   2404   924 ?        S    May04   0:00  \_ sh 
-c mkdir -m 0775 -p '/home/klevin/dvdrip-data/PA-V1/tmp' && cd 
/home/klevin/dvdrip-data/PA-V1/tmp && mkdir -p /home/klevin/dv
klevin    1421  5.8  0.6 118008 24992 ?        SNl  May04  40:48      \_ 
transcode -H 10 -a 0 -x vob -i /home/klevin/dvdrip-data/PA-V1/vob/003/ 
-w 1350,50 -b 0,1,3 -s 2.054 --a52_drc_off -f 30,4 -M
klevin    1426  0.5  0.0   3964  1692 ?        SN   May04   3:46 
   \_ oggenc -r -B 16 -C 2 -q 3.00 -R 48000 -Q -o 
/home/klevin/dvdrip-data/PA-V1/avi/003/PA-V1-003-00.ogm -
klevin    5103  0.0  0.0      0     0 ?        ZN   May04   0:00 
   \_ [sh] <defunct>
klevin    5104  0.0  0.0   2404   904 ?        SN   May04   0:00 
   \_ sh -c tccat -i "/home/klevin/dvdrip-data/PA-V1/vob/003/" -t vob -d 
0 -S 1090132 | tcdemux -s 0x80 -x mpeg2 -S 0,0-3 -M 2
klevin    5112  0.0  0.0   3460   804 ?        SN   May04   0:00 
       \_ tcextract -t vob -a 0 -x mpeg2 -d 0
klevin    5113  0.0  0.1  15532  5092 ?        SN   May04   0:00 
       \_ tcdecode -x mpeg2 -d 0 -y yv12
logfile.txt (text/plain, 13.5 KB)
Mon May  4 21:27:03 2009	Project PA-V1 created
Mon May  4 21:27:05 2009	Start job 'Read TOC (lsdvd|tcprobe)'
Mon May  4 21:27:05 2009	Start job 'Read TOC (lsdvd)'
Mon May  4 21:27:05 2009	Executing command: execflow lsdvd -a -n -c -s -v -Op \/dev\/scd0 2>/dev/null && echo EXECFLOW_OK
Mon May  4 21:27:06 2009	Warning: lsdvd failed reading TOC, trying tcprobe.
Mon May  4 21:27:06 2009	Start job 'Read TOC (tcprobe)'
Mon May  4 21:27:06 2009	Start job 'Determine number of titles'
Mon May  4 21:27:06 2009	Executing command: execflow tcprobe -H 10 -i \/dev\/scd0 && echo EXECFLOW_OK
Mon May  4 21:27:06 2009	Job 'Read TOC (lsdvd)' finished
Mon May  4 21:27:07 2009	Start job 'Probe all DVD titles'
Mon May  4 21:27:07 2009	Start job 'Probe - title #1'
Mon May  4 21:27:07 2009	Executing command: execflow tcprobe -H 10 -i /dev/scd0 -T 1 && echo EXECFLOW_OK; execflow dvdxchap -t 1 /dev/scd0 2>/dev/null
Mon May  4 21:27:07 2009	Job 'Determine number of titles' finished
Mon May  4 21:27:07 2009	Not enabling PSU core, because this movie has only one PSU.
Mon May  4 21:27:07 2009	Successfully probed title #1
Mon May  4 21:27:07 2009	Start job 'Probe - title #2'
Mon May  4 21:27:07 2009	Executing command: execflow tcprobe -H 10 -i /dev/scd0 -T 2 && echo EXECFLOW_OK; execflow dvdxchap -t 2 /dev/scd0 2>/dev/null
Mon May  4 21:27:07 2009	Job 'Probe - title #1' finished
Mon May  4 21:27:07 2009	Probe all DVD titles: 20% done.
Mon May  4 21:27:07 2009	Not enabling PSU core, because this movie has only one PSU.
Mon May  4 21:27:07 2009	Successfully probed title #2
Mon May  4 21:27:07 2009	Start job 'Probe - title #3'
Mon May  4 21:27:07 2009	Executing command: execflow tcprobe -H 10 -i /dev/scd0 -T 3 && echo EXECFLOW_OK; execflow dvdxchap -t 3 /dev/scd0 2>/dev/null
Mon May  4 21:27:07 2009	Job 'Probe - title #2' finished
Mon May  4 21:27:07 2009	Probe all DVD titles: 40% done.
Mon May  4 21:27:07 2009	Not enabling PSU core, because this movie has only one PSU.
Mon May  4 21:27:07 2009	Successfully probed title #3
Mon May  4 21:27:07 2009	Start job 'Probe - title #4'
Mon May  4 21:27:07 2009	Executing command: execflow tcprobe -H 10 -i /dev/scd0 -T 4 && echo EXECFLOW_OK; execflow dvdxchap -t 4 /dev/scd0 2>/dev/null
Mon May  4 21:27:07 2009	Job 'Probe - title #3' finished
Mon May  4 21:27:07 2009	Probe all DVD titles: 60% done.
Mon May  4 21:27:07 2009	Not enabling PSU core, because this movie has only one PSU.
Mon May  4 21:27:07 2009	Successfully probed title #4
Mon May  4 21:27:07 2009	Start job 'Probe - title #5'
Mon May  4 21:27:07 2009	Executing command: execflow tcprobe -H 10 -i /dev/scd0 -T 5 && echo EXECFLOW_OK; execflow dvdxchap -t 5 /dev/scd0 2>/dev/null
Mon May  4 21:27:07 2009	Job 'Probe - title #4' finished
Mon May  4 21:27:07 2009	Probe all DVD titles: 80% done.
Mon May  4 21:27:08 2009	Not enabling PSU core, because this movie has only one PSU.
Mon May  4 21:27:08 2009	Successfully probed title #5
Mon May  4 21:27:08 2009	Successfully read DVD TOC
Mon May  4 21:27:08 2009	Copying IFO files from /media/E2996 to /home/klevin/dvdrip-data/PA-V1/tmp/ifo
Mon May  4 21:27:08 2009	Job 'Read TOC (lsdvd|tcprobe)' finished
Mon May  4 21:27:08 2009	Job 'Read TOC (tcprobe)' finished
Mon May  4 21:27:08 2009	Job 'Probe all DVD titles' finished
Mon May  4 21:27:08 2009	Job 'Probe - title #5' finished
Mon May  4 21:27:13 2009	Executing view command: mplayer dvd://2 -aid 128 -chapter 1 -dvdangle 1 -dvd-device \/dev\/scd0
Mon May  4 21:27:22 2009	Start job 'Rip selected title(s) / chapter(s)'
Mon May  4 21:27:22 2009	Start job 'Process title #3'
Mon May  4 21:27:22 2009	Start job 'Rip - title #3'
Mon May  4 21:27:22 2009	Executing command: rm -rf /home/klevin/dvdrip-data/PA-V1/tmp/subtitles/003/00; mkdir -p /home/klevin/dvdrip-data/PA-V1/tmp/subtitles/003/00; touch /home/klevin/dvdrip-data/PA-V1/tmp/subtitles/003/00/.ripped; rm -f /home/klevin/dvdrip-data/PA-V1/vob/003//PA-V1-???.vob && execflow -n 19 tccat -t dvd -T 3,-1,1 -i \/dev\/scd0  | dvdrip-multitee 1 'tcextract -x ps1 -t vob -a 0x20 | subtitle2pgm -P -C 0 -o /home/klevin/dvdrip-data/PA-V1/tmp/subtitles/003/00/pic -e 00:00:00,50000 2>&1 | dvdrip-subpng' | dvdrip-splitpipe -f /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-nav.log 1024 /home/klevin/dvdrip-data/PA-V1/vob/003//PA-V1 vob  | tcextract -a 0 -x ac3 -t vob | tcdecode -x ac3 | tcscan -x pcm && echo EXECFLOW_OK
Mon May  4 21:28:22 2009	Rip - title #3: 10% done.
Mon May  4 21:29:05 2009	Rip - title #3: 20% done.
Mon May  4 21:29:52 2009	Rip - title #3: 30% done.
Mon May  4 21:30:39 2009	Rip - title #3: 40% done.
Mon May  4 21:31:23 2009	Rip - title #3: 50% done.
Mon May  4 21:32:06 2009	Rip - title #3: 60% done.
Mon May  4 21:32:44 2009	Rip - title #3: 70% done.
Mon May  4 21:33:33 2009	Rip - title #3: 80% done.
Mon May  4 21:34:14 2009	Rip - title #3: 90% done.
Mon May  4 21:35:00 2009	Rip - title #3: 100% done.
Mon May  4 21:35:47 2009	Rip - title #3: 110% done.
Mon May  4 21:36:31 2009	Rip - title #3: 120% done.
Mon May  4 21:36:53 2009	Executing command: execflow tcprobe -H 25 -i /home/klevin/dvdrip-data/PA-V1/vob/003/ && echo EXECFLOW_OK
Mon May  4 21:36:54 2009	DVD TOC reported wrong framerate 23.976, adjusted frame rate to 29.970 and frame count to 106514
Mon May  4 21:36:54 2009	Program stream units calculated
Mon May  4 21:36:54 2009	Enabled PSU core. Movie is NTSC and has more than one PSU.
Mon May  4 21:36:54 2009	Start job 'Process preview frame - title #3'
Mon May  4 21:36:54 2009	Start job 'Grab preview - title #3'
Mon May  4 21:36:54 2009	Executing command: mkdir -m 0775 /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap; cd /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap; execflow tccat -i \/home\/klevin\/dvdrip\-data\/PA\-V1\/vob\/003\/  -t vob -S 549295 -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 29.970 -i - -an -r 1 -ss '0.434' -vframes 1 snapshot%03d.png  && execflow convert -size 720x480 /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap/snapshot*.png /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-preview-orig.jpg && execflow convert -size 720x480 /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap/snapshot*.png gray:/home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-preview-orig.raw && rm -r /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap && echo EXECFLOW_OK
Mon May  4 21:36:54 2009	Job 'Rip - title #3' finished
Mon May  4 21:36:56 2009	Start job 'Apply preset & make previews - title #3'
Mon May  4 21:36:56 2009	Start job 'Apply Clip & Zoom preset - title #3'
Mon May  4 21:36:56 2009	Start job 'Generate preview thumbnails - title #3'
Mon May  4 21:36:56 2009	Executing command: execflow dvdrip-thumb /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-preview-orig.jpg /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-preview-clip1.jpg 0 0 0 0 && execflow dvdrip-thumb /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-preview-clip1.jpg /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-preview-zoom.jpg 640 360 && execflow dvdrip-thumb /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-preview-zoom.jpg /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-preview-clip2.jpg 4 0 4 0 && echo EXECFLOW_OK
Mon May  4 21:36:56 2009	Job 'Apply Clip & Zoom preset - title #3' finished
Mon May  4 21:36:56 2009	Job 'Grab preview - title #3' finished
Mon May  4 21:36:56 2009	Generate preview thumbnails - title #3: 30% done.
Mon May  4 21:36:56 2009	Generate preview thumbnails - title #3: 60% done.
Mon May  4 21:36:56 2009	Generate preview thumbnails - title #3: 100% done.
Mon May  4 21:36:56 2009	Start job 'Process title #4'
Mon May  4 21:36:56 2009	Start job 'Rip - title #4'
Mon May  4 21:36:56 2009	Executing command: rm -rf /home/klevin/dvdrip-data/PA-V1/tmp/subtitles/004/00; mkdir -p /home/klevin/dvdrip-data/PA-V1/tmp/subtitles/004/00; touch /home/klevin/dvdrip-data/PA-V1/tmp/subtitles/004/00/.ripped; rm -f /home/klevin/dvdrip-data/PA-V1/vob/004//PA-V1-???.vob && execflow -n 19 tccat -t dvd -T 4,-1,1 -i \/dev\/scd0  | dvdrip-multitee 1 'tcextract -x ps1 -t vob -a 0x20 | subtitle2pgm -P -C 0 -o /home/klevin/dvdrip-data/PA-V1/tmp/subtitles/004/00/pic -e 00:00:00,50000 2>&1 | dvdrip-subpng' | dvdrip-splitpipe -f /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-nav.log 1024 /home/klevin/dvdrip-data/PA-V1/vob/004//PA-V1 vob  | tcextract -a 0 -x ac3 -t vob | tcdecode -x ac3 | tcscan -x pcm && echo EXECFLOW_OK
Mon May  4 21:36:56 2009	Job 'Process title #3' finished
Mon May  4 21:36:57 2009	Job 'Process preview frame - title #3' finished
Mon May  4 21:36:57 2009	Job 'Apply preset & make previews - title #3' finished
Mon May  4 21:36:57 2009	Job 'Generate preview thumbnails - title #3' finished
Mon May  4 21:37:57 2009	Rip - title #4: 10% done.
Mon May  4 21:38:43 2009	Rip - title #4: 20% done.
Mon May  4 21:39:36 2009	Rip - title #4: 30% done.
Mon May  4 21:40:34 2009	Rip - title #4: 40% done.
Mon May  4 21:41:39 2009	Rip - title #4: 50% done.
Mon May  4 21:42:40 2009	Rip - title #4: 60% done.
Mon May  4 21:43:43 2009	Rip - title #4: 70% done.
Mon May  4 21:44:53 2009	Rip - title #4: 80% done.
Mon May  4 21:45:54 2009	Rip - title #4: 90% done.
Mon May  4 21:47:04 2009	Rip - title #4: 100% done.
Mon May  4 21:48:13 2009	Rip - title #4: 110% done.
Mon May  4 21:49:36 2009	Rip - title #4: 120% done.
Mon May  4 21:50:15 2009	Executing command: execflow tcprobe -H 25 -i /home/klevin/dvdrip-data/PA-V1/vob/004/ && echo EXECFLOW_OK
Mon May  4 21:50:15 2009	DVD TOC reported wrong framerate 23.976, adjusted frame rate to 29.970 and frame count to 103743
Mon May  4 21:50:16 2009	Program stream units calculated
Mon May  4 21:50:16 2009	Enabled PSU core. Movie is NTSC and has more than one PSU.
Mon May  4 21:50:16 2009	Start job 'Process preview frame - title #4'
Mon May  4 21:50:16 2009	Start job 'Grab preview - title #4'
Mon May  4 21:50:16 2009	Executing command: mkdir -m 0775 /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap; cd /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap; execflow tccat -i \/home\/klevin\/dvdrip\-data\/PA\-V1\/vob\/004\/  -t vob -S 595195 -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 29.970 -i - -an -r 1 -ss '0.133' -vframes 1 snapshot%03d.png  && execflow convert -size 720x480 /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap/snapshot*.png /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-preview-orig.jpg && execflow convert -size 720x480 /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap/snapshot*.png gray:/home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-preview-orig.raw && rm -r /home/klevin/dvdrip-data/PA-V1/tmp/dvdrip31299.snap && echo EXECFLOW_OK
Mon May  4 21:50:16 2009	Job 'Rip - title #4' finished
Mon May  4 21:50:17 2009	Start job 'Apply preset & make previews - title #4'
Mon May  4 21:50:17 2009	Start job 'Apply Clip & Zoom preset - title #4'
Mon May  4 21:50:18 2009	Start job 'Generate preview thumbnails - title #4'
Mon May  4 21:50:18 2009	Executing command: execflow dvdrip-thumb /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-preview-orig.jpg /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-preview-clip1.jpg 0 0 0 0 && execflow dvdrip-thumb /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-preview-clip1.jpg /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-preview-zoom.jpg 856 480 && execflow dvdrip-thumb /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-preview-zoom.jpg /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-004-preview-clip2.jpg 76 314 4 142 && echo EXECFLOW_OK
Mon May  4 21:50:18 2009	Job 'Apply Clip & Zoom preset - title #4' finished
Mon May  4 21:50:18 2009	Job 'Grab preview - title #4' finished
Mon May  4 21:50:18 2009	Generate preview thumbnails - title #4: 30% done.
Mon May  4 21:50:18 2009	Generate preview thumbnails - title #4: 60% done.
Mon May  4 21:50:18 2009	Generate preview thumbnails - title #4: 100% done.
Mon May  4 21:50:18 2009	Project file saved to '/home/klevin/dvdrip-data/PA-V1/tmp/backup.rip'
Mon May  4 21:50:19 2009	Job 'Rip selected title(s) / chapter(s)' finished
Mon May  4 21:50:19 2009	Job 'Process title #4' finished
Mon May  4 21:50:19 2009	Job 'Process preview frame - title #4' finished
Mon May  4 21:50:19 2009	Job 'Apply preset & make previews - title #4' finished
Mon May  4 21:50:19 2009	Job 'Generate preview thumbnails - title #4' finished
Mon May  4 21:54:59 2009	Start job 'Transcode multiple titles'
Mon May  4 21:54:59 2009	Start job 'Transcode with VBR audio - title #3'
Mon May  4 21:54:59 2009	Start job 'Transcode with VBR audio, first pass - title #3'
Mon May  4 21:54:59 2009	Start job 'Transcode video - title #3, pass 1'
Mon May  4 21:54:59 2009	Executing command: mkdir -m 0775 -p '/home/klevin/dvdrip-data/PA-V1/tmp' && cd /home/klevin/dvdrip-data/PA-V1/tmp && mkdir -p /home/klevin/dvdrip-data/PA-V1/avi/003 && execflow -n 19 transcode -H 10 -a 0 -x vob -i \/home\/klevin\/dvdrip\-data\/PA\-V1\/vob\/003\/ -w 1350,50 -b 0,1,3 -s 2.054 --a52_drc_off -f 30,4 -M 2 -Y 4,0,4,0 -B 15,10,8 -R 1 -y xvid,ogg -m /home/klevin/dvdrip-data/PA-V1/avi/003/PA-V1-003-00.ogm --psu_mode --nav_seek /home/klevin/dvdrip-data/PA-V1/tmp/PA-V1-003-nav.log --no_split  -o /dev/null --print_status 25 && echo EXECFLOW_OK
Mon May  4 22:02:30 2009	Transcode video - title #3, pass 1: 10% done.
Mon May  4 22:08:25 2009	Transcode video - title #3, pass 1: 20% done.
Mon May  4 22:15:08 2009	Transcode video - title #3, pass 1: 30% done.
Mon May  4 22:21:43 2009	Transcode video - title #3, pass 1: 40% done.
Mon May  4 22:27:54 2009	Transcode video - title #3, pass 1: 50% done.
Mon May  4 22:33:10 2009	Transcode video - title #3, pass 1: 60% done.
Mon May  4 22:37:17 2009	Transcode video - title #3, pass 1: 70% done.
Mon May  4 22:41:30 2009	Transcode video - title #3, pass 1: 80% done.
Mon May  4 22:47:00 2009	Transcode video - title #3, pass 1: 90% done.