+ '[' -z /var/lib/video/film/divx ']' + '[' -z lavc ']' + '[' -z avi ']' + '[' -z '-cache 32768' ']' + '[' -z '' ']' + DIVXENCOPT= + '[' -z s ']' + '[' -z mpeg4 ']' + '[' -z 5 ']' + '[' -z '-b 192 -h' ']' + '[' -z 2 ']' + '[' -z 750 ']' + '[' -z 2480 ']' + '[' -z 0 ']' + '[' -z 90 ']' + '[' -z 25 ']' + '[' -z 2 ']' + '[' -z no ']' + '[' -z '' ']' + DIVX_SPLIT=no + '[' -z no ']' + '[' -z yes ']' + '[' -z mp3lame ']' + NewJobs=/var/lib/video/film/tmp/vdr2divx.bk3858 + JOBFIL=vdr2divx + LOGFILE=/var/log/vdrconvert/vdr2divx.log + LTEMPDIR=/var/lib/video/film/tmp/vdr2divx + '[' -d /var/lib/video/film/divx ']' + '[' -d /var/lib/video/film/tmp/vdr2divx ']' + '[' -z no ']' + '[' -w /var/lib/video/film/tmp/vdr2divx -a -w /var/lib/video/film/divx ']' ++ cat /var/spool/vdrconvert/vdr2divx ++ nl ++ wc -l ++ awk '{ print $1 }' + let NLLINES=1 ++ cat /var/spool/vdrconvert/vdr2divx ++ wc -l ++ awk '{ print $1 }' + let ALINES=1 + '[' 1 -ge 1 ']' + TEMPJOBFILE=//var/lib/video/film/tmp/vdr2divx/vdr2divx.u.3861 ++ cat /var/spool/vdrconvert/vdr2divx ++ head -n 1 + printf '%s\n' '/var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec ' + let LINE=2 + '[' 2 -le 1 ']' + '[' 1 -gt 1 ']' + cp //var/lib/video/film/tmp/vdr2divx/vdr2divx.u.3861 /var/spool/vdrconvert/vdr2divx + rm //var/lib/video/film/tmp/vdr2divx/vdr2divx.u.3861 ++ cat /var/lib/video/film/tmp/vdr2divx.bk3858 ++ grep '^/var/lib/video' ++ wc -l + '[' 1 -gt 0 ']' + USESTREAMS= ++ head -n 1 /var/lib/video/film/tmp/vdr2divx.bk3858 + CMDLIN='/var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec ' + let Number=1 ++ date + echo 'Fri Feb 22 01:40:50 CET 2008 : Start conversion for /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec ' Fri Feb 22 01:40:50 CET 2008 : Start conversion for /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec + for i in '$CMDLIN' + '[' yes = yes ']' ++ echo /usr/lib/vdrconvert/bin/vdr2divx.sh ++ xargs basename + /usr/lib/vdr/svdrpsend.pl MESG 'vdr2divx.sh start for /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec' + logger '/usr/lib/vdrconvert/bin/vdr2divx.sh : start for /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec' + pxfiles= + '[' no = no ']' + p=/var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec/ + for j in '$p' ++ date + printf '\tFri Feb 22 01:40:51 CET 2008 : Begin conversion %s\n' /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec/ Fri Feb 22 01:40:51 CET 2008 : Begin conversion /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec/ ++ mktemp -d /var/lib/video/film/tmp/vdr2divx/VDRSYNC.XXXXXX + UniqueDir[Number]=/var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916 + status=0 + '[' 0 -eq 0 ']' + mkdir -p /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916 ++ echo /var/lib/video ++ sed 's/\// /g' ++ wc -w + r=3 ++ echo /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec ++ sed 's/\// /g' ++ wc -w + spalten=5 ++ echo /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec ++ sed 's/\%//g' ++ cut -f 5 -d / + Record=@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A + '[' 5 -lt 4 ']' + SubTitel= ++ basename /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec ++ cut -f1 -d. + Datum=2008-02-21 + '[' -z '' ']' + '[' no = yes ']' + Titel=@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A + '[' '!' -d /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/ ']' + '[' '!' -d /var/lib/video/film/divx/ ']' + cd /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916 + LOG[Number]=/var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/sync.log + '[' yes = yes ']' + /usr/lib/vdr/svdrpsend.pl MESG 'Try to demux with vdrsync' ++ date +%T + printf '\t01:40:52: Start demux with %s\n' vdrsync 01:40:52: Start demux with vdrsync + case `basename $DEMUXER` in ++ basename vdrsync ++ date +%T + printf '01:40:52\t: Start /usr/lib/vdrconvert/bin/vdrsync-0.1.2.2.pl\n' 01:40:52 : Start /usr/lib/vdrconvert/bin/vdrsync-0.1.2.2.pl + NEWSTREAMS[Number]= + nice -n 15 /usr/lib/vdrconvert/bin/vdrsync-0.1.2.2.pl -o /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916 /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec/ + status=0 + '[' yes = yes ']' + cat /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/sync.log perl: warning: Setting locale failed. perl: warning: Please check that your locale settings: LANGUAGE = (unset), LC_ALL = (unset), LANG = "de_DE@euro" are supported and installed on your system. perl: warning: Falling back to the standard locale ("C"). Got parameter -o Got parameter /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec/ got a directory on the command line trying to open /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec/ Initialising and analysing the streams.... 10 Mbytes of 0 read Created new MPEG stream object for stream e0, master video stream Created new MPEG stream object for stream c0 analysed the first 2000 packets... Total Input Size is 37762141 10 Mbytes of 37 read 20 Mbytes of 37 read 30 Mbytes of 37 read all Input files processed EOF reached 37 Mbytes of 37 read 20328 PES packets processed 2061 frames written for stream c0 (49.464 sec) 1237 frames written for stream e0 (49.48 sec) audio stream c0 info (MPEG1_Layer_2): Sample frequency: 48000 Bitrate: 256000 Mode: stereo Frame length (bytes) 768 Frame length (ticks) 2160 (90000 / sec) video stream e0 info: Frame length (ticks) 3600 (90000 / sec) Aspect ratio 16:9 Horizontal size 720 Vertical size 576 Frames per Second 25 Bitrate: 15000000 + '[' 0 -ne 0 ']' + AC3OPT= + '[' 0 -eq 0 ']' + let ABITR=0 + let field=0 + '[' -z '' ']' + '[' '' '!=' yes -a -f '/var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/*.ac3' ']' ++ ls /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mpa '/var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/*.mp2' + for ii in '`$LS ${UniqueDir[Number]}/*.mpa ${UniqueDir[Number]}/*.mp2 2>/dev/null`' ++ echo /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mpa ++ xargs basename + NEWSTREAMS[Number]=' c0.mpa' + for k in '${NEWSTREAMS[Number]}' + '[' -f /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mpa ']' ++ echo c0.mpa ++ cut -f1 -d . + id=c0 ++ echo c0.mpa ++ cut -f2 -d . + ids=mpa + '[' mpa = mpa -o mpa = mp2 ']' + ids=mp3 + '[' 0 -lt 2 ']' ++ '[' 0 -eq 0 ']' ++ echo '-p /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mp3 ' + MPLEXAUDIO='-p /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mp3 ' + '[' mp3 = ac3 ']' + '[' yes '!=' no -o '' = '-aid 128 -oac copy' ']' + '[' yes = yes ']' ++ echo /usr/lib/vdrconvert/bin/vdr2divx.sh ++ xargs basename + /usr/lib/vdr/svdrpsend.pl MESG 'vdr2divx.sh start LAME' ++ date +%T + printf '\t01:40:55: Start LAME for %s.\n\t\t %s\n' @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A '-p /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mp3 ' 01:40:55: Start LAME for @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A. -p /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mp3 + echo '--mp2input -b 192 -h /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mpa /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mp3' + xargs nice -n 15 lame + '[' -z '' ']' + AC3OPT='-oac copy' + let ABITR=ABITR+DIVXMP3R + let field=field+1 + '[' yes = yes ']' ++ echo /usr/lib/vdrconvert/bin/vdr2divx.sh ++ xargs basename + /usr/lib/vdr/svdrpsend.pl MESG 'vdr2divx.sh start TCMPLEX' ++ date +%T + printf '\t01:41:06: Start TCMPLEX for %s.\n\t\t %s\n' @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A '-p /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mp3 ' 01:41:06: Start TCMPLEX for @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A. -p /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mp3 + nice -n 15 tcmplex -i /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/e0.mpv -p /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mp3 -m s -o /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A.mpg + '[' vdrsync = pva ']' + rm -rf '/var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/*.ac3' /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/c0.mpa '/var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/*.mp2' .m2v + case `basename $DEMUXER` in ++ basename vdrsync ++ cat /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/sync.log ++ grep 'frames written for stream' ++ tail -n 1 ++ cut -f2 -d '(' ++ cut -f1 '-d)' ++ awk '{print $1}' ++ cut -f1 -d. + let sec=49 + let min=0 ++ ls -s --block-size=1024k /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A.mpg ++ awk '{print $1}' ls: /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A.mpg: No such file or directory + lmovie= + '[' 0 -eq 0 ']' + let min=1 + '[' yes = yes ']' + echo 'File @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A hat eine Länge von 1 Minuten bei MB' File @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A hat eine Länge von 1 Minuten bei MB + '[' '' '!=' no ']' + '[' 750 -gt 0 ']' + LEN=0 + '[' 0 -le 0 ']' + LEN=1 + VBITRT=-55 + '[' 0 -gt 0 -a -55 -le 0 ']' + '[' yes = yes ']' + echo 'Berechnete Bitrate für eine Länge 1 = -55' Berechnete Bitrate für eine Länge 1 = -55 ++ mencoder -frames 0 /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A.mpg ++ grep aspect ++ cut -f1 -d ')' ++ awk '{print $5}' + ASPECT= + '[' '' = 3 ']' ++ mencoder -frames 0 /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A.mpg ++ grep aspect ++ cut -f1 -d ')' ++ awk '{print $3}' ++ cut -f2 -dx + Y= + X=0 + ASPOPT='-vop scale=0:,lavcdeint -sws 2' + '[' no = yes ']' + '[' yes = yes ']' + /usr/lib/vdr/svdrpsend.pl MESG 'start mencoder mode lavc for /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec' + '[' yes = yes ']' + /usr/lib/vdr/svdrpsend.pl MESG 'ASPECT = -vop scale=0:,lavcdeint -sws 2: 0: Bitr=-55' + for t in '$DIVXTYPE' + case $t in ++ date +%T + printf '\t01:41:08 Erstelle %s Format 2 Pass\n' lavc 01:41:08 Erstelle lavc Format 2 Pass + for pass in 1 2 ++ date +%T + printf '\t01:41:08 : Start mencoder pass %d %s %s %s %s Videobitr. %s\n' 1 '' '-oac copy' '-cache 32768' '-vop scale=0:,lavcdeint -sws 2' -55 01:41:08 : Start mencoder pass 1 -oac copy -cache 32768 -vop scale=0:,lavcdeint -sws 2 Videobitr. -55 + nice -n 15 mencoder -oac copy -cache 32768 -vop scale=0:,lavcdeint -sws 2 -mc 0 -vc mpeg12 -passlogfile /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/divx2pass.log -ovc lavc -lavcopts vcodec=mpeg4:vhq:ildct:vpass=1:vqmin=2:vbitrate=-55 -o /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/output.avi /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A.mpg + status=1 + '[' yes = yes ']' + strings /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/sync.log MEncoder dev-SVN-rUNKNOWN-4.1.2 (C) 2000-2006 MPlayer Team CPU: Intel(R) Pentium(R) 4 CPU 1.80GHz (Family: 15, Model: 2, Stepping: 4) CPUflags: Type: 15 MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 1 SSE2: 1 Compiled with runtime CPU detection. -vop has been removed, use -vf instead. The vbitrate option must be >= 4: -55 Error parsing option on the command line: -lavcopts Exiting... (error parsing command line) + for pass in 1 2 ++ date +%T + printf '\t01:41:08 : Start mencoder pass %d %s %s %s %s Videobitr. %s\n' 2 '' '-oac copy' '-cache 32768' '-vop scale=0:,lavcdeint -sws 2' -55 01:41:08 : Start mencoder pass 2 -oac copy -cache 32768 -vop scale=0:,lavcdeint -sws 2 Videobitr. -55 + nice -n 15 mencoder -oac copy -cache 32768 -vop scale=0:,lavcdeint -sws 2 -mc 0 -vc mpeg12 -passlogfile /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/divx2pass.log -ovc lavc -lavcopts vcodec=mpeg4:vhq:ildct:vpass=2:vqmin=2:vbitrate=-55 -o /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/output.avi /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A.mpg + status=1 + '[' yes = yes ']' + strings /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916/sync.log MEncoder dev-SVN-rUNKNOWN-4.1.2 (C) 2000-2006 MPlayer Team CPU: Intel(R) Pentium(R) 4 CPU 1.80GHz (Family: 15, Model: 2, Stepping: 4) CPUflags: Type: 15 MMX: 1 MMX2: 1 3DNow: 0 3DNow2: 0 SSE: 1 SSE2: 1 Compiled with runtime CPU detection. -vop has been removed, use -vf instead. The vbitrate option must be >= 4: -55 Error parsing option on the command line: -lavcopts Exiting... (error parsing command line) + '[' 1 -ne 0 ']' + '[' yes = yes ']' ++ echo /usr/lib/vdrconvert/bin/vdr2divx.sh ++ xargs basename + /usr/lib/vdr/svdrpsend.pl MESG 'vdr2divx.sh for @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A fail' + echo 'Bei der Konvertierung von @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A ist es zu Fehlern gekommen.status=<1>' Bei der Konvertierung von @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A ist es zu Fehlern gekommen.status=<1> + logger 'Bei der Konvertierung von @Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A ist es zu Fehlern gekommen.status=<1>' + cd - / + rm -rf /var/lib/video/film/tmp/vdr2divx/VDRSYNC.aU3916 + let Number=Number+1 ++ date + printf '\tFri Feb 22 01:41:09 CET 2008 : Finish %s\n' /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec Fri Feb 22 01:41:09 CET 2008 : Finish /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec ++ date + echo 'Fri Feb 22 01:41:09 CET 2008 : Parts from /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec completed' Fri Feb 22 01:41:09 CET 2008 : Parts from /var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec completed + cp /var/lib/video/film/tmp/vdr2divx.bk3858 /var/lib/video/film/tmp/vdr2divx.bk3858.3861 + grep -x -v '/var/lib/video.00/@Amerikas_andere_Seite_Was_Kalifornien_besser_macht_Film_von_A/2008-02-21.22.52.50.99.rec ' /var/lib/video/film/tmp/vdr2divx.bk3858.3861 + rm /var/lib/video/film/tmp/vdr2divx.bk3858.3861 ++ cat /var/lib/video/film/tmp/vdr2divx.bk3858 ++ grep '^/var/lib/video' ++ wc -l + '[' 0 -gt 0 ']' + rm /var/lib/video/film/tmp/vdr2divx.bk3858 ++ date '+%d-%m-%2Y %T' + printf '22-02-2008 01:41:09 : Session %s end\n' /usr/lib/vdrconvert/bin/vdr2divx.sh 22-02-2008 01:41:09 : Session /usr/lib/vdrconvert/bin/vdr2divx.sh end + Exit + do_clean + '[' -f /var/run/vdrconvert/vdr2divx.sh.pid ']' ++ basename /var/run/vdrconvert/vdr2divx.sh.pid + TEMPPID=/var/lib/video/film/tmp/vdr2divx/vdr2divx.sh.pid.3861 + mv /var/run/vdrconvert/vdr2divx.sh.pid /var/lib/video/film/tmp/vdr2divx/vdr2divx.sh.pid.3861 + status=0 + '[' 0 -eq 0 ']' ++ cat /var/lib/video/film/tmp/vdr2divx/vdr2divx.sh.pid.3861 ++ wc -l + '[' 1 -gt 0 ']' + cat /var/lib/video/film/tmp/vdr2divx/vdr2divx.sh.pid.3861 + grep -v 3861 + '[' -f /var/lib/video/film/tmp/vdr2divx/vdr2divx.sh.pid.3861 ']' + rm /var/lib/video/film/tmp/vdr2divx/vdr2divx.sh.pid.3861 + exit