[Gelöst] Asterisk 1.8.18, Hylafax5.2.5 Faxempfang O.K. Versand schägt fehl

BigChief

Neuer User
Mitglied seit
25 Mrz 2006
Beiträge
75
Punkte für Reaktionen
0
Punkte
0
HEU!

Im Moment stehe ich (mal wieder) auf dem Schlauch.

Der Faxempfang von meinem neuen Fax/VoIP-Server geht ohne Probleme, nur der Versand scheitert. :eek:
iax.conf
Code:
[iaxmodem0]
type=friend
secret=P1r@t3n!
port=4570
host=dynamic
idefaultip=10.10.10.10
deny = 0.0.0.0/0.0.0.0
permit = 10.10.10.10
context=fax-out
disallow=all
allow=alaw

[iaxmodem1]
type=friend
secret=P1r@t3n!
port=4571
host=dynamic
idefaultip=10.10.10.10
deny = 0.0.0.0/0.0.0.0
permit = 10.10.10.10
context=fax-out
disallow=all
allow=alaw
extensions.conf
Code:
[from-fax]
; Ruf vom IAXMODEM-Pool
exten => _XXXX.,1,Verbose(### Anruf von den iaxmodems an ${EXTEN} via to-pstn ###)
exten => _XXXX.,n,Set(CALLERID(num)=892160899)
exten => _XXXX.,n,SetTransferCapability(3K1AUDIO)
exten => _XXXX.,n,Dial(DAHDI/G1/${EXTEN})
exten => _XXXX.,n,Hangup
Die beiden IAXmodems registrieren sich auch:
Code:
pml010010*CLI> iax2 show peers
Name/Username    Host                 Mask             Port          Status    
iaxmodem0        192.168.10.10   (D)  255.255.255.255  4570          Unmonitored
iaxmodem1        192.168.10.10   (D)  255.255.255.255  4571          Unmonitored

Verbinde ich mich mit minicom auf dem entsprechenden Device, so kann ich wie anno 1985 mittels AT-Befehle den IAXmodem steuern. Wähle ich eine Nummer mit "ATDT09876543212 eine Nummer an, passiert auf der Asterisk-Konsole absolut nix! :( Rufe ich den IAXmodem an, so sehe ich die RINGs.
Code:
Willkommen zu minicom 2.3

Optionen: I18n 
Übersetzt am Aug 19 2010, 05:50:19.
Port /dev/ttyIAX0

                 Drücken Sie CTRL-A  Z für Hilfe zu speziellen Tasten                
                                                                                     
OK                                                                                   
ATDT0987654321                                                                               
NO ANSWER                                                                            

DATE=1212

TIME=1551

NAME=

NMBR=

ANID=<NONE>

USER=<NONE>

PASS=<NONE>

CDID=<NONE>

NDID=9517

RING

RING

RING
Frage ich den Status meiner IAXmodems ab, sehe ich:
Code:
# faxstat
HylaFAX scheduler on pml010010.intra.nausch.org: Running
Modem ttyIAX0 (+49 89 982160899): Running and idle
Modem ttyIAX1 (+49 89 982160899): Running and idle

Der Versand via sendfax -n -d 08921609519116 /etc/motd schlägt genauso, wie mein minicom-Versuch fehl.

Im syslog steht lapidar nur:
Code:
Dec 12 15:19:23 pml010010 FaxSend[27307]: SEND FAILED: JOB 12 DEST 08921609519116 ERR No answer from remote {E003}
Das stimmt IMHO so ja gar nicht, da der IAX-Modem ja nachweilich gar nicht versucht einen Channel in Richtung B-700 aufzumachen. :(

any idea?



ttyl
BigChief
 
Zuletzt bearbeitet:
ERR No answer from remote {E003}

Sagt doch das sich das Faxgerät auf der anderen seite nichts zurück meldet
 
Griasdebou!

Damit der Server so einen Fehler werfen kann, müsste er doch dazu auf der Asterisk-Konsole das Belegen eines IAX2-Channels anzeigen, oder?
Aber auf der Asterisk-Konsole tut sich absolut nix! Weder beim Versuch via sendfax... noch via minicom mit Anwahl über hayes-Befehle. Ich würde da erwarten, dass er beim Versuch rauszurufen sich ähnlich geschwätzig ist wie beim Anrufen der IAXmodem-Ports.

Sers
BigChief
 
Zuletzt bearbeitet von einem Moderator:
HI!

O.K. Du hast recht, das past natürlich nicht zusammen. Aber :/ es funktioniert immer noch nicht.

Irgendwo ist da noch 'n bug

cu
BC
 
Dann solltest Du mal die Asterisk -Ausgabe mit

Code:
core set verbose 5
iax2 set debug peer iaxmodem0
iax2 set debug peer iaxmodem1

bei einem Sendeversuch posten. Dann läßt sich das Problem mit Sicherheit finden.
 
HI!

Tja, das einzigste, was ich da zu sehen bekomme, ist die Reregistrierung des IAXmodems:
Code:
[Dec 12 22:44:28] NOTICE[17896]: chan_iax2.c:8806 update_registry: Restricting registration for peer 'iaxmodem0' to 60 seconds (requested 600)
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00015ms  SCall: 00316  DCall: 08622 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   DATE TIME       : 2012-12-12  22:44:28
   REFRESH         : 60
   APPARENT ADDRES : IPV4 192.168.10.10:4570

Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00015ms  SCall: 08622  DCall: 00316 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 08623  DCall: 00000 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   REFRESH         : 600

Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00009ms  SCall: 03051  DCall: 08623 [192.168.10.10:4570]
   AUTHMETHODS     : 3
   CHALLENGE       : \x31\x36\x31\x33\x35\x35\x35\x35\x39
   USERNAME        : iaxmodem0

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 08623  DCall: 03051 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   MD5 RESULT      : 760424f295c7c5a416ec991f152df2c4
   REFRESH         : 600

Egal was ich vom minicom aus wähle, das schlägt nichts durch zum Asterisken.

Anrufen kann ich:
Code:
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 08634  DCall: 00000 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   REFRESH         : 600

Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00017ms  SCall: 08443  DCall: 08634 [192.168.10.10:4570]
   AUTHMETHODS     : 3
   CHALLENGE       : \x31\x35\x31\x32\x35\x35\x35\x37\x39
   USERNAME        : iaxmodem0

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 08634  DCall: 08443 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   MD5 RESULT      : 91cee623d2b06e43cd855271c5017f0c
   REFRESH         : 600

[Dec 12 22:53:39] NOTICE[17900]: chan_iax2.c:8806 update_registry: Restricting registration for peer 'iaxmodem0' to 60 seconds (requested 600)
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00018ms  SCall: 08443  DCall: 08634 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   DATE TIME       : 2012-12-12  22:53:38
   REFRESH         : 60
   APPARENT ADDRES : IPV4 192.168.10.10:4570

Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00018ms  SCall: 08634  DCall: 08443 [192.168.10.10:4570]
    -- Accepting call from '045534406' to '883179' on channel 0/1, span 1
    -- Executing [88318879@from-pstn:1] Verbose("DAHDI/i1/017645534406-1", "1,### Anruf von "from-pstn" ISDN 88318879 an (DAHDI-Channel 5 ###") in new stack
 ### Anruf von "from-pstn" ISDN 88318879 an (DAHDI-Channel 5 ###
    -- Executing [883179@from-pstn:2] Dial("DAHDI/i1/045534406-1", "IAX2/iaxmodem0/883179,15,r") in new stack
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00012ms  SCall: 03734  DCall: 00000 [192.168.10.10:4570]
   VERSION         : 2
   CALLED NUMBER   : 88318879
   CODEC_PREFS     : (alaw)
   CALLING NUMBER  : 045534406
   CALLING PRESNTN : 3
   CALLING TYPEOFN : 33
   CALLING TRANSIT : 0
   CALLING NAME    : 
   LANGUAGE        : en
   FORMAT          : 8
   FORMAT2         : alaw
   CAPABILITY      : 8
   CAPABILITY2     : alaw
   ADSICPE         : 2
   DATE TIME       : 2012-12-12  22:53:56

    -- Called IAX2/iaxmodem0/883179
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: ACK    
   Timestamp: 00012ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: ACCEPT 
   Timestamp: 00015ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
   FORMAT          : 8

    -- Call accepted by 192.168.10.10 (format alaw)
    -- Format for call is alaw
Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: ACK    
   Timestamp: 00015ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: CONTROL Subclass: RINGING
   Timestamp: 00015ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00015ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
    -- IAX2/iaxmodem0-3734 is ringing
Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 001 Type: IAX     Subclass: PING   
   Timestamp: 02002ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 003 Type: IAX     Subclass: PONG   
   Timestamp: 02002ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 02002ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: ANSWER 
   Timestamp: 05222ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 004 Type: IAX     Subclass: ACK    
   Timestamp: 05222ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
    -- IAX2/iaxmodem0-3734 answered DAHDI/i1/017645534406-1
Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 004 Type: CONTROL Subclass: SRCUPDT
   Timestamp: 05236ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 004 Type: VOICE   Subclass: 8
   Timestamp: 05260ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 002 Type: VOICE   Subclass: 8
   Timestamp: 05260ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 005 Type: IAX     Subclass: ACK    
   Timestamp: 05260ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 004 Type: IAX     Subclass: ACK    
   Timestamp: 05260ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 005 Type: IAX     Subclass: LAGRQ  
   Timestamp: 10018ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 005 Type: IAX     Subclass: ACK    
   Timestamp: 10018ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 005 Type: IAX     Subclass: LAGRP  
   Timestamp: 10018ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 006 Type: IAX     Subclass: ACK    
   Timestamp: 10018ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 005 Type: IAX     Subclass: PING   
   Timestamp: 12023ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 007 Type: IAX     Subclass: PONG   
   Timestamp: 12023ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OUTOFORDER   : 0

Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 006 Type: IAX     Subclass: ACK    
   Timestamp: 12023ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 006 Type: IAX     Subclass: HANGUP 
   Timestamp: 12823ms  SCall: 08635  DCall: 03734 [192.168.10.10:4570]
   CAUSE           : Normal disconnect

Tx-Frame Retry[-01] -- OSeqno: 006 ISeqno: 008 Type: IAX     Subclass: ACK    
   Timestamp: 12823ms  SCall: 03734  DCall: 08635 [192.168.10.10:4570]
    -- Hungup 'IAX2/iaxmodem0-3734'
  == Spawn extension (from-pstn, 88318879, 2) exited non-zero on 'DAHDI/i1/045534406-1'
    -- Hungup 'DAHDI/i1/045534406-1'
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 08636  DCall: 00000 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   REFRESH         : 600

Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00001ms  SCall: 08787  DCall: 08636 [192.168.10.10:4570]
   AUTHMETHODS     : 3
   CHALLENGE       : \x32\x35\x33\x31\x38\x38\x33\x39\x30
   USERNAME        : iaxmodem0

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 08636  DCall: 08787 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   MD5 RESULT      : 4def4bf211bc56f9e9603ff19413d561
   REFRESH         : 600

[Dec 12 22:54:34] NOTICE[17893]: chan_iax2.c:8806 update_registry: Restricting registration for peer 'iaxmodem0' to 60 seconds (requested 600)
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00002ms  SCall: 08787  DCall: 08636 [192.168.10.10:4570]
   USERNAME        : iaxmodem0
   DATE TIME       : 2012-12-12  22:54:34
   REFRESH         : 60
   APPARENT ADDRES : IPV4 192.168.10.10:4570

Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00002ms  SCall: 08636  DCall: 08787 [192.168.10.10:4570]

Irgendwie sieht es aus, wie wenn der context aus der iax.conf nicht zu dem in der extensions.conf passen würde, oder anders ausgedrückt, der Anwahlversuch fällt nicht in den context from-fax:

Code:
 [iaxmodem0]
type=friend
secret=P1r@t3nP4rTe1
port=4570
host=dynamic
idefaultip=192.168.10.10
deny = 0.0.0.0/0.0.0.0
permit = 192.168.10.10
context=from-fax
disallow=all
allow=alaw

Code:
[from-fax]
exten => 11,1,Dial(SIP/11,60)
exten => 11,n,Hangup

cu
BC
 
Mach mal nicht minicom sondern sendfax und poste das com-log von Hylafax.
Die Asterisk-Konfiguration stimmt hier jedenfalls.
Und: da Du die iax.conf angepasst hast, bitte (neben dem reload des Moduls in Asterisk) auch mal die iaxmodems neu starten (das sollte zwar keine Rolle spielen, aber ...)

Das Spannende ist hier, dass Asterisk den Call gar nicht bekommt. Das heißt, Hylafax (respektive minicom) setzt das ATDT nicht via (logischen Link) Modemdevice (ttyIAX0 bzw. ttyIAX1) auf Modem (iaxmodem0 bzw. iaxmodem1) ab.
Hättest Du ein Kontextproblem innerhalb von Asterisk (was Du nicht hast), würdest Du das im Debug sehen, da dann der Call da wäre und kein Ziel finden würde ...

Deshalb mal wirklich über sendfax machen und die comm-log von Hylafax anschauen, was er mit dem ATDT macht ...
 
HI!
Mach mal nicht minicom sondern sendfax und poste das com-log von Hylafax.
Tja, ich dachte mir hald, mach es so wie damals. Steck den Modem von der Dose, häng ein einfaches Telefon dran und prüfe, ob:
  • man ein Freizeichen bekommt,
  • man eine andere Nst. anrufen kann
Daher der Weg via minicom. :rolleyes:
Deshalb mal wirklich über sendfax machen und die comm-log von Hylafax anschauen, was er mit dem ATDT macht ...
O.K., ich will ja nix unversucht lassen:
Code:
# sendfax -n -d 089216095916 /etc/motd
Also, im Syslog macht er folgendes:
Code:
Dec 13 09:13:09 pml010010 FaxQueuer[9003]: JOB 17 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:59): PREPARE START
Dec 13 09:13:09 pml010010 FaxQueuer[9916]: JOB 17 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:59): CONVERT DOCUMENT: bin/ps2fax -i 17 -o docq/doc17.ps;c0 -r 98 -w 1728 -l 297 -m 50 -U -3 docq/doc17.ps.17
Dec 13 09:13:10 pml010010 FaxQueuer[9003]: JOB 17 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:58): PREPARE DONE
Dec 13 09:13:10 pml010010 FaxQueuer[9003]: JOB 17 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:58): CMD START /usr/sbin/faxsend -m ttyIAX0 sendq/q17 (PID 9922)
Dec 13 09:13:11 pml010010 FaxSend[9922]: MODEM set DTR OFF
Dec 13 09:13:11 pml010010 FaxSend[9922]: MODEM set baud rate: 0 baud (flow control unchanged)
Dec 13 09:13:11 pml010010 FaxSend[9922]: DELAY 75 ms
Dec 13 09:13:11 pml010010 FaxSend[9922]: MODEM set DTR ON
Dec 13 09:13:11 pml010010 FaxSend[9922]: DELAY 2600 ms
Dec 13 09:13:14 pml010010 FaxSend[9922]: MODEM set baud rate: 19200 baud, input flow XON/XOFF, output flow XON/XOFF
Dec 13 09:13:14 pml010010 FaxSend[9922]: DELAY 10 ms
Dec 13 09:13:14 pml010010 FaxSend[9922]: MODEM flush i/o
Dec 13 09:13:14 pml010010 FaxSend[9922]: <-- [4:ATZ\r]
Dec 13 09:13:15 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:15 pml010010 FaxSend[9922]: DELAY 3000 ms
Dec 13 09:13:18 pml010010 FaxSend[9922]: MODEM flush i/o
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [10:AT+VCID=1\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [9:AT+VCID=1]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [7:ATS0=0\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [6:ATS0=0]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [5:ATE0\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [4:ATE0]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [5:ATV1\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [5:ATQ0\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [7:ATS8=2\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [8:ATS7=60\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [12:AT+FCLASS=?\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [7:0,1,1.0]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [12:AT+FCLASS=1\r]
Dec 13 09:13:18 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:18 pml010010 FaxSend[9922]: <-- [5:ATI3\r]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [19:www.soft-switch.org]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:19 pml010010 FaxSend[9922]: <-- [5:ATI0\r]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [7:spandsp]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:19 pml010010 FaxSend[9922]: <-- [9:AT+FTM=?\r]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [39:24,48,72,73,74,96,97,98,121,122,145,146]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:19 pml010010 FaxSend[9922]: <-- [9:AT+FRM=?\r]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [39:24,48,72,73,74,96,97,98,121,122,145,146]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:19 pml010010 FaxSend[9922]: MODEM WWW.SOFT-SWITCH.ORG spandsp/
Dec 13 09:13:19 pml010010 FaxSend[9922]: <-- [5:ATM0\r]
Dec 13 09:13:19 pml010010 FaxSend[9922]: --> [2:OK]
Dec 13 09:13:19 pml010010 FaxSend[9922]: MODEM input buffering disabled
Dec 13 09:13:19 pml010010 FaxSend[9922]: SEND FAX: JOB 17 DEST 089216095916 COMMID 000000155 DEVICE '/dev/ttyIAX0' FROM 'root <[email protected]>' USER root
Dec 13 09:14:04 pml010010 FaxSend[9922]: SEND FAILED: JOB 17 DEST 089216095916 ERR No answer from remote {E003}
Dec 13 09:14:06 pml010010 FaxQueuer[9003]: JOB 17 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:02): CMD DONE: exit status 0
Dec 13 09:14:06 pml010010 FaxQueuer[9003]: JOB 17 (sleeping dest +4989216095916 pri 126 tts 0:00:00 killtime 2:58:02): SEND INCOMPLETE: requeue for 0:04:58; No answer from remote
Im hylafax-log steht dann "nur":
Code:
Dec 13 09:13:19.45: [ 9922]: SESSION BEGIN 000000155 +4989216095916
Dec 13 09:13:19.45: [ 9922]: HylaFAX (tm) Version 5.2.5
Dec 13 09:13:19.45: [ 9922]: SEND FAX: JOB 17 DEST 089216095916 COMMID 000000155 DEVICE '/dev/ttyIAX0' FROM 'root <[email protected]>' USER root
Dec 13 09:13:19.45: [ 9922]: <-- [12:AT+FCLASS=1\r]
Dec 13 09:13:19.46: [ 9922]: --> [2:OK]
Dec 13 09:13:19.46: [ 9922]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Dec 13 09:13:19.49: [ 9922]: DIAL 089216095916
Dec 13 09:13:19.49: [ 9922]: <-- [20:ATX3DT089216095916\r]
Dec 13 09:14:04.50: [ 9922]: --> [9:NO ANSWER]
Dec 13 09:14:04.50: [ 9922]: SEND FAILED: JOB 17 DEST 089216095916 ERR No answer from remote {E003}
Dec 13 09:14:05.50: [ 9922]: <-- [5:ATH0\r]
Dec 13 09:14:05.50: [ 9922]: --> [2:OK]
Dec 13 09:14:05.50: [ 9922]: MODEM set DTR OFF
Dec 13 09:14:05.50: [ 9922]: MODEM set baud rate: 0 baud (flow control unchanged)
Dec 13 09:14:05.50: [ 9922]: SESSION END

Sehr sonderbar das alles ist.

Joda
 
Das sieht so schlecht nicht aus. Was mich allerdings wundert:

Dec 13 09:13:19.46: [ 9922]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Dec 13 09:13:19.49: [ 9922]: DIAL 089216095916
Dec 13 09:13:19.49: [ 9922]: <-- [20:ATX3DT089216095916\r]

Bei meiner Installation ist das an der Stelle (aus einem Faxversand von mir):

Code:
Dec 04 17:56:52.68: [23143]: <-- [14:AT+VSID="",""\r]
Dec 04 17:56:52.68: [23143]: --> [2:OK]
Dec 04 17:56:52.72: [23143]: DIAL 04028655066
Dec 04 17:56:52.72: [23143]: <-- [16:ATDT04028655066\r]

Des Pudels Kern dürfte hier die Modem-Konfigurationsdatei unter /var/spool/hylafax/etc/config.ttyIAX0 respektive 1 sein. Die ist beei Dir vermutlich krumm.
Nimm mal folgendes:

Code:
# $Id: iaxmodem,v 1.1 2006/04/02 23:18:30 darren Exp $

#
# prototype config for the IAXmodem softmodem which uses
# the spandsp soft-DSP library
#

#
CountryCode:			49
AreaCode:			<Vorwahl ohne 0>
FAXNumber:	"" 
LongDistancePrefix:		0
InternationalPrefix:		00
DialStringRules:	etc/dialrules.europe
ServerTracing:		1
SessionTracing:		11
RecvFileMode:		0644
LogFileMode:		0644
DeviceMode:		0644
RingsBeforeAnswer:	1 
SpeakerVolume:	off 
GettyArgs:		"-h %l dx_%s"
LocalIdentifier:	"" 
TagLineFont:		etc/lutRS18.pcf
TagLineFormat:	"Von: %%l|%d.%m.%Y %H:%M:%S|Seite %%P von %%T" 
MaxRecvPages:	500 
#

#
#
# Modem-related stuff: should reflect modem command interface
# and hardware connection/cabling (e.g. flow control).
#
ModemType:		Class1		# use this to supply a hint

#
# The modem is taken off-hook during initialization, and then
# placed back on-hook when done to prevent glare.
#
ModemResetCmds:		"ATH1\nAT+VCID=1"	# enables CallID display
ModemReadyCmds:		ATH0
ModemSetOriginCmd:	AT+VSID="%s","%d"

Class1AdaptRecvCmd:	AT+FAR=1
Class1TMConnectDelay:	400		# counteract quick CONNECT response
Class1RMQueryCmd:	"!24,48,72,96"	# V.17 fast-train recv doesn't work well
Class1SwitchingCmd:  "<delay:7>"

CallIDPattern:          "NMBR="
CallIDPattern:          "NAME="
CallIDPattern:		"ANID="
CallIDPattern:          "NDID="
# Uncomment these if you really want them, but you probably don't.
#CallIDPattern:          "DATE="
#CallIDPattern:          "TIME="

Nach entsprechender Änderung hylafax und faxgetty sowie iaxmodem neu starten und dann nochmal testen.
 
Ahoi!
Des Pudels Kern dürfte hier die Modem-Konfigurationsdatei unter /var/spool/hylafax/etc/config.ttyIAX0 respektive 1 sein. Die ist beei Dir vermutlich krumm.
Das ist die, die IAXmodem mitgebracht hat:
Code:
If your application is HylaFAX, there is a HylaFAX modem configuration
file included with IAXmodem (config.ttyIAX).  The config file is for
HylaFAX version 4.2.2 and later.  Instead of running faxaddmodem,
simply edit this config file to your liking and then copy it to
/var/spool/hylafax/etc/.  (Run faxgetty and the rest of HylaFAX as
usual.)
Nimm mal folgendes:
O.K. werd ich nachher mal ausprobieren!

bis später ...


BigChief
 
HI!
Also, nach wie vor habe ich das selbe Bild. ;( D.H ich kann nix rausschicken. Im hylafax-log zeigt sich:
Code:
Dec 13 13:06:57.46: [15766]: SESSION BEGIN 000000168 +4989216095916
Dec 13 13:06:57.46: [15766]: HylaFAX (tm) Version 5.2.5
Dec 13 13:06:57.46: [15766]: SEND FAX: JOB 19 DEST 089216095916 COMMID 000000168 DEVICE '/dev/ttyIAX0' FROM 'root <[email protected]>' USER root
Dec 13 13:06:57.46: [15766]: <-- [12:AT+FCLASS=1\r]
Dec 13 13:06:57.47: [15766]: --> [2:OK]
Dec 13 13:06:57.47: [15766]: <-- [14:AT+VSID="",""\r]
Dec 13 13:06:57.47: [15766]: --> [2:OK]
Dec 13 13:06:57.51: [15766]: DIAL 0892167950916
Dec 13 13:06:57.51: [15766]: <-- [18:ATDT089216095916\r]
Dec 13 13:07:42.51: [15766]: --> [9:NO ANSWER]
Dec 13 13:07:42.52: [15766]: SEND FAILED: JOB 19 DEST 089216095916 ERR No answer from remote {E003}
Dec 13 13:07:43.52: [15766]: <-- [5:ATH0\r]
Dec 13 13:07:43.52: [15766]: --> [2:OK]
Dec 13 13:07:43.52: [15766]: SESSION END
Im syslog entsprechend
Code:
Dec 13 13:05:58 pml010010 FaxGetty[15143]: Purge stale UUCP lock /var/lock/LCK..ttyIAX0
Dec 13 13:06:07 pml010010 FaxGetty[15143]: MODEM WWW.SOFT-SWITCH.ORG spandsp/
Dec 13 13:06:10 pml010010 FaxGetty[15143]: CAUGHT SIGNAL 15
Dec 13 13:06:10 pml010010 FaxGetty[15143]: CLOSE /dev/ttyIAX0
Dec 13 13:06:10 pml010010 init: faxgetty0 main process (15143) terminated with status 255
Dec 13 13:06:14 pml010010 FaxGetty[15508]: OPEN /dev/ttyIAX0  HylaFAX (tm) Version 5.2.5
Dec 13 13:06:23 pml010010 FaxGetty[15508]: MODEM WWW.SOFT-SWITCH.ORG spandsp/
Dec 13 13:06:46 pml010010 FaxQueuer[15130]: SUBMIT JOB 19
Dec 13 13:06:47 pml010010 FaxGetty[15508]: LOCKWAIT
Dec 13 13:06:47 pml010010 FaxQueuer[15130]: JOB 19 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:59): PREPARE START
Dec 13 13:06:47 pml010010 FaxQueuer[15757]: JOB 19 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:59): CONVERT DOCUMENT: bin/ps2fax -i 19 -o docq/doc19.ps;c0 -r 98 -w 1728 -l 297 -m 50 -U -3 docq/doc19.ps.19
Dec 13 13:06:48 pml010010 FaxQueuer[15130]: JOB 19 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:58): PREPARE DONE
Dec 13 13:06:48 pml010010 FaxQueuer[15130]: JOB 19 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:58): CMD START /usr/sbin/faxsend -m ttyIAX0 sendq/q19 (PID 15766)
Dec 13 13:06:57 pml010010 FaxSend[15766]: MODEM WWW.SOFT-SWITCH.ORG spandsp/
Dec 13 13:06:57 pml010010 FaxSend[15766]: SEND FAX: JOB 19 DEST 089216095916 COMMID 000000168 DEVICE '/dev/ttyIAX0' FROM 'root <[email protected]>' USER root
Dec 13 13:07:42 pml010010 FaxSend[15766]: SEND FAILED: JOB 19 DEST 089216095916 ERR No answer from remote {E003}
Dec 13 13:07:44 pml010010 FaxQueuer[15130]: JOB 19 (active dest +4989216095916 pri 127 tts 0:00:00 killtime 2:58:02): CMD DONE: exit status 0
Dec 13 13:07:44 pml010010 FaxQueuer[15130]: JOB 19 (sleeping dest +4989216095916 pri 126 tts 0:00:00 killtime 2:58:02): SEND INCOMPLETE: requeue for 0:04:58; No answer from remote 
Dec 13 13:07:55 pml010010 FaxGetty[15508]: MODEM WWW.SOFT-SWITCH.ORG spandsp/
Auf der Asterisk-Console wird nach wie vor nix ausgegeben.

Ich werd mal heute Nacht mal einen analogen Modem am Server anstecken und versuchen, ob hylafax überhaupt gewillt ist rauszuwählen. Sonst fällt mir absolut nix mehr ein. :argh:

cu
BC

P.S.: Ich hab mal das debugging im IAXmodem aktiviert und iaxmodem nicht als daemon gestartet, damit ich sehe, was da abgeht:
Code:
[2012-12-13 13:27:57] Hanging Up
[2012-12-13 13:28:01] Taking receiver off-hook.
[2012-12-13 13:28:02] Dialing '089219250916'
Tx-Frame Retry[010] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

Tx-Frame Retry[009] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[008] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[010] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
Tx-Frame Retry[009] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[008] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[010] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 06395  DCall: 00000 [192.168.10.10:4569]
   USERNAME        : iaxmodem0
   REFRESH         : 600

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00010ms  SCall: 00089  DCall: 06395 [192.168.10.10:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 56928472
   USERNAME        : iaxmodem0

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 0
Tx-Frame Retry[010] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00001ms  SCall: 06395  DCall: 00089 [192.168.10.10:4569]
   USERNAME        : iaxmodem0
   MD5 RESULT      : 34f02f5331e9aecf28a2ab729f286588
   REFRESH         : 600

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00011ms  SCall: 00089  DCall: 06395 [192.168.10.10:4569]
   USERNAME        : iaxmodem0
   DATE TIME       : 428698498
   REFRESH         : 60
   APPARENT ADDRES : IPV4 192.168.10.10:4570

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 1
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00011ms  SCall: 06395  DCall: 00089 [192.168.10.10:4569]
[2012-12-13 13:28:05] Registration completed successfully.
Tx-Frame Retry[007] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[007] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[006] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[006] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[010] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
Tx-Frame Retry[009] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[005] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[008] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[005] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[007] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[004] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 4
Tx-Frame Retry[004] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 4
Tx-Frame Retry[006] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[003] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 3
Tx-Frame Retry[010] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 22005ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
Tx-Frame Retry[009] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 22005ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[003] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 3
Tx-Frame Retry[008] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 22005ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[005] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[002] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 2
Tx-Frame Retry[007] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 22005ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[002] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 2
Tx-Frame Retry[004] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 4
Tx-Frame Retry[001] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 1
Tx-Frame Retry[006] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 22005ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[001] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 1
Tx-Frame Retry[010] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 32008ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
Tx-Frame Retry[009] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 32008ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[003] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 3
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
   Timestamp: 00003ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
   VERSION         : 2
   CALLING NUMBER  : 
   CALLING NAME    : 
   FORMAT          : 64
   CAPABILITY      : 76
   USERNAME        : iaxmodem0
   CALLED NUMBER   : 089219250916
   DNID            : 089219250916

iax.c line 3357 in iax_get_event: Scheduling retransmission 0
Tx-Frame Retry[008] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 32008ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[005] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 22005ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 02000ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 0
Tx-Frame Retry[007] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 32008ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[002] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 2
Tx-Frame Retry[004] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 22005ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 4
Tx-Frame Retry[006] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 32008ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[001] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 1
Tx-Frame Retry[010] -- OSeqno: 005 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 42010ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
Tx-Frame Retry[009] -- OSeqno: 005 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 42010ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[003] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 22005ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 3
Tx-Frame Retry[008] -- OSeqno: 005 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 42010ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[005] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 32008ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 12002ms  SCall: 06394  DCall: 00000 [88.217.187.21:4569]
iax.c line 3357 in iax_get_event: Scheduling retransmission 0
FLOW T.31 Call event 6 received
[2012-12-13 13:28:48] Hanging Up
[2012-12-13 13:28:57] Hanging Up
Tx-Frame Retry[010] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 06396  DCall: 00000 [192.168.10.10:4569]
   USERNAME        : iaxmodem0
   REFRESH         : 600

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00012ms  SCall: 05249  DCall: 06396 [192.168.10.10:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 542187684
   USERNAME        : iaxmodem0

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 0
Tx-Frame Retry[010] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00001ms  SCall: 06396  DCall: 05249 [192.168.10.10:4569]
   USERNAME        : iaxmodem0
   MD5 RESULT      : 3485c8050d4e9d7e305c7594695eb22d
   REFRESH         : 600

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00014ms  SCall: 05249  DCall: 06396 [192.168.10.10:4569]
   USERNAME        : iaxmodem0
   DATE TIME       : 428698528
   REFRESH         : 60
   APPARENT ADDRES : IPV4 192.168.10.10:4570

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 1
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00014ms  SCall: 06396  DCall: 05249 [192.168.10.10:4569]
[2012-12-13 13:29:00] Registration completed successfully.
[2012-12-13 13:29:00] Taking receiver off-hook.
[2012-12-13 13:29:00] Hanging Up
 
Zuletzt bearbeitet:
Ahoi!
Ich werd mal heute Nacht mal einen analogen Modem am Server anstecken und versuchen, ob hylafax überhaupt gewillt ist rauszuwählen.
So, hab mal einen analogen Modem mit dem Sangoma B700 a/b-Port und dem Faxserver verbunden. Fax erfolgreich verschickt!

Also grundlegend funktioniert der Hylafax-Server. Nur eben nicht mit IAXmodem beim Versenden.

Ankommende Faksimiles laufen ohne Probleme über die B700 zum Asterisk-Server => IAXmodem => HylaFax-Server => IMAP-Server.
Nur eben nicht der Rückweg, also Hylafax-Server => IAXmodem => Asterisk-Server => B700 => PSTN
Irgenwo zwischen IAX-Modem in Richtung Asterisk versandet IMHO die Kommunikation. :/

cu
BC
 
Hi,

hab' gerade erst Dein letztes Debug gesehen und siehe da, das scheint des Pudels Kern sein zu können:

Timestamp: 00003ms SCall: 06394 DCall: 00000 [88.217.187.21:4569]

Das Paket geht in retransmissions, was bedeutet, er findet die IP und den Port nicht. Das erklärt dann auch, warum eingehend funktioniert und abgehend nicht.

Das iaxmodem liegt laut Deinen Daten auf 192.168.10.10. Warum will es seine Pakete an eine öffentliche IP schicken ? Diese ist mit Sicherheit (Retranmsmissions im Log) nicht erreichbar (Firewall). Daher sollte die korrekte Einstellung der (internen) Asterisk-IP in /etc/iaxmodem/... helfen (server=).
 
Hohoho!

Das iaxmodem liegt laut Deinen Daten auf 192.168.10.10. Warum will es seine Pakete an eine öffentliche IP schicken ?
Gute Frage, ich hab mal sämtliche Configurations- und Logfiles zum Maintainer vom IAXmodem geschickt.

Tja, was soll ich sagen, die Antwort fiel etwas eigenartig aus:
... I don't immediately remember how the IAX2 protocol goes in order to say if the public IP appearance is normal or not.
Ich werd mal weiter testen.
Was mal interessant wäre, ist folgendes. Was loggt er denn bei Dir, wenn Du beim IAXmodem das Debugging aktivierst? Könntest Du das mal posten?

servus
BigChief
 
Zuletzt bearbeitet:
Standardsituation: iaxmodem2, hylafax und asterisk auf gleicher Maschine

Aus Sicht iaxmodem:
Code:
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK
   Timestamp: 00011ms  SCall: 14857  DCall: 15483 [127.0.0.1:4569]
[2012-12-17 16:45:44] Registration completed successfully.
iax.c line 2350 in iax_find_session: No session, peer = 10605, us = 14858
iax.c line 3205 in iax_net_process: No session?
[2012-12-17 16:45:55] Hanging Up
[2012-12-17 16:45:58] Taking receiver off-hook.
[2012-12-17 16:46:00] Dialing '03435625102'
Tx-Frame Retry[010] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW
   Timestamp: 00003ms  SCall: 14859  DCall: 00000 [127.0.0.1:4569]
   VERSION         : 2
   CALLING NUMBER  :
   CALLING NAME    :
   FORMAT          : 8
   CAPABILITY      : 76
   USERNAME        : iaxmodem2
   CALLED NUMBER   : 03435625102
   DNID            : 03435625102

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: ACK
   Timestamp: 00003ms  SCall: 15621  DCall: 14859 [127.0.0.1:4569]
iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 0
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: AUTHREQ
   Timestamp: 00010ms  SCall: 15621  DCall: 14859 [127.0.0.1:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 107022013
   USERNAME        : iaxmodem2

Tx-Frame Retry[010] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: AUTHREP
   Timestamp: 00003ms  SCall: 14859  DCall: 15621 [127.0.0.1:4569]
   MD5 RESULT      : 065f10bb581d35b4d98427ee82e01d68

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACK
   Timestamp: 00003ms  SCall: 15621  DCall: 14859 [127.0.0.1:4569]
iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 1
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACCEPT
   Timestamp: 00013ms  SCall: 15621  DCall: 14859 [127.0.0.1:4569]
   FORMAT          : 4
   Unknown IE 056  : Present

Ignoring unknown information element 'Unknown IE' (56) of length 9
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK
   Timestamp: 00013ms  SCall: 14859  DCall: 15621 [127.0.0.1:4569]
[2012-12-17 16:46:00] Call accepted.
Tx-Frame Retry[010] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: PING
   Timestamp: 02001ms  SCall: 14859  DCall: 15621 [127.0.0.1:4569]
Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 003 Type: IAX     Subclass: PONG
   Timestamp: 02001ms  SCall: 15621  DCall: 14859 [127.0.0.1:4569]
   RR_JITTER       : 0
   RR_LOSS         : 0
   RR_PKTS         : 1
   RR_DELAY        : 40
   RR_DROPPED      : 0
   RR_OOO          : 0

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 2
Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 003 Type: IAX     Subclass: ACK
   Timestamp: 02001ms  SCall: 14859  DCall: 15621 [127.0.0.1:4569]
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: CONTROL Subclass: RINGING
   Timestamp: 02895ms  SCall: 15621  DCall: 14859 [127.0.0.1:4569]

Aus Sicht Asterisk:

Code:
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    Timestamp: 00003ms  SCall: 16515  DCall: 00000 [127.0.0.1:4572]
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    VERSION         : 2
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    CALLING NUMBER  :
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    CALLING NAME    :
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    FORMAT          : 8
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    CAPABILITY      : 76
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    USERNAME        : iaxmodem2
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    CALLED NUMBER   : 03435625102
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    DNID            : 03435625102
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: ACK
[2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    Timestamp: 00003ms  SCall: 05251  DCall: 16515 [127.0.0.1:4572]
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: AUTHREQ
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:    Timestamp: 00014ms  SCall: 05251  DCall: 16515 [127.0.0.1:4572]
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:    AUTHMETHODS     : 3
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:    CHALLENGE       : \x31\x31\x32\x37\x30\x34\x36\x32\x34
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:    USERNAME        : iaxmodem2
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:
[2012-12-17 16:37:10] VERBOSE[3998] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: AUTHREP
[2012-12-17 16:37:10] VERBOSE[3998] chan_iax2.c:    Timestamp: 00003ms  SCall: 16515  DCall: 05251 [127.0.0.1:4572]
[2012-12-17 16:37:10] VERBOSE[3998] chan_iax2.c:    MD5 RESULT      : 4641efabe7794434b24228f64b491a46
[2012-12-17 16:37:10] VERBOSE[3998] chan_iax2.c:
[2012-12-17 16:37:10] VERBOSE[3998] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACK
[2012-12-17 16:37:10] VERBOSE[3998] chan_iax2.c:    Timestamp: 00003ms  SCall: 05251  DCall: 16515 [127.0.0.1:4572]
[2012-12-17 16:37:10] VERBOSE[3998] chan_iax2.c:     -- Accepting AUTHENTICATED call from 127.0.0.1:
       > requested format = alaw,
       > requested prefs = (),
       > actual format = ulaw,
       > host prefs = (ulaw),
       > priority = mine
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACCEPT
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:    Timestamp: 00015ms  SCall: 05251  DCall: 16515 [127.0.0.1:4572]
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:    FORMAT          : 4
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:    FORMAT2         : ulaw
[2012-12-17 16:37:10] VERBOSE[3949] chan_iax2.c:

usw.
Entscheidend ist der Unterschied in der ip:

Code:
[B][2012-12-17 16:37:10] VERBOSE[3971] chan_iax2.c:    Timestamp: 00003ms  SCall: 16515  DCall: 00000 [127.0.0.1:4572][/B]

Das versteht das Netzwerk, es kommt beim Asterisk an und alles ist gut.

Vielleicht liegts ja an der iax.conf (bindaddr). Hier mal der relevante Teil bei mir:

Code:
[general]
bindport = 4569
bindaddr = 0.0.0.0
externip=xxx.xxx.xxx.xxx
language=de
jitterbuffer=no
minregexpire=60
maxregexpire=1300
autokill=yes
iaxthreadcount=50
iaxmaxthreadcount=200
transfer=no
delayreject=yes
maxcallnumbers=256
maxcallnumbers_nonvalidated=512
calltokenoptional=127.0.0.1/255.255.255.255
context=enums
disallow=all
allow=ulaw
allow=alaw
tos=ef

[fax](!)
type=friend
secret=iaxmodem
host=dynamic
transfer=no
disallow=all
allow=ulaw
qualify=yes
requirecalltoken=no
deny=0.0.0.0/0.0.0.0
permit=127.0.0.1/255.255.255.255

[iaxmodem2](fax)
port=4572
 
HI!

Also ich bin ein kleines Stückchen weitergekommen. Ich hab dafür gesorgt, dass ein Konfigfehler im DNS beseitigt wurde und ein paar kapitale Böcke aus der extensions.conf habe ich auch rausgeworfen, aber es geht trotzdem immer noch nicht.

Auf der Asterisk-Shell kommt nach wie vor nix vorbei.

im iaxmodem-debug-log taucht nun auf:
Code:
[2012-12-18 18:26:38] Hanging Up
Tx-Frame Retry[010] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 24456  DCall: 00000 [127.0.0.1:4569]
   USERNAME        : ttyIAX0
   REFRESH         : 60

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00012ms  SCall: 00652  DCall: 24456 [127.0.0.1:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 924640111
   USERNAME        : ttyIAX0

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 0
Tx-Frame Retry[010] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 24456  DCall: 00652 [127.0.0.1:4569]
   USERNAME        : ttyIAX0
   MD5 RESULT      : 32bc0ad11caa4c62f5a2cc4b3f7544df
   REFRESH         : 60

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00015ms  SCall: 00652  DCall: 24456 [127.0.0.1:4569]
   USERNAME        : ttyIAX0
   DATE TIME       : 429036371
   REFRESH         : 60
   APPARENT ADDRES : IPV4 127.0.0.1:4570

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 1
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
   Timestamp: 00015ms  SCall: 24456  DCall: 00652 [127.0.0.1:4569]
[2012-12-18 18:26:39] Registration completed successfully.
[2012-12-18 18:26:41] Taking receiver off-hook.
[2012-12-18 18:26:43] Dialing '08921605916'
Tx-Frame Retry[010] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
Tx-Frame Retry[009] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[008] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[007] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[006] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[010] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
Tx-Frame Retry[009] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[008] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[005] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[007] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[004] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 4
Tx-Frame Retry[006] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[010] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 20003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
Tx-Frame Retry[009] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 20003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[003] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 3
Tx-Frame Retry[008] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 20003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[005] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[007] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 20003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[002] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 2
Tx-Frame Retry[004] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 4
Tx-Frame Retry[006] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 20003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[001] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 1
Tx-Frame Retry[010] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 30005ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
Tx-Frame Retry[009] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 30005ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[003] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 3
Tx-Frame Retry[008] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 30005ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[005] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 20003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 00003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 0
Tx-Frame Retry[007] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 30005ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 7
Tx-Frame Retry[002] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 2
Tx-Frame Retry[004] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 20003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 4
Tx-Frame Retry[006] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 30005ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 6
Tx-Frame Retry[001] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 1
Tx-Frame Retry[010] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 40006ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
Tx-Frame Retry[009] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 40006ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 9
Tx-Frame Retry[003] -- OSeqno: 002 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 20003ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 3
Tx-Frame Retry[008] -- OSeqno: 004 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 40006ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 8
Tx-Frame Retry[005] -- OSeqno: 003 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 30005ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 5
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 000 Type: IAX     Subclass: PING   
   Timestamp: 10001ms  SCall: 24457  DCall: 00000 [0.0.0.0:0]
iax.c line 3357 in iax_get_event: Scheduling retransmission 0
FLOW T.31 Call event 6 received
[2012-12-18 18:27:29] Hanging Up
Tx-Frame Retry[010] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 24458  DCall: 00000 [127.0.0.1:4569]
   USERNAME        : ttyIAX0
   REFRESH         : 60

Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
   Timestamp: 00014ms  SCall: 01615  DCall: 24458 [127.0.0.1:4569]
   AUTHMETHODS     : 3
   CHALLENGE       : 311459575
   USERNAME        : ttyIAX0

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 0
Tx-Frame Retry[010] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ 
   Timestamp: 00003ms  SCall: 24458  DCall: 01615 [127.0.0.1:4569]
   USERNAME        : ttyIAX0
   MD5 RESULT      : 31d95a208840677335be837c6f950e38
   REFRESH         : 60

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK 
   Timestamp: 00015ms  SCall: 01615  DCall: 24458 [127.0.0.1:4569]
   USERNAME        : ttyIAX0
   DATE TIME       : 429036401
   REFRESH         : 60
   APPARENT ADDRES : IPV4 127.0.0.1:4570

iax.c line 2617 in iax_header_to_event: Cancelling transmission of packet 1
Nur, warum will er da Tx-Frame Retry[010] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: PING
Timestamp: 00003ms SCall: 24457 DCall: 00000 [0.0.0.0:0]
also 0.0.0.0:0 ansprechen?

Die ttyIAX0 sieht nunmehr wie folgt aus:
Code:
device          /dev/ttyIAX0
owner           uucp:uucp
mode            660
port            4570
refresh         60
server          127.0.0.1
peername        ttyIAX0
secret          P1r@t3nP4rTe1
cidname         nausch.org
cidnumber       +49 8121 8831-79
codec           ulaw
iax2debug	yes
dspdebug	yes

Die iax.conf hat folgenden Inhalt:
Code:
[general]
bindport=4569			; bindport and bindaddr may be specified
bindaddr=0.0.0.0		; more than once to bind to multiple
language=de
bandwidth=low
disallow=lpc10			; Icky sound quality...  Mr. Roboto.
allow=gsm			; Always allow GSM, it's cool :)
jitterbuffer=no
forcejitterbuffer=no
autokill=yes

[demo]
type=peer
username=asterisk
secret=supersecret
host=216.207.245.47

[13]
context=default
callerid="Buero Praktikantenstelle <13>"
type=friend
username=13
secret=ZIpf3bl3d@
mailbox=31
host=dynamic
deny = 0.0.0.0/0.0.0.0
permit = 192.168.10.74
transfer=no
language=de

[ttyIAX0]
context=from-iaxmodems
type=friend
secret=P1r@t3nP4rTe1
port=4570
host=dynamic
disallow=all
allow=ulaw
allow=alaw
jitterbuffer=no
requirecalltoken=no

[ttyIAX1]
context=from-iaxmodems
type=friend
secret=P1r@t3nP4rTe1
port=4571
host=dynamic
disallow=all
allow=ulaw
allow=alaw
jitterbuffer=no
requirecalltoken=no

[26]
context=default
callerid="Sekretärin Händie <26>"
type=friend
username=26
secret=G3|l3$chl4mP3!
mailbox=22
host=dynamic
transfer=no
language=de

[33]
context=default
callerid=Geschäftsführer IAX-Händie <33>
type=friend
username=33
secret=(TsV1860!e
mailbox=33
host=dynamic
canreinvite=yes
callgroup=1
pickupgroup=1
notifyringing=yes
notifyhold=yes
notifycid=yes
language=de

in der extensions.conf habe ich für die abgehende Wahl folgenden context eingefügt.
Code:
[from-iaxmodems]
exten => 19,1,NoOp(Anwahl des internen analogen Faxanschlusses vom IAXmodem-Pool)
exten => 19,n,Dial(DAHDI/G3,60)
exten => 19,n,Hangup()

exten => _XXX.,1,Verbose(### Anruf vom IAXmodem-Pool an die Rufnummer ${EXTEN} via PSTN ###)
exten => _XXX.,n,Set(CALLERID(num)=8131893270)
exten => _XXX.,n,Dial(DAHDI/G1/${EXTEN})
exten => _XXX.,n,Hangup

Für den context from-iaxmodems gibt es natürlich KEINEN include im context [default].

Somit sollte es doch klappen, tut es aber nicht! :oops:


ttyl
BigChief
 
Zuletzt bearbeitet:
Kann nicht klappen, die iax.conf ist falsch, die Asterisk-Konsole sollte das auch anmeckern.

Da fehlt im general

Code:
calltokenoptional=127.0.0.1/255.255.255.255

und bei ttyIAX0 respektive ttyIAX1

Code:
requirecalltoken=no

Ohne diese Angaben geht die Kommunikation abgehend definitiv nicht, da iaxmodem mit der verwendeten libiax keine calltoken-Untzerstützung hat, diese aber bei der bestehenden Konfiguration erwartet wird.
 
HI!
Kann nicht klappen, die iax.conf ist falsch, die Asterisk-Konsole sollte das auch anmeckern.
Nö tut es nicht, leider.
Code:
calltokenoptional=127.0.0.1/255.255.255.255
Habe ich im context der sip.conf nachgetragen.
und bei ttyIAX0 respektive ttyIAX1

Code:
requirecalltoken=no
Da wars eh schon drinnen.
Ohne diese Angaben geht die Kommunikation abgehend definitiv nicht, da iaxmodem mit der verwendeten libiax keine calltoken-Untzerstützung hat, diese aber bei der bestehenden Konfiguration erwartet wird.
Da es immer noch nicht geht, nehme ich mal an, dass da beim übersetzen der libiax etwas schief ging, oder?
Habe folgende Rahmenbedingungen:
Code:
# iaxmodem -V
1.2.0
spandsp-0.0.5-snapshot-20080725
libiax2-0.2.3-SVN-20071223+
werd mal kucken wo bei libiax der Wurm drinnen ist.

ttyl
BigChief
 
Häh :confused: Laut Deinem Codeauszug war es nicht drin und wieso sip.conf :confused:
 
Holen Sie sich 3CX - völlig kostenlos!
Verbinden Sie Ihr Team und Ihre Kunden Telefonie Livechat Videokonferenzen

Gehostet oder selbst-verwaltet. Für bis zu 10 Nutzer dauerhaft kostenlos. Keine Kreditkartendetails erforderlich. Ohne Risiko testen.

3CX
Für diese E-Mail-Adresse besteht bereits ein 3CX-Konto. Sie werden zum Kundenportal weitergeleitet, wo Sie sich anmelden oder Ihr Passwort zurücksetzen können, falls Sie dieses vergessen haben.