[Eisfair] vbox will nicht (mehr) mailen

[Stephan Manske]

Am 05.10.2018 um 17:44 schrieb Marcus Roeckrath:

> Gibts es Logs?
> 
> For vbox liegen die eventuell unter /var/log.
> 

unten nochmal ausführlichere Logs von vbox.

Mir scheint, es wird überhaupt nicht zu mailen versucht, nach dem record 
kommt

06-Oct 15:51:36 <E> In "/var/spool/vbox/kanzlei/standard.tcl":  (line 40).
06-Oct 15:51:36 <D> Freeing tcl interpreter...
06-Oct 15:51:36 <E> General tcl problem - setting flag to quit program...



und so sieht es ab line 40 bei mir aus:

if { "$vbox_flag_record" == "TRUE" } {

    set VBOX_NEW_MESSAGE "$vbox_var_spooldir/incoming/$vbox_var_savename"

    set RC [ vbox_get_message $VBOX_NEW_MESSAGE $vbox_var_rectime ]

    vbox_pause 1000

    exec -- /usr/local/vbox/bin/vboxmail $VBOX_NEW_MESSAGE 
$vbox_caller_name $vbox_caller_id $vbox_user_name

    if { "$RC" == "HANGUP" } {
       return
    }

    if { "$RC" == "TIMEOUT" } {

       vbox_put_message $vbox_msg_timeout

       vbox_pause 500
    }
}



und btw, wenn ich vboxmail manuell aufrufe:

root at eis (/var/spool/vbox/kanzlei)# /usr/local/vbox/bin/vboxmail 
/var/spool/vbox/kanzlei/incoming/00001538833862-00026837 Stephan 00178 
kanzlei
/usr/bin/sox: error while loading shared libraries: libvorbisenc.so.2: 
cannot open shared object file: No such file or directory
Can't init infile '/tmp/00001538833862-00026837.wav'
/usr/local/vbox/bin/vboxmail: line 549: 
/usr/local/bin/send_file_from_cmd.sh: No such file or directory






