[Eisfair] VBOX geht nicht ran

[Stephan Manske]

Hallo!

Ich habe - leider kann ich nicht genau sagen seit wann - ein Problem mit 
meiner VBOX 1.1.8: sie geht nicht ans Telefon. Hat jemand eine Idee, wo 
ich den Fehler suchen sollte?

Meine Konfig:

base     : 2.8.25
eiskernel: 4.9.245-eisfair-1-PAE

Detecting ISDN-Devices ...
1 f1pci      running  f1pci-d400-19    A1 3.11-07 0xd400 19
avm_fritz-1_pci_4.9.245-eisfair-1-pae

Asterisk installiert aber nicht aktiviert

Die interne ISDN-MSN-Zuweisung sollte iO sein, da vbox laut Logfile 
reagiert. Aber es wird halt nicht der Ruf angenommen.

Um Fehler in meiner individuellen Konfig oder den Ansagedateien 
auszuschließen, habe ich einen neuen Eintrag "test" generiert.

vbox Konfig

#----------------------------------------------------------------------------------
# general settings
#----------------------------------------------------------------------------------

START_VBOX='yes'                       # start vbox daemon: yes or no
VBOX_TRUSTED_NETS_N='1'                # number of trusted nets or hosts
VBOX_TRUSTED_NETS_1='192.168.10.0/24'  # 1. net or host
VBOX_TYPE='isdn'                       # vbox type: 'analog' or 'isdn'
VBOX_COMPRESSION='ulaw'
VBOX_UMASK='077'
VBOX_FREESPACE='2000000'               # if space < freespace a call 
isn't accepted
VBOX_DEBUGLEVEL='fewidj'               # FEWIDJ means log everything, -> 
see docs

VBOX_USER_3_ACTIVE='yes'
VBOX_USER_3_ISDN_PORT='/dev/ttyI6'
VBOX_USER_3_ISDN_MSN='50'
VBOX_USER_3_NAME='test'
VBOX_USER_3_GROUP='vbox'
VBOX_USER_3_PASS='vbox'
VBOX_USER_3_NUMBER_PREFIX=''
VBOX_USER_3_CALL_ENABLED='no'
VBOX_USER_3_CALL_NUMBER=''
VBOX_USER_3_MAIL_ENABLED='no'
VBOX_USER_3_MAIL_TYPE='subject'
VBOX_USER_3_MAIL_ADDR='frank at local.lan'
VBOX_USER_3_MAIL_SUBJECT='New message from %cname (%cid)'
VBOX_USER_3_MAIL_SOUND_FORMAT='wav'
VBOX_USER_3_RSS_ENABLED='no'
VBOX_USER_3_RSS_SUBJECT='New message from %cname (%cid)'
VBOX_USER_3_CALLERID_UNKNOWN='*** Unknown ***'
VBOX_USER_3_CALLERID_LOOKUP='no'
VBOX_USER_3_CALLERID_N='0'
VBOX_USER_3_RING_STANDARD_RINGS='2'
VBOX_USER_3_RING_N='0'
VBOX_USER_3_SECTION_STANDARD_DEF_RECTIME='90'
VBOX_USER_3_SECTION_STANDARD_DEF_RINGS='2'
VBOX_USER_3_SECTION_STANDARD_DEF_TOLLRINGS='2'
VBOX_USER_3_SECTION_STANDARD_N='0'
VBOX_USER_3_SECTION_N='0'



logfile


