[Eisfair] VBOX geht nicht ran

Sa Feb 13 19:37:55 CET 2021

Sorry für den fullquote, aber ich hatte das aus privaten Gründen aus den 
Augen verloren und will das jetzt aber doch nochmal angehen:

Hat niemand eine Idee, wo das Problem liegen könnte? Wie gesagt,  das 
älteste vboxgetty-ttyIx.log das ich finde, wo ein Anruf war, ist vom 
3.11. und hatte schon den Fehler.

Und die letzte aufgezeichnete Nachricht ist vom April 2020.

Und EISfax nimmt Faxe wunderbar an über dieselbe ISDN-Karte.

Hat denn überhaupt noch jemand hier vbox am Laufen?

Ciao, Stephan

Am 11.12.2020 um 11:54 schrieb 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...