06-Oct 15:50:47 <D> Range *: match.
06-Oct 15:50:47 <J> Found Flag "RINGS=3"...
06-Oct 15:50:47 <J> Found Flag "TOLLRINGS=4"...
06-Oct 15:50:47 <J> Settings: Message 
"/var/spool/vbox/kanzlei/messages/standard.msg".
06-Oct 15:50:47 <J> Settings: Beep 
"/var/spool/vbox/kanzlei/messages/beep.msg".
06-Oct 15:50:47 <J> Settings: Timeout 
"/var/spool/vbox/kanzlei/messages/timeout.msg".
06-Oct 15:50:47 <J> Settings: Script "/var/spool/vbox/kanzlei/standard.tcl".
06-Oct 15:50:47 <J> Settings: Rings changed to 3.
06-Oct 15:50:47 <J> Settings: Rings changed to 4 if new message exist.
06-Oct 15:50:47 <J> Settings: Check for new messages in 
"/var/spool/vbox/kanzlei/incoming".
06-Oct 15:50:47 <J> Settings: 180 secs record time.
06-Oct 15:50:47 <J> Settings: Answer call.
06-Oct 15:50:47 <J> Settings: Record a message.
06-Oct 15:50:47 <J> Settings: Play standard message.
06-Oct 15:50:47 <J> Settings: Play beep message.
06-Oct 15:50:47 <J> Settings: Play timeout message.
06-Oct 15:50:47 <I> [ 1/ 3] CALLER NUMBER: 178xxxxx (*** Unknown ***)...
06-Oct 15:50:47 <D> Checking for new messages in 
"/var/spool/vbox/kanzlei/incoming"...
06-Oct 15:50:47 <D> Found 7 new messages; new number of rings are 4...
06-Oct 15:50:47 <J> Reading modem input (6 secs timeout)...
06-Oct 15:50:52 <I> [ 2/ 4] RING...
06-Oct 15:50:52 <J> Reading modem input (6 secs timeout)...
06-Oct 15:50:57 <I> [ 3/ 4] RING...
06-Oct 15:50:57 <J> Reading modem input (6 secs timeout)...
06-Oct 15:51:02 <I> [ 4/ 4] RING...
06-Oct 15:51:02 <D> Initializing tcl script 
"/var/spool/vbox/kanzlei/standard.tcl"...
06-Oct 15:51:02 <D> Answering call...
06-Oct 15:51:02 <D> Flushing modem...
06-Oct 15:51:02 <D> Sending "ATA"...
06-Oct 15:51:02 <J> Reading modem echo (4 secs timeout)...
06-Oct 15:51:02 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:02 <D> Waiting for "VCON|CONNECT"... Got "VCON" (1).
06-Oct 15:51:02 <I> Running tcl script 
"/var/spool/vbox/kanzlei/standard.tcl"...
06-Oct 15:51:02 <I> Playing 
"/var/spool/vbox/kanzlei/messages/standard.msg"...
06-Oct 15:51:02 <D> Setting voice compression "ULAW"...
06-Oct 15:51:02 <D> Flushing modem...
06-Oct 15:51:02 <D> Sending "AT+VSM=6+VLS=2"...
06-Oct 15:51:02 <J> Reading modem echo (4 secs timeout)...
06-Oct 15:51:02 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:02 <D> Waiting for "OK|VCON"... Got "OK" (1).
06-Oct 15:51:02 <D> Flushing modem...
06-Oct 15:51:02 <D> Sending "AT+VTX"...
06-Oct 15:51:02 <J> Reading modem echo (4 secs timeout)...
06-Oct 15:51:02 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:02 <D> Waiting for "CONNECT"... Got it.
06-Oct 15:51:02 <J> Play: <DATA 256 incoming; 256 outgoing>
06-Oct 15:51:02 <J> Play: <DATA 256 incoming; 256 outgoing>
06-Oct 15:51:02 <J> Play: <DATA 256 incoming; 256 outgoing>
06-Oct 15:51:02 <J> Play: <DATA 256 incoming; 256 outgoing>
06-Oct 15:51:03 <J> Play: <DATA 256 incoming; 256 outgoing>
...
06-Oct 15:51:22 <J> Play: <DATA 256 incoming; 256 outgoing>
06-Oct 15:51:22 <D> End of audio data
06-Oct 15:51:22 <J> Function play 20 secs (kernel needs 20 secs)...
06-Oct 15:51:22 <J> Sending "<DLE><ETX>"...
06-Oct 15:51:22 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:22 <D> Waiting for "VCON"... Got it.
06-Oct 15:51:22 <D> [vbox_put_message] result "OK".
06-Oct 15:51:22 <J> [vbox_pause] waiting 500 ms...
06-Oct 15:51:22 <I> Playing "/var/spool/vbox/kanzlei/messages/beep.msg"...
06-Oct 15:51:22 <D> Setting voice compression "ADPCM-4"...
06-Oct 15:51:22 <D> Flushing modem...
06-Oct 15:51:22 <D> Sending "AT+VSM=4+VLS=2"...
06-Oct 15:51:22 <J> Reading modem echo (4 secs timeout)...
06-Oct 15:51:22 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:22 <D> Waiting for "OK|VCON"... Got "OK" (1).
06-Oct 15:51:22 <D> Flushing modem...
06-Oct 15:51:22 <D> Sending "AT+VTX"...
06-Oct 15:51:22 <J> Reading modem echo (4 secs timeout)...
06-Oct 15:51:22 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:22 <D> Waiting for "CONNECT"... Got it.
06-Oct 15:51:22 <J> Play: <DATA 256 incoming; 256 outgoing>
06-Oct 15:51:22 <J> Play: <DATA 256 incoming; 256 outgoing>
...
06-Oct 15:51:23 <J> Play: <DATA 170 incoming; 170 outgoing>
06-Oct 15:51:23 <D> End of audio data
06-Oct 15:51:23 <J> Function play 1 secs (kernel needs 2 secs)...
06-Oct 15:51:23 <J> Waiting 1 secs to complete playing...
06-Oct 15:51:24 <J> Sending "<DLE><ETX>"...
06-Oct 15:51:24 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:24 <D> Waiting for "VCON"... Got it.
06-Oct 15:51:24 <D> [vbox_put_message] result "OK".
06-Oct 15:51:24 <J> [vbox_pause] waiting 500 ms...
06-Oct 15:51:25 <I> Recording 
"/var/spool/vbox/kanzlei/incoming/00001538833862-00026837" (180 secs)...
06-Oct 15:51:25 <D> Setting voice compression "ULAW"...
06-Oct 15:51:25 <D> Flushing modem...
06-Oct 15:51:25 <D> Sending "AT+VSM=6+VLS=2"...
06-Oct 15:51:25 <J> Reading modem echo (4 secs timeout)...
06-Oct 15:51:25 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:25 <D> Waiting for "OK|VCON"... Got "OK" (1).
06-Oct 15:51:25 <D> Flushing modem...
06-Oct 15:51:25 <D> Sending "AT+VRX"...
06-Oct 15:51:25 <J> Reading modem echo (4 secs timeout)...
06-Oct 15:51:25 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:25 <D> Waiting for "CONNECT"... Got it.
06-Oct 15:51:25 <J> Record: <DATA 257 incoming; 256 outgoing>
...
06-Oct 15:51:34 <J> Record: <DATA 256 incoming; 256 outgoing>
06-Oct 15:51:34 <J> *** NO CARRIER ***
06-Oct 15:51:34 <D> Found sequence "<DLE><ETX>" (remote hangup)...
06-Oct 15:51:34 <D> Setting 
"/var/spool/vbox/kanzlei/incoming/00001538833862-00026837" to 2005.201 
(0600)...
06-Oct 15:51:34 <J> Reading modem input (4 secs timeout)...
06-Oct 15:51:34 <D> Waiting for "NO CARRIER"... Got it.
06-Oct 15:51:34 <D> [vbox_get_message] result "HANGUP".
06-Oct 15:51:34 <J> [vbox_pause] waiting 1000 ms...
06-Oct 15:51:36 <E> In "/var/spool/vbox/kanzlei/standard.tcl":  (line 40).
06-Oct 15:51:36 <D> Freeing tcl interpreter...
06-Oct 15:51:36 <E> General tcl problem - setting flag to quit program...
06-Oct 15:51:36 <D> Hangup modem (drop dtr 400 ms)...
06-Oct 15:51:36 <D> Flushing modem (timeout)...
06-Oct 15:51:37 <D> Blocking all signals (0-65)...
06-Oct 15:51:37 <I> Exit program on signal 15...
06-Oct 15:51:37 <D> Hangup modem (drop dtr 400 ms)...
06-Oct 15:51:37 <D> Flushing modem (timeout)...
06-Oct 15:51:37 <D> Closing modem port (4)...
06-Oct 15:51:37 <D> Unlocking vboxgetty (/var/run/vboxgetty-ttyI6.pid)...
06-Oct 15:51:37 <D> Unlocking modem port (/var/lock/LCK..ttyI6)...
06-Oct 15:51:37 <I> ------------------------[End 
session]-----------------------
06-Oct 15:51:37 <I> -----------------------[Begin 
session]----------------------
06-Oct 15:51:37 <I> Running vbox version 2.0.0BETA5...
06-Oct 15:51:37 <I> Tcl interpreter version 8.4...
06-Oct 15:51:37 <D> Found command 'debuglevel = FEWIDJ' (-global-)...
06-Oct 15:51:37 <D> Found command 'port = /dev/ttyI6'...
06-Oct 15:51:37 <D> Found command 'modeminit = ATZ&B512&E50' (/dev/ttyI6)...
06-Oct 15:51:37 <D> Found command 'user = kanzlei' (/dev/ttyI6)...
06-Oct 15:51:37 <D> Found command 'group = vbox' (/dev/ttyI6)...
06-Oct 15:51:37 <D> Found command 'spooldir = /var/spool/vbox/kanzlei' 
(/dev/ttyI6)...
06-Oct 15:51:37 <D> Found command 'port = /dev/ttyI7'...
06-Oct 15:51:37 <I> User kanzlei's messagebox is 
"/var/spool/vbox/kanzlei"...
06-Oct 15:51:37 <I> User kanzlei's vbox.conf is 
"/var/spool/vbox/kanzlei/vbox.conf"...
06-Oct 15:51:37 <D> Setting "/var/spool/vbox/kanzlei" to 2005.201 (0700)...
06-Oct 15:51:37 <D> Opening modem port "/dev/ttyI6"...
06-Oct 15:51:37 <D> Locking vboxgetty (/var/run/vboxgetty-ttyI6.pid)...
06-Oct 15:51:37 <D> Setting "/var/run/vboxgetty-ttyI6.pid" to 2005.201 
(0644)...
06-Oct 15:51:37 <D> Locking modem port (/var/lock/LCK..ttyI6)...
06-Oct 15:51:37 <D> Setting "/var/lock/LCK..ttyI6" to 2005.201 (0644)...
06-Oct 15:51:37 <I> Drop permissions to userid 2005; groupid 201...
06-Oct 15:51:37 <I> Initializing modem port (voice mode; 2500 ms)...
06-Oct 15:51:40 <D> Hangup modem (drop dtr 400 ms)...
06-Oct 15:51:40 <D> Flushing modem (timeout)...
06-Oct 15:51:40 <J> Setting modem speed to 57600...
06-Oct 15:51:40 <J> Setting modem flow control (HARD)...
06-Oct 15:51:40 <D> Flushing modem...
06-Oct 15:51:40 <D> Sending "ATZ&B512&E50"...
06-Oct 15:51:40 <J> Reading modem echo (4 secs timeout)...