[Eisfair] VBOX - kein attach möglich

[Kai Kassler]

Hallo Leute,

habe einen aktuellen eisfair nebst vbox über eine AVM laufen.
Soweit so gut.

Nun möchte ich die eingegangenen Voicenachrichten via Mail weiterleiten.
Hierzu habe ich das Paket sSMTP nachgeladen.

Passe ich die Konfiguration der VBOX auf Mailtyp subject oder mail an,
funktionert der eMail Versand ohne Probleme.

Ändere ich aber auf den von mir gewollten Typ attach geht keine Mail raus.
Auch wenn ich das Format von wav auf mp3 ändere geht nichts raus.



Gruß
Kai


vboxgetty-ttyI6.log:

18-Oct 12:33:36 <D> Blocking all signals (0-64)...
18-Oct 12:33:36 <I> Exit
program on signal 15...
18-Oct 12:33:36 <D> Hangup modem (drop dtr 400
ms)...
18-Oct 12:33:36 <D> Flushing modem (timeout)...
18-Oct 12:33:37 <D>
Closing modem port (4)...
18-Oct 12:33:37 <D> Unlocking vboxgetty
(/var/run/vboxgetty-ttyI6.pid)...
18-Oct 12:33:37 <D> Unlocking modem port
(/var/lock/LCK..ttyI6)...
18-Oct 12:33:37 <I> ------------------------[End
session]-----------------------
18-Oct 12:33:40 <I>
-----------------------[Begin session]----------------------
18-Oct
12:33:40 <I> Running vbox version 2.0.0BETA5...
18-Oct 12:33:40 <I> Tcl
interpreter version 8.3...
18-Oct 12:33:40 <D> Found command 'debuglevel =
FEWIDJ' (-global-)...
18-Oct 12:33:40 <D> Found command 'port =
/dev/ttyI6'...
18-Oct 12:33:40 <D> Found command 'modeminit =
ATZ&B512&E4459162' (/dev/ttyI6)...
18-Oct 12:33:40 <D> Found command 'user
= apm' (/dev/ttyI6)...
18-Oct 12:33:40 <D> Found command 'group = vbox'
(/dev/ttyI6)...
18-Oct 12:33:40 <D> Found command 'spooldir =
/var/spool/vbox/apm' (/dev/ttyI6)...
18-Oct 12:33:40 <I> User apm's
messagebox is "/var/spool/vbox/apm"...
18-Oct 12:33:40 <I> User apm's
vbox.conf is "/var/spool/vbox/apm/vbox.conf"...
18-Oct 12:33:40 <D> Setting
"/var/spool/vbox/apm" to 2001.201 (0700)...
18-Oct 12:33:40 <D> Opening
modem port "/dev/ttyI6"...
18-Oct 12:33:40 <D> Locking vboxgetty
(/var/run/vboxgetty-ttyI6.pid)...
18-Oct 12:33:40 <D> Setting
"/var/run/vboxgetty-ttyI6.pid" to 2001.201 (0644)...
18-Oct 12:33:40 <D>
Locking modem port (/var/lock/LCK..ttyI6)...
18-Oct 12:33:40 <D> Setting
"/var/lock/LCK..ttyI6" to 2001.201 (0644)...
18-Oct 12:33:40 <I> Drop
permissions to userid 2001; groupid 201...
18-Oct 12:33:40 <I> Initializing
modem port (voice mode; 2500 ms)...
18-Oct 12:33:42 <D> Hangup modem (drop
dtr 400 ms)...
18-Oct 12:33:42 <D> Flushing modem (timeout)...
18-Oct
12:33:43 <J> Setting modem speed to 57600...
18-Oct 12:33:43 <J> Setting
modem flow control (HARD)...
18-Oct 12:33:43 <D> Flushing modem...
18-Oct
12:33:43 <D> Sending "ATZ&B512&E4459162"...
18-Oct 12:33:43 <J> Reading
modem echo (4 secs timeout)...
18-Oct 12:33:43 <J> Reading modem input (4
secs timeout)...
18-Oct 12:33:43 <D> Waiting for "OK"... Got it.
18-Oct
12:33:43 <D> Flushing modem...
18-Oct 12:33:43 <D> Sending
"AT+FCLASS=8"...
18-Oct 12:33:43 <J> Reading modem echo (4 secs
timeout)...
18-Oct 12:33:43 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:33:43 <D> Waiting for "OK|VCON"... Got "OK"
(1).
18-Oct 12:33:43 <D> Flushing modem...
18-Oct 12:33:43 <D> Sending
"ATS13.2=1S13.4=1"...
18-Oct 12:33:43 <J> Reading modem echo (4 secs
timeout)...
18-Oct 12:33:43 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:33:43 <D> Waiting for "OK|VCON"... Got "OK"
(1).
18-Oct 12:33:43 <D> Flushing modem...
18-Oct 12:33:43 <I>
Waiting...
18-Oct 12:34:21 <I> Wakeup!
18-Oct 12:34:21 <D> Checking free
space on "/var/spool/vbox/apm"...
18-Oct 12:34:21 <J> 785670144 bytes
available; 2000000 bytes needed... enough.
18-Oct 12:34:21 <D> Getting
number of rings to wait before answer call...
18-Oct 12:34:21 <J> Jumping
to section "[RINGS]"...
18-Oct 12:34:21 <D> Parsing time(s) "*"...
18-Oct
12:34:21 <D> Range **:**:** - **:**:** (12:34:21): match.
18-Oct 12:34:21
<D> Parsing day(s) "*"...
18-Oct 12:34:21 <D> Range *: match.
18-Oct
12:34:21 <D> Call will be answered after 6 rings...
18-Oct 12:34:21 <J>
Reading modem input (6 secs timeout)...
18-Oct 12:34:21 <I> [ 1/ 6]
RING...
18-Oct 12:34:21 <J> Reading modem input (6 secs timeout)...
18-Oct
12:34:21 <D> Flushing modem...
18-Oct 12:34:21 <D> Sending
"ATS20?"...
18-Oct 12:34:21 <J> Reading modem echo (4 secs
timeout)...
18-Oct 12:34:21 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:34:21 <D> Got s-register value "1".
18-Oct 12:34:21
<D> Searching user with caller number "XXXXXXXX"...
18-Oct 12:34:21 <J>
Jumping to section "[CALLERIDS]"...
18-Oct 12:34:21 <D> Caller number match
user "*** Unknown ***"...
18-Oct 12:34:21 <D> Section "[STANDARD]" will be
used...
18-Oct 12:34:21 <D> Parsing settings from section
"[STANDARD]"...
18-Oct 12:34:21 <J> Jumping to section
"[STANDARD]"...
18-Oct 12:34:21 <D> Parsing time(s) "*"...
18-Oct 12:34:21
<D> Range **:**:** - **:**:** (12:34:21): match.
18-Oct 12:34:21 <D>
Parsing day(s) "*"...
18-Oct 12:34:21 <D> Range *: match.
18-Oct 12:34:21
<J> Found Flag "RINGS=6"...
18-Oct 12:34:21 <J> Found Flag
"TOLLRINGS=6"...
18-Oct 12:34:21 <J> Settings: Message
"/var/spool/vbox/apm/messages/standard.msg".
18-Oct 12:34:21 <J> Settings:
Beep "/var/spool/vbox/apm/messages/beep.msg".
18-Oct 12:34:21 <J> Settings:
Timeout "/var/spool/vbox/apm/messages/timeout.msg".
18-Oct 12:34:21 <J>
Settings: Script "/var/spool/vbox/apm/standard.tcl".
18-Oct 12:34:21 <J>
Settings: Rings changed to 6.
18-Oct 12:34:21 <J> Settings: Rings changed
to 6 if new message exist.
18-Oct 12:34:21 <J> Settings: Check for new
messages in "/var/spool/vbox/apm/incoming".
18-Oct 12:34:21 <J> Settings:
180 secs record time.
18-Oct 12:34:21 <J> Settings: Answer call.
18-Oct
12:34:21 <J> Settings: Record a message.
18-Oct 12:34:21 <J> Settings: Play
standard message.
18-Oct 12:34:21 <J> Settings: Play beep message.
18-Oct
12:34:21 <J> Settings: Play timeout message.
18-Oct 12:34:21 <I> [ 1/ 6]
CALLER NUMBER: XXXXXXXX (*** Unknown ***)...
18-Oct 12:34:21 <J> Reading
modem input (6 secs timeout)...
18-Oct 12:34:26 <I> [ 2/ 6] RING...
18-Oct
12:34:26 <J> Reading modem input (6 secs timeout)...
18-Oct 12:34:31 <I> [
3/ 6] RING...
18-Oct 12:34:31 <J> Reading modem input (6 secs
timeout)...
18-Oct 12:34:36 <I> [ 4/ 6] RING...
18-Oct 12:34:36 <J> Reading
modem input (6 secs timeout)...
18-Oct 12:34:41 <I> [ 5/ 6] RING...
18-Oct
12:34:41 <J> Reading modem input (6 secs timeout)...
18-Oct 12:34:46 <I> [
6/ 6] RING...
18-Oct 12:34:46 <D> Initializing tcl script
"/var/spool/vbox/apm/standard.tcl"...
18-Oct 12:34:46 <D> Answering
call...
18-Oct 12:34:46 <D> Flushing modem...
18-Oct 12:34:46 <D> Sending
"ATA"...
18-Oct 12:34:46 <J> Reading modem echo (4 secs timeout)...
18-Oct
12:34:46 <J> Reading modem input (4 secs timeout)...
18-Oct 12:34:46 <D>
Waiting for "VCON|CONNECT"... Got "VCON" (1).
18-Oct 12:34:46 <I> Running
tcl script "/var/spool/vbox/apm/standard.tcl"...
18-Oct 12:34:46 <I>
Playing "/var/spool/vbox/apm/messages/standard.msg"...
18-Oct 12:34:46 <D>
Setting voice compression "ULAW"...
18-Oct 12:34:46 <D> Flushing
modem...
18-Oct 12:34:46 <D> Sending "AT+VSM=6+VLS=2"...
18-Oct 12:34:46
<J> Reading modem echo (4 secs timeout)...
18-Oct 12:34:46 <J> Reading
modem input (4 secs timeout)...
18-Oct 12:34:46 <D> Waiting for
"OK|VCON"... Got "OK" (1).
18-Oct 12:34:46 <D> Flushing modem...
18-Oct
12:34:46 <D> Sending "AT+VTX"...
18-Oct 12:34:46 <J> Reading modem echo (4
secs timeout)...
18-Oct 12:34:46 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:34:46 <D> Waiting for "CONNECT"... Got it.
18-Oct
12:34:46 <J> Play: <DATA 256 incoming; 256 outgoing>
[...]
18-Oct 12:35:01
<J> Play: <DATA 256 incoming; 256 outgoing>
18-Oct 12:35:01 <D> End of
audio data
18-Oct 12:35:01 <J> Function play 15 secs (kernel needs 16
secs)...
18-Oct 12:35:01 <J> Waiting 1 secs to complete playing...
18-Oct
12:35:02 <J> Sending "<DLE><ETX>"...
18-Oct 12:35:02 <J> Reading modem
input (4 secs timeout)...
18-Oct 12:35:02 <D> Waiting for "VCON"... Got
it.
18-Oct 12:35:02 <D> [vbox_put_message] result "OK".
18-Oct 12:35:02 <J>
[vbox_pause] waiting 500 ms...
18-Oct 12:35:03 <I> Playing
"/var/spool/vbox/apm/messages/beep.msg"...
18-Oct 12:35:03 <D> Setting
voice compression "ULAW"...
18-Oct 12:35:03 <D> Flushing modem...
18-Oct
12:35:03 <D> Sending "AT+VSM=6+VLS=2"...
18-Oct 12:35:03 <J> Reading modem
echo (4 secs timeout)...
18-Oct 12:35:03 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:35:03 <D> Waiting for "OK|VCON"... Got "OK"
(1).
18-Oct 12:35:03 <D> Flushing modem...
18-Oct 12:35:03 <D> Sending
"AT+VTX"...
18-Oct 12:35:03 <J> Reading modem echo (4 secs
timeout)...
18-Oct 12:35:03 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:35:03 <D> Waiting for "CONNECT"... Got it.
18-Oct
12:35:03 <J> Play: <DATA 256 incoming; 256 outgoing>
[...]
18-Oct 12:35:05
<J> Play: <DATA 256 incoming; 256 outgoing>
18-Oct 12:35:05 <D> End of
audio data
18-Oct 12:35:05 <J> Function play 2 secs (kernel needs 3
secs)...
18-Oct 12:35:05 <J> Waiting 1 secs to complete playing...
18-Oct
12:35:06 <J> Sending "<DLE><ETX>"...
18-Oct 12:35:06 <J> Reading modem
input (4 secs timeout)...
18-Oct 12:35:06 <D> Waiting for "VCON"... Got
it.
18-Oct 12:35:06 <D> [vbox_put_message] result "OK".
18-Oct 12:35:06 <J>
[vbox_pause] waiting 500 ms...
18-Oct 12:35:06 <I> Recording
"/var/spool/vbox/apm/incoming/00001318934086-00009674" (180 secs)...
18-Oct
12:35:06 <D> Setting voice compression "ULAW"...
18-Oct 12:35:06 <D>
Flushing modem...
18-Oct 12:35:06 <D> Sending "AT+VSM=6+VLS=2"...
18-Oct
12:35:06 <J> Reading modem echo (4 secs timeout)...
18-Oct 12:35:06 <J>
Reading modem input (4 secs timeout)...
18-Oct 12:35:06 <D> Waiting for
"OK|VCON"... Got "OK" (1).
18-Oct 12:35:06 <D> Flushing modem...
18-Oct
12:35:06 <D> Sending "AT+VRX"...
18-Oct 12:35:06 <J> Reading modem echo (4
secs timeout)...
18-Oct 12:35:06 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:35:06 <D> Waiting for "CONNECT"... Got it.
18-Oct
12:35:06 <J> Record: <DATA 256 incoming; 256 outgoing>
[...]
18-Oct
12:35:10 <J> Record: <DATA 256 incoming; 256 outgoing>
18-Oct 12:35:10 <J>
*** NO CARRIER ***
18-Oct 12:35:10 <D> Found sequence "<DLE><ETX>" (remote
hangup)...
18-Oct 12:35:10 <D> Setting
"/var/spool/vbox/apm/incoming/00001318934086-00009674" to 2001.201
(0600)...
18-Oct 12:35:10 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:35:10 <D> Waiting for "NO CARRIER"... Got it.
18-Oct
12:35:10 <D> [vbox_get_message] result "HANGUP".
18-Oct 12:35:10 <J>
[vbox_pause] waiting 1000 ms...
18-Oct 12:35:11 <E> In
"/var/spool/vbox/apm/standard.tcl":  (line 40).
18-Oct 12:35:11 <D> Freeing
tcl interpreter...
18-Oct 12:35:11 <E> General tcl problem - setting flag
to quit program...
18-Oct 12:35:11 <D> Hangup modem (drop dtr 400
ms)...
18-Oct 12:35:11 <D> Flushing modem (timeout)...
18-Oct 12:35:12 <D>
Blocking all signals (0-64)...
18-Oct 12:35:12 <I> Exit program on signal
15...
18-Oct 12:35:12 <D> Hangup modem (drop dtr 400 ms)...
18-Oct 12:35:12
<D> Flushing modem (timeout)...
18-Oct 12:35:12 <D> Closing modem port
(4)...
18-Oct 12:35:12 <D> Unlocking vboxgetty
(/var/run/vboxgetty-ttyI6.pid)...
18-Oct 12:35:12 <D> Unlocking modem port
(/var/lock/LCK..ttyI6)...
18-Oct 12:35:12 <I> ------------------------[End
session]-----------------------
18-Oct 12:35:12 <I>
-----------------------[Begin session]----------------------
18-Oct
12:35:12 <I> Running vbox version 2.0.0BETA5...
18-Oct 12:35:12 <I> Tcl
interpreter version 8.3...
18-Oct 12:35:12 <D> Found command 'debuglevel =
FEWIDJ' (-global-)...
18-Oct 12:35:12 <D> Found command 'port =
/dev/ttyI6'...
18-Oct 12:35:12 <D> Found command 'modeminit =
ATZ&B512&E4459162' (/dev/ttyI6)...
18-Oct 12:35:12 <D> Found command 'user
= apm' (/dev/ttyI6)...
18-Oct 12:35:12 <D> Found command 'group = vbox'
(/dev/ttyI6)...
18-Oct 12:35:12 <D> Found command 'spooldir =
/var/spool/vbox/apm' (/dev/ttyI6)...
18-Oct 12:35:12 <I> User apm's
messagebox is "/var/spool/vbox/apm"...
18-Oct 12:35:12 <I> User apm's
vbox.conf is "/var/spool/vbox/apm/vbox.conf"...
18-Oct 12:35:12 <D> Setting
"/var/spool/vbox/apm" to 2001.201 (0700)...
18-Oct 12:35:12 <D> Opening
modem port "/dev/ttyI6"...
18-Oct 12:35:12 <D> Locking vboxgetty
(/var/run/vboxgetty-ttyI6.pid)...
18-Oct 12:35:12 <D> Setting
"/var/run/vboxgetty-ttyI6.pid" to 2001.201 (0644)...
18-Oct 12:35:12 <D>
Locking modem port (/var/lock/LCK..ttyI6)...
18-Oct 12:35:12 <D> Setting
"/var/lock/LCK..ttyI6" to 2001.201 (0644)...
18-Oct 12:35:12 <I> Drop
permissions to userid 2001; groupid 201...
18-Oct 12:35:12 <I> Initializing
modem port (voice mode; 2500 ms)...
18-Oct 12:35:15 <D> Hangup modem (drop
dtr 400 ms)...
18-Oct 12:35:15 <D> Flushing modem (timeout)...
18-Oct
12:35:15 <J> Setting modem speed to 57600...
18-Oct 12:35:15 <J> Setting
modem flow control (HARD)...
18-Oct 12:35:15 <D> Flushing modem...
18-Oct
12:35:15 <D> Sending "ATZ&B512&E4459162"...
18-Oct 12:35:15 <J> Reading
modem echo (4 secs timeout)...
18-Oct 12:35:15 <J> Reading modem input (4
secs timeout)...
18-Oct 12:35:15 <D> Waiting for "OK"... Got it.
18-Oct
12:35:15 <D> Flushing modem...
18-Oct 12:35:15 <D> Sending
"AT+FCLASS=8"...
18-Oct 12:35:15 <J> Reading modem echo (4 secs
timeout)...
18-Oct 12:35:15 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:35:15 <D> Waiting for "OK|VCON"... Got "OK"
(1).
18-Oct 12:35:15 <D> Flushing modem...
18-Oct 12:35:15 <D> Sending
"ATS13.2=1S13.4=1"...
18-Oct 12:35:15 <J> Reading modem echo (4 secs
timeout)...
18-Oct 12:35:15 <J> Reading modem input (4 secs
timeout)...
18-Oct 12:35:15 <D> Waiting for "OK|VCON"... Got "OK"
(1).
18-Oct 12:35:15 <D> Flushing modem...
18-Oct 12:35:15 <I> Waiting...






