Hello,
it seems, there is a problem between openpbx and iaxmodem
while submitting a fax over an not hq SIP provider.
That the fax is not properly received, is not surprising
and o.k., but openpbx crashes.
(by using a better SIP provider like sipgate this crash
problem doesn't occur)
My setup:
Hylafax+ connected over network (interceptty) with iaxmodem.
I have defined 4 iaxmodems and run iaxmodem in daemon mode.
The iaxmodems are the only iax2 clients/peers.
OpenPBX.org 1.2-RC3
Relevant parts of the config files:
/etc/iaxmodem/ttyIAX01:
device /dev/ttyIAX01
owner iaxmodem:iaxmodem
mode 660
port 4101
refresh 60
server 88.198.163.100
peername iaxmodem1
secret xxxxxxxx
cidname XWD
cidnumber 00493928842880
codec slinear
sip.conf:
[webcalldirectout]
type=friend
username=xtremeweb
secret=xxxxxxxxxxxxxx
host=sip.webcalldirect.com
insecure=invite
canreinvite=now
nat=now
iax.conf:
[iaxmodem1]
type=friend
username=iaxmodem1
secret=xxxxxxxxxxxxxxxx
host=dynamic
context=iaxmodemdialout
auth=md5
extensions.conf:
[iaxmodemdialout]
exten => _X.,1,Dial,SIP/[EMAIL PROTECTED]|45|
exten => _X.,n,Hangup
So, if hylafax sends a fax, it will be transmitted over
'webcalldirectout'.
The relevant source for the crash seems to be:
Feb 10 07:40:21 WARNING[1088838576]: chan_iax2.c:7071 network_thread:
chan_iax2: Sent 24 queued outbound frames all at once
Feb 10 07:40:45 WARNING[1085606832]: chan_iax2.c:1368 attempt_transmit:
Max retries exceeded to host 88.198.163.100 on IAX2/iaxmodem1-5 (type =
2, subclass = 4, ts=268374242, seqno=100)
See below for the correspondence between my openpbx server
and the remote SIP server.
Below that you find the logs of hylafax (although IMO they
are not related to the crash).
For me it looks like a bug in openpbx ...
Sorry for the long post.
(I crosspost this to the iaxmodem and openpbx ML)
Greetings Torsten
########
from CLI with verbose 99, debug 99, sip debug peer webcalldirectout
########
-- Accepting AUTHENTICATED call from 88.198.163.100:
> requested format = slin,
> requested prefs = (),
> actual format = ulaw,
> host prefs = (),
> priority = mine
-- Executing Dial("IAX2/iaxmodem1-5",
"SIP/[EMAIL PROTECTED]|45|") in new stack
We're at 88.198.163.100 port 11632
Video is at 88.198.163.100 port 12260
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x2 (gsm) to SDP
Adding codec 0x8 (alaw) to SDP
Adding codec 0x80000 (h263) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
13 headers, 15 lines
Reliably Transmitting (NAT) to 194.120.0.202:5060:
INVITE sip:[EMAIL PROTECTED] SIP/2.0
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK26451e75;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>
Contact: <sip:[EMAIL PROTECTED]>
Call-ID: [EMAIL PROTECTED]
CSeq: 102 INVITE
User-Agent: OpenPBX
Max-Forwards: 70
Date: Sat, 10 Feb 2007 06:39:46 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 319
v=0
o=root 16014 16014 IN IP4 88.198.163.100
s=session
c=IN IP4 88.198.163.100
t=0 0
m=audio 11632 RTP/AVP 0 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:34 H263/90000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
m=video 12260 RTP/AVP 34
---
-- Called [EMAIL PROTECTED]
nethawk*CLI>
<-- SIP read from 194.120.0.202:5060:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK26451e75;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>
Contact: sip:[EMAIL PROTECTED]:5060
Call-ID: [EMAIL PROTECTED]
CSeq: 102 INVITE
Server: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS
WWW-Authenticate: Digest
realm="sipdiscount.com",nonce="3678715829",algorithm=MD5
Content-Length: 0
--- (11 headers 0 lines) ---
Transmitting (NAT) to 194.120.0.202:5060:
ACK sip:[EMAIL PROTECTED] SIP/2.0
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK26451e75;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>
Contact: <sip:[EMAIL PROTECTED]>
Call-ID: [EMAIL PROTECTED]
CSeq: 102 ACK
User-Agent: OpenPBX
Max-Forwards: 70
ontent-Length: 0
---
We're at 88.198.163.100 port 11632
Video is at 88.198.163.100 port 12260
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x2 (gsm) to SDP
Adding codec 0x8 (alaw) to SDP
Adding codec 0x80000 (h263) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (NAT) to 194.120.0.202:5060:
INVITE sip:[EMAIL PROTECTED] SIP/2.0
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK3a4aee39;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>
Contact: <sip:[EMAIL PROTECTED]>
Call-ID: [EMAIL PROTECTED]
CSeq: 103 INVITE
User-Agent: OpenPBX
Max-Forwards: 70
Authorization: Digest username="xtremeweb", realm="sipdiscount.com",
algorithm=MD5, uri="sip:[EMAIL PROTECTED]",
nonce="3678715829", response="9b8b1be9932d79cbb068b50655054f69", opaque=""
Date: Sat, 10 Feb 2007 06:39:46 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 347
v=0
o=root 16014 16015 IN IP4 88.198.163.100
s=session
c=IN IP4 88.198.163.100
t=0 0
m=audio 11632 RTP/AVP 0 3 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:34 H263/90000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
m=video 12260 RTP/AVP 34
[EMAIL PROTECTED]
[EMAIL PROTECTED]
---
nethawk*CLI>
<-- SIP read from 194.120.0.202:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK3a4aee39;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>
Contact: sip:[EMAIL PROTECTED]:5060
Call-ID: [EMAIL PROTECTED]
CSeq: 103 INVITE
Server: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS
Content-Length: 0
--- (10 headers 0 lines) ---
nethawk*CLI>
<-- SIP read from 194.120.0.202:5060:
SIP/2.0 183 Session progress
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK3a4aee39;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>;tag=ca0078c245caec8833b34
Contact: sip:[EMAIL PROTECTED]:5060
Call-ID: [EMAIL PROTECTED]
CSeq: 103 INVITE
Server: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS
Content-Type: application/sdp
Content-Length: 204
v=0
o=xtremeweb 1171089555 1171089555 IN IP4 194.221.62.163
s=SIP Call
c=IN IP4 194.221.62.163
t=0 0
m=audio 58030 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
--- (11 headers 9 lines) ---
Found RTP audio format 0
Found RTP audio format 101
Peer audio RTP is at port 194.221.62.163:58030
Found description format PCMU
Found description format telephone-event
Capabilities: us - 0x8000e (gsm|ulaw|alaw|h263), peer - audio=0x4
(ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw)
Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1
(telephone-event), combined - 0x1 (telephone-event)
-- SIP/webcalldirectout-74ea is making progress passing it to
IAX2/iaxmodem1-5
nethawk*CLI>
<-- SIP read from 194.120.0.202:5060:
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK3a4aee39;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>;tag=ca0078c245caec8833b34
Contact: sip:[EMAIL PROTECTED]:5060
Call-ID: [EMAIL PROTECTED]
CSeq: 103 INVITE
Server: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS
Content-Type: application/sdp
Content-Length: 204
v=0
o=xtremeweb 1171089565 1171089565 IN IP4 194.221.62.163
s=SIP Call
c=IN IP4 194.221.62.163
t=0 0
m=audio 58030 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
--- (11 headers 9 lines) ---
Found RTP audio format 0
Found RTP audio format 101
Peer audio RTP is at port 194.221.62.163:58030
Found description format PCMU
Found description format telephone-event
Capabilities: us - 0x8000e (gsm|ulaw|alaw|h263), peer - audio=0x4
(ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw)
Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1
(telephone-event), combined - 0x1 (telephone-event)
list_route: hop: <sip:[EMAIL PROTECTED]:5060>
set_destination: Parsing <sip:[EMAIL PROTECTED]:5060> for
address/port to send to
set_destination: set destination to 194.120.0.202, port 5060
Transmitting (NAT) to 194.120.0.202:5060:
ACK sip:[EMAIL PROTECTED]:5060 SIP/2.0
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK1a1d699f;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>;tag=ca0078c245caec8833b34
Contact: <sip:[EMAIL PROTECTED]>
Call-ID: [EMAIL PROTECTED]
CSeq: 103 ACK
User-Agent: OpenPBX
Max-Forwards: 70
Content-Length: 0
---
-- SIP/webcalldirectout-74ea answered IAX2/iaxmodem1-5
12 headers, 0 lines
Reliably Transmitting (NAT) to 194.120.0.202:5060:
OPTIONS sip:sip.webcalldirect.com SIP/2.0
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK22ebf887;rport
From: "openpbx" <sip:[EMAIL PROTECTED]>;tag=as48868bd2
To: <sip:sip.webcalldirect.com>
Contact: <sip:[EMAIL PROTECTED]>
Call-ID: [EMAIL PROTECTED]
CSeq: 102 OPTIONS
User-Agent: OpenPBX
Max-Forwards: 70
Date: Sat, 10 Feb 2007 06:40:06 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
---
nethawk*CLI>
<-- SIP read from 194.120.0.202:5060:
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK22ebf887;rport
From: "openpbx" <sip:[EMAIL PROTECTED]>;tag=as48868bd2
To: <sip:sip.webcalldirect.com>
Contact: sip:194.120.0.202:5060
Call-ID: [EMAIL PROTECTED]
CSeq: 102 OPTIONS
Supported: foo
User-Agent: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS
Accept: application/sdp
Accept-Encoding:
Accept-Language:
--- (13 headers 0 lines) ---
Destroying call '[EMAIL PROTECTED]'
Feb 10 07:40:21 WARNING[1088838576]: chan_iax2.c:7071 network_thread:
chan_iax2: Sent 24 queued outbound frames all at once
Feb 10 07:40:45 WARNING[1085606832]: chan_iax2.c:1368 attempt_transmit:
Max retries exceeded to host 88.198.163.100 on IAX2/iaxmodem1-5 (type =
2, subclass = 4, ts=268374242, seqno=100)
set_destination: Parsing <sip:[EMAIL PROTECTED]:5060> for
address/port to send to
set_destination: set destination to 194.120.0.202, port 5060
Reliably Transmitting (NAT) to 194.120.0.202:5060:
BYE sip:[EMAIL PROTECTED]:5060 SIP/2.0
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK01ad7868;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>;tag=ca0078c245caec8833b34
Contact: <sip:[EMAIL PROTECTED]>
Call-ID: [EMAIL PROTECTED]
CSeq: 104 BYE
User-Agent: OpenPBX
Max-Forwards: 70
Authorization: Digest username="xtremeweb", realm="sipdiscount.com",
algorithm=MD5, uri="sip:[EMAIL PROTECTED]:5060",
nonce="3678715829", response="ed70c155920cb139895af791bc427bb3", opaque=""
Content-Length: 0
---
== Spawn extension (iaxmodemdialout, 03928842880, 1) exited non-zero
on 'IAX2/iaxmodem1-5'
nethawk*CLI>
<-- SIP read from 194.120.0.202:5060:
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 88.198.163.100:5060;branch=z9hG4bK01ad7868;rport
From: "XWD" <sip:[EMAIL PROTECTED]>;tag=as06822413
To: <sip:[EMAIL PROTECTED]>;tag=ca0078c245caec8833b34
Contact: sip:[EMAIL PROTECTED]:5060
Call-ID: [EMAIL PROTECTED]
CSeq: 104 BYE
Server: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS
Content-Length: 0
--- (10 headers 0 lines) ---
Destroying call '[EMAIL PROTECTED]'
Feb 10 07:40:45 WARNING[1085606832]: chan_iax2.c:1368 attempt_transmit:
Max retries exceeded to host 88.198.163.100 on IAX2/iaxmodem1-5 (type =
2, subclass = 4, ts=268374262, seqno=101)
-- Hungup 'IAX2/iaxmodem1-5'
nethawk*CLI>
Disconnected from OpenPBX server
Now openpbx crashed.
######
log of the sending iaxmodem
######
*** /var/spool/hylafax/log//c000000518 ***
Feb 09 11:00:19.54: [ 3537]: SESSION BEGIN 000000518 493928842880
Feb 09 11:00:19.54: [ 3537]: HylaFAX (tm) Version 5.0.4
Feb 09 11:00:19.54: [ 3537]: SEND FAX: JOB 177 DEST 03928842880 COMMID
000000518 DEVICE '/dev/iaxmod3' FROM 'torsten <[EMAIL PROTECTED]
Feb 09 11:00:19.54: [ 3537]: STATE CHANGE: RUNNING -> SENDING
Feb 09 11:00:19.54: [ 3537]: <-- [12:AT+FCLASS=1\r]
Feb 09 11:00:19.60: [ 3537]: --> [2:OK]
Feb 09 11:00:19.60: [ 3537]: MODEM set XON/XOFF/FLUSH: input ignored,
output disabled
Feb 09 11:00:19.60: [ 3537]: DIAL 03928842880
Feb 09 11:00:19.60: [ 3537]: <-- [16:ATDT03928842880\r]
Feb 09 11:03:19.61: [ 3537]: MODEM TIMEOUT: reading line from modem
Feb 09 11:03:19.61: [ 3537]: SEND FAILED: JOB 177 DEST 03928842880 ERR
Unknown problem
Feb 09 11:03:19.61: [ 3537]: <-- [5:ATH0\r]
Feb 09 11:03:19.68: [ 3537]: --> [2:OK]
Feb 09 11:03:19.68: [ 3537]: MODEM set DTR OFF
Feb 09 11:03:19.68: [ 3537]: MODEM set baud rate: 0 baud (flow control
unchanged)
Feb 09 11:03:19.68: [ 3537]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Feb 09 11:03:19.68: [ 3537]: SESSION END
#####
log of the receiving analog modem behind an ISDN PBX
#####
*** /var/spool/hylafax/log//c000000519 ***
Feb 09 11:00:29.67: [ 1991]: SESSION BEGIN 000000519 493928842880
Feb 09 11:00:29.67: [ 1991]: HylaFAX (tm) Version 5.0.4
Feb 09 11:00:29.67: [ 1991]: <-- [4:ATA\r]
Feb 09 11:00:36.29: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:00:37.09: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:00:41.50: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:00:42.30: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:00:46.70: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:00:47.51: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:00:51.90: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:00:52.72: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:00:57.11: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:00:57.92: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:01:02.32: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:01:03.13: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:01:07.52: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:01:08.33: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:01:12.73: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:01:13.54: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:01:17.93: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:01:18.74: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:01:23.14: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:01:23.95: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:01:28.34: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:01:29.15: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:01:33.55: [ 1991]: --> [74:+FHT:FF 03 40 20 20 20 20 20 20 20
20 20 20 20 72 65 76 72 65 73 78 61 46 ]
Feb 09 11:01:34.36: [ 1991]: --> [26:+FHT:FF 13 80 00 6E FA 00 ]
Feb 09 11:01:36.92: [ 1991]: --> [8:+FHNG:74]
Feb 09 11:01:36.92: [ 1991]: REMOTE HANGUP: T.30 T1 timeout after EOM
received (code 74)
Feb 09 11:01:36.92: [ 1991]: ANSWER: Ring detected without successful
handshake
Feb 09 11:01:36.92: [ 1991]: SESSION END
_______________________________________________
Openpbx-users mailing list
[email protected]
http://lists.openpbx.org/mailman/listinfo/openpbx-users