11-Dec 11:39:16 <I> -----------------------[Begin 
session]----------------------
11-Dec 11:39:16 <I> Running vbox version 2.0.0BETA5...
11-Dec 11:39:16 <I> Tcl interpreter version 8.4...
11-Dec 11:39:16 <D> Found command 'debuglevel = FEWIDJ' (-global-)...
11-Dec 11:39:16 <D> Found command 'port = /dev/ttyI7'...
11-Dec 11:39:16 <D> Found command 'port = /dev/ttyI6'...
11-Dec 11:39:16 <D> Found command 'modeminit = ATZ&B512&E50' (/dev/ttyI6)...
11-Dec 11:39:16 <D> Found command 'user = test' (/dev/ttyI6)...
11-Dec 11:39:16 <D> Found command 'group = vbox' (/dev/ttyI6)...
11-Dec 11:39:16 <D> Found command 'spooldir = /var/spool/vbox/test' 
(/dev/ttyI6)...
11-Dec 11:39:16 <I> User test's messagebox is "/var/spool/vbox/test"...
11-Dec 11:39:16 <I> User test's vbox.conf is 
"/var/spool/vbox/test/vbox.conf"...
11-Dec 11:39:16 <D> Setting "/var/spool/vbox/test" to 2008.201 (0700)...
11-Dec 11:39:16 <D> Opening modem port "/dev/ttyI6"...
11-Dec 11:39:16 <D> Locking vboxgetty (/var/run/vboxgetty-ttyI6.pid)...
11-Dec 11:39:16 <D> Setting "/var/run/vboxgetty-ttyI6.pid" to 2008.201 
(0644)...
11-Dec 11:39:16 <D> Locking modem port (/var/lock/LCK..ttyI6)...
11-Dec 11:39:16 <D> Setting "/var/lock/LCK..ttyI6" to 2008.201 (0644)...
11-Dec 11:39:16 <I> Drop permissions to userid 2008; groupid 201...
11-Dec 11:39:16 <I> Initializing modem port (voice mode; 2500 ms)...
11-Dec 11:39:18 <D> Hangup modem (drop dtr 400 ms)...
11-Dec 11:39:18 <D> Flushing modem (timeout)...
11-Dec 11:39:19 <J> Setting modem speed to 57600...
11-Dec 11:39:19 <J> Setting modem flow control (HARD)...
11-Dec 11:39:19 <D> Flushing modem...
11-Dec 11:39:19 <D> Sending "ATZ&B512&E50"...
11-Dec 11:39:19 <J> Reading modem echo (4 secs timeout)...
11-Dec 11:39:19 <J> Reading modem input (4 secs timeout)...
11-Dec 11:39:19 <D> Waiting for "OK"... Got it.
11-Dec 11:39:19 <D> Flushing modem...
11-Dec 11:39:19 <D> Sending "AT+FCLASS=8"...
11-Dec 11:39:19 <J> Reading modem echo (4 secs timeout)...
11-Dec 11:39:19 <J> Reading modem input (4 secs timeout)...
11-Dec 11:39:19 <D> Waiting for "OK|VCON"... Got "OK" (1).
11-Dec 11:39:19 <D> Flushing modem...
11-Dec 11:39:19 <D> Sending "ATS13.2=1S13.4=1"...
11-Dec 11:39:19 <J> Reading modem echo (4 secs timeout)...
11-Dec 11:39:19 <J> Reading modem input (4 secs timeout)...
11-Dec 11:39:19 <D> Waiting for "OK|VCON"... Got "OK" (1).
11-Dec 11:39:19 <D> Flushing modem...
11-Dec 11:39:19 <I> Waiting...
11-Dec 11:39:40 <I> Wakeup!
11-Dec 11:39:40 <D> Checking free space on "/var/spool/vbox/test"...
11-Dec 11:39:40 <J> -2106200064 bytes available; 2000000 bytes needed... 
enough.
11-Dec 11:39:40 <D> Getting number of rings to wait before answer call...
11-Dec 11:39:40 <J> Jumping to section "[RINGS]"...
11-Dec 11:39:40 <D> Parsing time(s) "*"...
11-Dec 11:39:40 <D> Range **:**:** - **:**:** (11:39:40): match.
11-Dec 11:39:40 <D> Parsing day(s) "*"...
11-Dec 11:39:40 <D> Range *: match.
11-Dec 11:39:40 <D> Call will be answered after 2 rings...
11-Dec 11:39:40 <J> Reading modem input (6 secs timeout)...
11-Dec 11:39:40 <I> [ 1/ 2] RING...
11-Dec 11:39:40 <J> Reading modem input (6 secs timeout)...
11-Dec 11:39:40 <D> Flushing modem...
11-Dec 11:39:40 <D> Sending "ATS20?"...
11-Dec 11:39:40 <J> Reading modem echo (4 secs timeout)...
11-Dec 11:39:40 <J> Reading modem input (4 secs timeout)...
11-Dec 11:39:40 <D> Got s-register 20 value "1".
11-Dec 11:39:40 <D> TEST-tmpline 012345679
11-Dec 11:39:40 <D> TEST-tmpline-length 10
11-Dec 11:39:40 <D> Flushing modem...
11-Dec 11:39:40 <D> Sending "ATS21?"...
11-Dec 11:39:40 <J> Reading modem echo (4 secs timeout)...
11-Dec 11:39:40 <J> Reading modem input (4 secs timeout)...
11-Dec 11:39:40 <D> Got s-register 21 value "0".
11-Dec 11:39:40 <D> TEST-setup.voice.callerid 012345679
11-Dec 11:39:40 <D> Searching user with caller number "012345679"...
11-Dec 11:39:40 <J> Jumping to section "[CALLERIDS]"...
11-Dec 11:39:40 <D> Caller number match user "*** Unknown ***"...
11-Dec 11:39:40 <D> Section "[STANDARD]" will be used...
11-Dec 11:39:40 <D> Parsing settings from section "[STANDARD]"...
11-Dec 11:39:40 <J> Jumping to section "[STANDARD]"...
11-Dec 11:39:40 <D> Parsing time(s) "*"...
11-Dec 11:39:40 <D> Range **:**:** - **:**:** (11:39:40): match.
11-Dec 11:39:40 <D> Parsing day(s) "*"...
11-Dec 11:39:40 <D> Range *: match.
11-Dec 11:39:40 <J> Found Flag "RINGS=2"...
11-Dec 11:39:40 <J> Found Flag "TOLLRINGS=2"...
11-Dec 11:39:40 <J> Settings: Message 
"/var/spool/vbox/test/messages/standard.msg".
11-Dec 11:39:40 <J> Settings: Beep "/var/spool/vbox/test/messages/beep.msg".
11-Dec 11:39:40 <J> Settings: Timeout 
"/var/spool/vbox/test/messages/timeout.msg".
11-Dec 11:39:40 <J> Settings: Script "/var/spool/vbox/test/standard.tcl".
11-Dec 11:39:40 <J> Settings: Rings changed to 2.
11-Dec 11:39:40 <J> Settings: Rings changed to 2 if new message exist.
11-Dec 11:39:40 <J> Settings: Check for new messages in 
"/var/spool/vbox/test/incoming".
11-Dec 11:39:40 <J> Settings: 90 secs record time.
11-Dec 11:39:40 <J> Settings: Answer call.
11-Dec 11:39:40 <J> Settings: Record a message.
11-Dec 11:39:40 <J> Settings: Play standard message.
11-Dec 11:39:40 <J> Settings: Play beep message.
11-Dec 11:39:40 <J> Settings: Play timeout message.
11-Dec 11:39:40 <I> [ 1/ 2] CALLER NUMBER: 012345679 (*** Unknown ***)...
11-Dec 11:39:40 <J> Reading modem input (6 secs timeout)...
11-Dec 11:39:46 <J> Modem timeout function called...
11-Dec 11:39:46 <J> Can't read from modem [0] (timeout).
11-Dec 11:39:46 <I> Initializing modem port (voice mode; 2500 ms)...
11-Dec 11:39:48 <D> Hangup modem (drop dtr 400 ms)...
11-Dec 11:39:48 <D> Flushing modem (timeout)...
11-Dec 11:39:48 <J> Junk: <CR>
11-Dec 11:39:48 <J> Junk: <NL>
11-Dec 11:39:48 <J> Junk: R
11-Dec 11:39:48 <J> Junk: I
11-Dec 11:39:48 <J> Junk: N
11-Dec 11:39:48 <J> Junk: G
11-Dec 11:39:48 <J> Junk: <CR>
11-Dec 11:39:48 <J> Junk: <NL>
11-Dec 11:39:49 <J> Setting modem speed to 57600...
11-Dec 11:39:49 <J> Setting modem flow control (HARD)...
11-Dec 11:39:49 <D> Flushing modem...
11-Dec 11:39:49 <D> Sending "ATZ&B512&E50"...
11-Dec 11:39:49 <J> Reading modem echo (4 secs timeout)...
11-Dec 11:39:49 <J> Reading modem input (4 secs timeout)...
11-Dec 11:39:49 <D> Waiting for "OK"... Got it.
11-Dec 11:39:49 <D> Flushing modem...
11-Dec 11:39:49 <D> Sending "AT+FCLASS=8"...
11-Dec 11:39:49 <J> Reading modem echo (4 secs timeout)...
11-Dec 11:39:49 <J> Reading modem input (4 secs timeout)...
11-Dec 11:39:49 <D> Waiting for "OK|VCON"... Got "OK" (1).
11-Dec 11:39:49 <D> Flushing modem...
11-Dec 11:39:49 <D> Sending "ATS13.2=1S13.4=1"...
11-Dec 11:39:49 <J> Reading modem echo (4 secs timeout)...
11-Dec 11:39:49 <J> Reading modem input (4 secs timeout)...
11-Dec 11:39:49 <D> Waiting for "OK|VCON"... Got "OK" (1).
11-Dec 11:39:49 <D> Flushing modem...
11-Dec 11:39:49 <I> Waiting...