messages:


Oct 18 12:33:40 eis init: Re-reading inittab
Oct 18 12:33:40 eis
xinetd[7500]: Starting reconfiguration
Oct 18 12:33:40 eis xinetd[7500]:
Reading included configuration file: /etc/xinetd.d/ftp
[file=/etc/xinetd.conf] [line=7]
Oct 18 12:33:40 eis xinetd[7500]: Reading
included configuration file: /etc/xinetd.d/netstat
[file=/etc/xinetd.d/netstat] [line=11]
Oct 18 12:33:40 eis xinetd[7500]:
Reading included configuration file: /etc/xinetd.d/sshd
[file=/etc/xinetd.d/sshd] [line=15]
Oct 18 12:33:40 eis xinetd[7500]:
Reading included configuration file: /etc/xinetd.d/telnet
[file=/etc/xinetd.d/telnet] [line=14]
Oct 18 12:33:40 eis xinetd[7500]:
Reading included configuration file: /etc/xinetd.d/tftp
[file=/etc/xinetd.d/tftp] [line=9]
Oct 18 12:33:40 eis xinetd[7500]:
Reading included configuration file: /etc/xinetd.d/time
[file=/etc/xinetd.d/time] [line=10]
Oct 18 12:33:40 eis xinetd[7500]:
Reading included configuration file: /etc/xinetd.d/vbox
[file=/etc/xinetd.d/vbox] [line=21]
Oct 18 12:33:40 eis xinetd[7500]:
removing ftp
Oct 18 12:33:40 eis xinetd[7500]: removing netstat
Oct 18
12:33:40 eis xinetd[7500]: removing ssh
Oct 18 12:33:40 eis xinetd[7500]:
removing telnet
Oct 18 12:33:40 eis xinetd[7500]: removing tftp
Oct 18
12:33:40 eis xinetd[7500]: removing time
Oct 18 12:33:40 eis xinetd[7500]:
removing time
Oct 18 12:33:40 eis xinetd[7500]: Swapping defaults
Oct 18
12:33:40 eis xinetd[7500]: Reconfigured: new=1 old=0 dropped=0
(services)
Oct 18 12:34:21 eis kernel: capidrv-1: incoming call
XXXXXXXX,1,1,4459162
Oct 18 12:34:21 eis kernel: capidrv-1: patching si2=1
to 0 for VBOX
Oct 18 12:34:21 eis kernel: isdn_net: call from XXXXXXXX -> 0
4459162 ignored
Oct 18 12:34:21 eis kernel: isdn_tty: call from XXXXXXXX,
-> RING on ttyI6
Oct 18 12:34:21 eis kernel: capidrv-1: incoming call
XXXXXXXX,1,0,4459162 tty alerting
Oct 18 12:34:46 eis kernel:
capilib_new_ncci: kcapi: appl 1 ncci 0x10101 up
Oct 18 12:34:46 eis kernel:
capidrv-1: chan 0 up with ncci 0x10101
Oct 18 12:35:10 eis kernel: kcapi:
appl 1 ncci 0x10101 down
Oct 18 12:35:10 eis kernel: capidrv-1:
DISCONNECT_IND reason 0x3490 (..) for plci 0x101
Oct 18 12:35:56 eis
sshd[9969]: PAM unable to dlopen(/lib/security/pam_smbpass.so):
/lib/security/pam_smbpass.so: cannot open shared object file: No such file
or directory
Oct 18 12:35:56 eis sshd[9969]: PAM adding faulty module:
/lib/security/pam_smbpass.so
Oct 18 12:35:56 eis sshd[9969]: Accepted
keyboard-interactive/pam for root from 192.168.0.104 port 50034 ssh2
Oct 18
12:35:56 eis sshd[9969]: subsystem request for sftp by user root