trunk SIP OXE-XIVO

bwen · Post by **bwen** » 26 Sep 2016 12:01

Hello,

I try to connect an alcatel OXE and an Asterisk Xivo according to some posts I have seen on this forum but it fail...

Here is the debug call form xivo user to OXE user:

Code: Select all

xivo*CLI> sip set debug peer OXE
SIP Debugging Enabled for IP: XXX.XXX.XXX.XXX
[Sep 26 17:12:39]   == Manager 'xivo_cti_user' logged on from 127.0.0.1
[Sep 26 17:12:43]   == Using SIP RTP CoS mark 5
[Sep 26 17:12:43]     -- Executing [2266@default:1] Set("SIP/ifvdbq1h-00000000", "XIVO_BASE_CONTEXT=default") in new stack
[Sep 26 17:12:43]     -- Executing [2266@default:2] Set("SIP/ifvdbq1h-00000000", "XIVO_BASE_EXTEN=2266") in new stack
[Sep 26 17:12:43]     -- Executing [2266@default:3] Gosub("SIP/ifvdbq1h-00000000", "outcall,s,1(3,)") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:1] Set("SIP/ifvdbq1h-00000000", "XIVO_DSTID=3") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:2] Set("SIP/ifvdbq1h-00000000", "XIVO_PRESUBR_GLOBAL_NAME=OUTCALL") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:3] Set("SIP/ifvdbq1h-00000000", "XIVO_SRCNUM=667") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:4] Set("SIP/ifvdbq1h-00000000", "XIVO_DSTNUM=2266") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:5] Set("SIP/ifvdbq1h-00000000", "XIVO_CONTEXT=default") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:6] Gosub("SIP/ifvdbq1h-00000000", "originate-caller-id,s,1") in new stack
[Sep 26 17:12:43]     -- Executing [s@originate-caller-id:1] GotoIf("SIP/ifvdbq1h-00000000", "0?:name") in new stack
[Sep 26 17:12:43]     -- Goto (originate-caller-id,s,3)
[Sep 26 17:12:43]     -- Executing [s@originate-caller-id:3] GotoIf("SIP/ifvdbq1h-00000000", "0?:fix") in new stack
[Sep 26 17:12:43]     -- Goto (originate-caller-id,s,5)
[Sep 26 17:12:43]     -- Executing [s@originate-caller-id:5] GotoIf("SIP/ifvdbq1h-00000000", "?:end") in new stack
[Sep 26 17:12:43]     -- Goto (originate-caller-id,s,8)
[Sep 26 17:12:43]     -- Executing [s@originate-caller-id:8] Return("SIP/ifvdbq1h-00000000", "") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:7] AGI("SIP/ifvdbq1h-00000000", "agi://127.0.0.1/outgoing_user_set_features") in new stack
[Sep 26 17:12:43]  agi://127.0.0.1/outgoing_user_set_features: AGI handler 'outgoing_user_set_features' successfully executed
[Sep 26 17:12:43]     -- <SIP/ifvdbq1h-00000000>AGI Script agi://127.0.0.1/outgoing_user_set_features completed, returning 0
[Sep 26 17:12:43]     -- Executing [s@outcall:8] Gosub("SIP/ifvdbq1h-00000000", "xivo-subroutine,s,1()") in new stack
[Sep 26 17:12:43]     -- Executing [s@xivo-subroutine:1] GotoIf("SIP/ifvdbq1h-00000000", "?:nosubroutine") in new stack
[Sep 26 17:12:43]     -- Goto (xivo-subroutine,s,4)
[Sep 26 17:12:43]     -- Executing [s@xivo-subroutine:4] Return("SIP/ifvdbq1h-00000000", "") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:9] Gosub("SIP/ifvdbq1h-00000000", "xivo-user_rights_check,s,1") in new stack
[Sep 26 17:12:43]     -- Executing [s@xivo-user_rights_check:1] AGI("SIP/ifvdbq1h-00000000", "agi://127.0.0.1/user_set_call_rights") in new stack
[Sep 26 17:12:43]  agi://127.0.0.1/user_set_call_rights: AGI handler 'user_set_call_rights' successfully executed
[Sep 26 17:12:43]     -- <SIP/ifvdbq1h-00000000>AGI Script agi://127.0.0.1/user_set_call_rights completed, returning 0
[Sep 26 17:12:43]     -- Executing [s@xivo-user_rights_check:2] GotoIf("SIP/ifvdbq1h-00000000", "ALLOW?:error,1") in new stack
[Sep 26 17:12:43]     -- Executing [s@xivo-user_rights_check:3] GotoIf("SIP/ifvdbq1h-00000000", "1?allow,1") in new stack
[Sep 26 17:12:43]     -- Goto (xivo-user_rights_check,allow,1)
[Sep 26 17:12:43]     -- Executing [allow@xivo-user_rights_check:1] NoOp("SIP/ifvdbq1h-00000000", "User allowed to make call") in new stack
[Sep 26 17:12:43]     -- Executing [allow@xivo-user_rights_check:2] Return("SIP/ifvdbq1h-00000000", "") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:10] AGI("SIP/ifvdbq1h-00000000", "agi://127.0.0.1/check_schedule") in new stack
[Sep 26 17:12:43]  agi://127.0.0.1/check_schedule: AGI handler 'check_schedule' successfully executed
[Sep 26 17:12:43]     -- <SIP/ifvdbq1h-00000000>AGI Script agi://127.0.0.1/check_schedule completed, returning 0
[Sep 26 17:12:43]     -- Executing [s@outcall:11] GotoIf("SIP/ifvdbq1h-00000000", "0?CLOSED,1") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:12] GotoIf("SIP/ifvdbq1h-00000000", "?:14") in new stack
[Sep 26 17:12:43]     -- Goto (outcall,s,14)
[Sep 26 17:12:43]     -- Executing [s@outcall:14] GotoIf("SIP/ifvdbq1h-00000000", "SIP/OXE?:error,1") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:15] Set("SIP/ifvdbq1h-00000000", "TRUNKINDEX=0") in new stack
[Sep 26 17:12:43]     -- Executing [s@outcall:16] Goto("SIP/ifvdbq1h-00000000", "dial,1") in new stack
[Sep 26 17:12:43]     -- Goto (outcall,dial,1)
[Sep 26 17:12:43]     -- Executing [dial@outcall:1] Set("SIP/ifvdbq1h-00000000", "INTERFACE=SIP/OXE") in new stack
[Sep 26 17:12:43]     -- Executing [dial@outcall:2] Set("SIP/ifvdbq1h-00000000", "TRUNKEXTEN=2266") in new stack
[Sep 26 17:12:43]     -- Executing [dial@outcall:3] Set("SIP/ifvdbq1h-00000000", "TRUNKSUFFIX=") in new stack
[Sep 26 17:12:43]     -- Executing [dial@outcall:4] Gosub("SIP/ifvdbq1h-00000000", "xivo-global-subroutine,s,1") in new stack
[Sep 26 17:12:43]     -- Executing [s@xivo-global-subroutine:1] GotoIf("SIP/ifvdbq1h-00000000", "1?:return") in new stack
[Sep 26 17:12:43]     -- Executing [s@xivo-global-subroutine:2] GotoIf("SIP/ifvdbq1h-00000000", "OUTCALL?:return") in new stack
[Sep 26 17:12:43]     -- Executing [s@xivo-global-subroutine:3] GotoIf("SIP/ifvdbq1h-00000000", "xivo-subrgbl-outcall?:return") in new stack
[Sep 26 17:12:43]     -- Executing [s@xivo-global-subroutine:4] GotoIf("SIP/ifvdbq1h-00000000", "0?:return") in new stack
[Sep 26 17:12:43]     -- Goto (xivo-global-subroutine,s,6)
[Sep 26 17:12:43]     -- Executing [s@xivo-global-subroutine:6] Return("SIP/ifvdbq1h-00000000", "") in new stack
[Sep 26 17:12:43]     -- Executing [dial@outcall:5] CELGenUserEvent("SIP/ifvdbq1h-00000000", "XIVO_OUTCALL") in new stack
[Sep 26 17:12:43]     -- Executing [dial@outcall:6] Set("SIP/ifvdbq1h-00000000", "CONNECTEDLINE(num,i)=2266") in new stack
[Sep 26 17:12:43]     -- Executing [dial@outcall:7] Dial("SIP/ifvdbq1h-00000000", "SIP/OXE/2266,,o(2266)") in new stack
[Sep 26 17:12:43]   == Using SIP RTP CoS mark 5
[Sep 26 17:12:43] Audio is at 13206
[Sep 26 17:12:43] Adding codec alaw to SDP
[Sep 26 17:12:43] Adding codec ulaw to SDP
[Sep 26 17:12:43] Adding codec g723 to SDP
[Sep 26 17:12:43] Adding non-codec 0x1 (telephone-event) to SDP
[Sep 26 17:12:43] Reliably Transmitting (no NAT) to XXX.XXX.XXX.XXX:5060:
[Sep 26 17:12:43] INVITE sip:2266@XXX.XXX.XXX.XXX:5060 SIP/2.0
[Sep 26 17:12:43] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK03aa1085
[Sep 26 17:12:43] Max-Forwards: 70
[Sep 26 17:12:43] From: "jean-marc " <sip:mtcl@XXX.XXX.XXX.XXX>;tag=as20b11e65
[Sep 26 17:12:43] To: <sip:2266@XXX.XXX.XXX.XXX:5060>
[Sep 26 17:12:43] Contact: <sip:mtcl@XXX.XXX.XXX.XXX:5060>
[Sep 26 17:12:43] Call-ID: 17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX
[Sep 26 17:12:43] CSeq: 102 INVITE
[Sep 26 17:12:43] User-Agent: XIVO PBX
[Sep 26 17:12:43] Date: Mon, 26 Sep 2016 15:12:43 GMT
[Sep 26 17:12:43] Session-Expires: 1860
[Sep 26 17:12:43] Min-SE: 190
[Sep 26 17:12:43] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
[Sep 26 17:12:43] Supported: replaces, timer
[Sep 26 17:12:43] Remote-Party-ID: "jean-marc " <sip:667@XXX.XXX.XXX.XXX>;party=calling;privacy=off;screen=no
[Sep 26 17:12:43] Content-Type: application/sdp
[Sep 26 17:12:43] Content-Length: 317
[Sep 26 17:12:43]
[Sep 26 17:12:43] v=0
[Sep 26 17:12:43] o=root 987193067 987193067 IN IP4 XXX.XXX.XXX.XXX
[Sep 26 17:12:43] s=Asterisk PBX 13.9.1
[Sep 26 17:12:43] c=IN IP4 XXX.XXX.XXX.XXX
[Sep 26 17:12:43] t=0 0
[Sep 26 17:12:43] m=audio 13206 RTP/AVP 8 0 4 101
[Sep 26 17:12:43] a=rtpmap:8 PCMA/8000
[Sep 26 17:12:43] a=rtpmap:0 PCMU/8000
[Sep 26 17:12:43] a=rtpmap:4 G723/8000
[Sep 26 17:12:43] a=fmtp:4 annexa=no
[Sep 26 17:12:43] a=rtpmap:101 telephone-event/8000
[Sep 26 17:12:43] a=fmtp:101 0-16
[Sep 26 17:12:43] a=ptime:20
[Sep 26 17:12:43] a=maxptime:150
[Sep 26 17:12:43] a=sendrecv
[Sep 26 17:12:43]
[Sep 26 17:12:43] ---
[Sep 26 17:12:43]     -- Called SIP/OXE/2266
[Sep 26 17:12:43]
[Sep 26 17:12:43] <--- SIP read from UDP:XXX.XXX.XXX.XXX:5060 --->
[Sep 26 17:12:43] SIP/2.0 100 Trying
[Sep 26 17:12:43] To: <sip:2266@XXX.XXX.XXX.XXX:5060>
[Sep 26 17:12:43] From: "jean-marc " <sip:mtcl@XXX.XXX.XXX.XXX>;tag=as20b11e65
[Sep 26 17:12:43] Call-ID: 17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX
[Sep 26 17:12:43] CSeq: 102 INVITE
[Sep 26 17:12:43] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK03aa1085
[Sep 26 17:12:43] Content-Length: 0
[Sep 26 17:12:43]
[Sep 26 17:12:43]
[Sep 26 17:12:43] <------------->
[Sep 26 17:12:43] --- (7 headers 0 lines) ---
[Sep 26 17:12:44]
[Sep 26 17:12:44] <--- SIP read from UDP:XXX.XXX.XXX.XXX:5060 --->
[Sep 26 17:12:44] SIP/2.0 422 Session Timer Too Small
[Sep 26 17:12:44] Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
[Sep 26 17:12:44] User-Agent: OmniPCX Enterprise R10.0 j1.410.49.a
[Sep 26 17:12:44] Min-SE: 43200
[Sep 26 17:12:44] To: <sip:2266@XXX.XXX.XXX.XXX:5060>;tag=6b7c65ad13af8ac975c452c7e9f5faee
[Sep 26 17:12:44] From: "jean-marc " <sip:mtcl@XXX.XXX.XXX.XXX>;tag=as20b11e65
[Sep 26 17:12:44] Call-ID: 17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX
[Sep 26 17:12:44] CSeq: 102 INVITE
[Sep 26 17:12:44] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK03aa1085
[Sep 26 17:12:44] Content-Length: 0
[Sep 26 17:12:44]
[Sep 26 17:12:44]
[Sep 26 17:12:44] <------------->
[Sep 26 17:12:44] --- (10 headers 0 lines) ---
[Sep 26 17:12:44] Transmitting (no NAT) to XXX.XXX.XXX.XXX:5060:
[Sep 26 17:12:44] ACK sip:2266@XXX.XXX.XXX.XXX:5060 SIP/2.0
[Sep 26 17:12:44] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK03aa1085
[Sep 26 17:12:44] Max-Forwards: 70
[Sep 26 17:12:44] From: "jean-marc " <sip:mtcl@XXX.XXX.XXX.XXX>;tag=as20b11e65
[Sep 26 17:12:44] To: <sip:2266@XXX.XXX.XXX.XXX:5060>;tag=6b7c65ad13af8ac975c452c7e9f5faee
[Sep 26 17:12:44] Contact: <sip:mtcl@XXX.XXX.XXX.XXX:5060>
[Sep 26 17:12:44] Call-ID: 17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX
[Sep 26 17:12:44] CSeq: 102 ACK
[Sep 26 17:12:44] User-Agent: XIVO PBX
[Sep 26 17:12:44] Content-Length: 0
[Sep 26 17:12:44]
[Sep 26 17:12:44]
[Sep 26 17:12:44] ---
[Sep 26 17:12:44] Audio is at 13206
[Sep 26 17:12:44] Adding codec alaw to SDP
[Sep 26 17:12:44] Adding codec ulaw to SDP
[Sep 26 17:12:44] Adding codec g723 to SDP
[Sep 26 17:12:44] Adding non-codec 0x1 (telephone-event) to SDP
[Sep 26 17:12:44] Reliably Transmitting (no NAT) to XXX.XXX.XXX.XXX:5060:
[Sep 26 17:12:44] INVITE sip:2266@XXX.XXX.XXX.XXX:5060 SIP/2.0
[Sep 26 17:12:44] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK57c845ae
[Sep 26 17:12:44] Max-Forwards: 70
[Sep 26 17:12:44] From: "jean-marc " <sip:mtcl@XXX.XXX.XXX.XXX>;tag=as20b11e65
[Sep 26 17:12:44] To: <sip:2266@XXX.XXX.XXX.XXX:5060>
[Sep 26 17:12:44] Contact: <sip:mtcl@XXX.XXX.XXX.XXX:5060>
[Sep 26 17:12:44] Call-ID: 17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX
[Sep 26 17:12:44] CSeq: 103 INVITE
[Sep 26 17:12:44] User-Agent: XIVO PBX
[Sep 26 17:12:44] Date: Mon, 26 Sep 2016 15:12:43 GMT
[Sep 26 17:12:44] Session-Expires: 43200
[Sep 26 17:12:44] Min-SE: 43200
[Sep 26 17:12:44] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
[Sep 26 17:12:44] Supported: replaces, timer
[Sep 26 17:12:44] Remote-Party-ID: "jean-marc " <sip:667@XXX.XXX.XXX.XXX>;party=calling;privacy=off;screen=no
[Sep 26 17:12:44] Content-Type: application/sdp
[Sep 26 17:12:44] Content-Length: 317
[Sep 26 17:12:44]
[Sep 26 17:12:44] v=0
[Sep 26 17:12:44] o=root 987193067 987193068 IN IP4 XXX.XXX.XXX.XXX
[Sep 26 17:12:44] s=Asterisk PBX 13.9.1
[Sep 26 17:12:44] c=IN IP4 XXX.XXX.XXX.XXX
[Sep 26 17:12:44] t=0 0
[Sep 26 17:12:44] m=audio 13206 RTP/AVP 8 0 4 101
[Sep 26 17:12:44] a=rtpmap:8 PCMA/8000
[Sep 26 17:12:44] a=rtpmap:0 PCMU/8000
[Sep 26 17:12:44] a=rtpmap:4 G723/8000
[Sep 26 17:12:44] a=fmtp:4 annexa=no
[Sep 26 17:12:44] a=rtpmap:101 telephone-event/8000
[Sep 26 17:12:44] a=fmtp:101 0-16
[Sep 26 17:12:44] a=ptime:20
[Sep 26 17:12:44] a=maxptime:150
[Sep 26 17:12:44] a=sendrecv
[Sep 26 17:12:44]
[Sep 26 17:12:44] ---
[Sep 26 17:12:44]
[Sep 26 17:12:44] <--- SIP read from UDP:XXX.XXX.XXX.XXX:5060 --->
[Sep 26 17:12:44] SIP/2.0 100 Trying
[Sep 26 17:12:44] To: <sip:2266@XXX.XXX.XXX.XXX:5060>
[Sep 26 17:12:44] From: "jean-marc " <sip:mtcl@XXX.XXX.XXX.XXX>;tag=as20b11e65
[Sep 26 17:12:44] Call-ID: 17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX
[Sep 26 17:12:44] CSeq: 103 INVITE
[Sep 26 17:12:44] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK57c845ae
[Sep 26 17:12:44] Content-Length: 0
[Sep 26 17:12:44]
[Sep 26 17:12:44]
[Sep 26 17:12:44] <------------->
[Sep 26 17:12:44] --- (7 headers 0 lines) ---
[Sep 26 17:12:44]
[Sep 26 17:12:44] <--- SIP read from UDP:XXX.XXX.XXX.XXX:5060 --->
[Sep 26 17:12:44] SIP/2.0 404 Not Found
[Sep 26 17:12:44] Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
[Sep 26 17:12:44] User-Agent: OmniPCX Enterprise R10.0 j1.410.49.a
[Sep 26 17:12:44] To: <sip:2266@XXX.XXX.XXX.XXX:5060>;tag=ed76b849a1efcaef37284c303c067d71
[Sep 26 17:12:44] From: "jean-marc " <sip:mtcl@XXX.XXX.XXX.XXX>;tag=as20b11e65
[Sep 26 17:12:44] Call-ID: 17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX
[Sep 26 17:12:44] CSeq: 103 INVITE
[Sep 26 17:12:44] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK57c845ae
[Sep 26 17:12:44] Content-Length: 0
[Sep 26 17:12:44]
[Sep 26 17:12:44]
[Sep 26 17:12:44] <------------->
[Sep 26 17:12:44] --- (9 headers 0 lines) ---
[Sep 26 17:12:44] Transmitting (no NAT) to XXX.XXX.XXX.XXX:5060:
[Sep 26 17:12:44] ACK sip:2266@XXX.XXX.XXX.XXX:5060 SIP/2.0
[Sep 26 17:12:44] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK57c845ae
[Sep 26 17:12:44] Max-Forwards: 70
[Sep 26 17:12:44] From: "jean-marc " <sip:mtcl@XXX.XXX.XXX.XXX>;tag=as20b11e65
[Sep 26 17:12:44] To: <sip:2266@XXX.XXX.XXX.XXX:5060>;tag=ed76b849a1efcaef37284c303c067d71
[Sep 26 17:12:44] Contact: <sip:mtcl@XXX.XXX.XXX.XXX:5060>
[Sep 26 17:12:44] Call-ID: 17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX
[Sep 26 17:12:44] CSeq: 103 ACK
[Sep 26 17:12:44] User-Agent: XIVO PBX
[Sep 26 17:12:44] Content-Length: 0
[Sep 26 17:12:44]
[Sep 26 17:12:44]
[Sep 26 17:12:44] ---
[Sep 26 17:12:44] Scheduling destruction of SIP dialog '17afe02c4d9f68f00087657b14c49dcf@XXX.XXX.XXX.XXX' in 32000 ms (Method: INVITE)
[Sep 26 17:12:44]   == Everyone is busy/congested at this time (1:0/0/1)
[Sep 26 17:12:44]     -- Executing [dial@outcall:8] Goto("SIP/ifvdbq1h-00000000", "CHANUNAVAIL,1") in new stack
[Sep 26 17:12:44]     -- Goto (outcall,CHANUNAVAIL,1)
[Sep 26 17:12:44]     -- Executing [CHANUNAVAIL@outcall:1] Goto("SIP/ifvdbq1h-00000000", "redial,1") in new stack
[Sep 26 17:12:44]     -- Goto (outcall,redial,1)
[Sep 26 17:12:44]     -- Executing [redial@outcall:1] Set("SIP/ifvdbq1h-00000000", "TRUNKINDEX=1") in new stack
[Sep 26 17:12:44]     -- Executing [redial@outcall:2] GotoIf("SIP/ifvdbq1h-00000000", "?dial,1") in new stack
[Sep 26 17:12:44]     -- Executing [redial@outcall:3] Playback("SIP/ifvdbq1h-00000000", "congestion-call") in new stack
[Sep 26 17:12:44]        > 0x7f5524005df0 -- Probation passed - setting RTP source address to 10.51.55.168:16470
[Sep 26 17:12:44]     -- <SIP/ifvdbq1h-00000000> Playing 'congestion-call.slin' (language 'fr_FR')
[Sep 26 17:12:50]     -- Executing [redial@outcall:4] Hangup("SIP/ifvdbq1h-00000000", "") in new stack
[Sep 26 17:12:50]   == Spawn extension (outcall, redial, 4) exited non-zero on 'SIP/ifvdbq1h-00000000'
[Sep 26 17:12:50]     -- SIP/ifvdbq1h-00000000 Internal Gosub(hangup_handlers,userevent,1) start
[Sep 26 17:12:50]     -- Executing [userevent@hangup_handlers:1] NoOp("SIP/ifvdbq1h-00000000", "Sending Hangup userevent") in new stack
[Sep 26 17:12:50]     -- Executing [userevent@hangup_handlers:2] UserEvent("SIP/ifvdbq1h-00000000", "Hangup,XIVO_USERUUID: 1c4c5c80-1952-43c5-97c7-009f7f1dc7de") in new stack
[Sep 26 17:12:50]     -- Executing [userevent@hangup_handlers:3] Return("SIP/ifvdbq1h-00000000", "") in new stack
[Sep 26 17:12:50]   == Spawn extension (outcall, redial, 4) exited non-zero on 'SIP/ifvdbq1h-00000000'
[Sep 26 17:12:50]     -- SIP/ifvdbq1h-00000000 Internal Gosub(hangup_handlers,userevent,1) complete GOSUB_RETVAL=

Any expert help would be appreciated.
Is there a way to view debug log from OXE?

Thanks
Bryan

cavagnaro · Post by **cavagnaro** » 26 Sep 2016 20:11

SIP/2.0 422 Session Timer Too Small

Increase the SIP Session timers on OXE (mgr/SIP)
Error is pretty clear

bwen · Post by **bwen** » 27 Sep 2016 06:54

Hello,

Thank you for your answer but session timer is already set to max (86400)..

bwen · Post by **bwen** » 27 Sep 2016 08:05

I have decrease the minSE on OXE and i dont have "session timer too small" anymore but no more call success:

Code: Select all

[Sep 27 13:58:20]   == Using SIP RTP CoS mark 5
[Sep 27 13:58:20]     -- Executing [2266@default:1] Set("SIP/ifvdbq1h-00000004", "XIVO_BASE_CONTEXT=default") in new stack
[Sep 27 13:58:20]     -- Executing [2266@default:2] Set("SIP/ifvdbq1h-00000004", "XIVO_BASE_EXTEN=2266") in new stack
[Sep 27 13:58:20]     -- Executing [2266@default:3] Gosub("SIP/ifvdbq1h-00000004", "outcall,s,1(3,)") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:1] Set("SIP/ifvdbq1h-00000004", "XIVO_DSTID=3") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:2] Set("SIP/ifvdbq1h-00000004", "XIVO_PRESUBR_GLOBAL_NAME=OUTCALL") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:3] Set("SIP/ifvdbq1h-00000004", "XIVO_SRCNUM=667") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:4] Set("SIP/ifvdbq1h-00000004", "XIVO_DSTNUM=2266") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:5] Set("SIP/ifvdbq1h-00000004", "XIVO_CONTEXT=default") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:6] Gosub("SIP/ifvdbq1h-00000004", "originate-caller-id,s,1") in new stack
[Sep 27 13:58:20]     -- Executing [s@originate-caller-id:1] GotoIf("SIP/ifvdbq1h-00000004", "0?:name") in new stack
[Sep 27 13:58:20]     -- Goto (originate-caller-id,s,3)
[Sep 27 13:58:20]     -- Executing [s@originate-caller-id:3] GotoIf("SIP/ifvdbq1h-00000004", "0?:fix") in new stack
[Sep 27 13:58:20]     -- Goto (originate-caller-id,s,5)
[Sep 27 13:58:20]     -- Executing [s@originate-caller-id:5] GotoIf("SIP/ifvdbq1h-00000004", "?:end") in new stack
[Sep 27 13:58:20]     -- Goto (originate-caller-id,s,8)
[Sep 27 13:58:20]     -- Executing [s@originate-caller-id:8] Return("SIP/ifvdbq1h-00000004", "") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:7] AGI("SIP/ifvdbq1h-00000004", "agi://127.0.0.1/outgoing_user_set_features") in new stack
[Sep 27 13:58:20]  agi://127.0.0.1/outgoing_user_set_features: AGI handler 'outgoing_user_set_features' successfully executed
[Sep 27 13:58:20]     -- <SIP/ifvdbq1h-00000004>AGI Script agi://127.0.0.1/outgoing_user_set_features completed, returning 0
[Sep 27 13:58:20]     -- Executing [s@outcall:8] Gosub("SIP/ifvdbq1h-00000004", "xivo-subroutine,s,1()") in new stack
[Sep 27 13:58:20]     -- Executing [s@xivo-subroutine:1] GotoIf("SIP/ifvdbq1h-00000004", "?:nosubroutine") in new stack
[Sep 27 13:58:20]     -- Goto (xivo-subroutine,s,4)
[Sep 27 13:58:20]     -- Executing [s@xivo-subroutine:4] Return("SIP/ifvdbq1h-00000004", "") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:9] Gosub("SIP/ifvdbq1h-00000004", "xivo-user_rights_check,s,1") in new stack
[Sep 27 13:58:20]     -- Executing [s@xivo-user_rights_check:1] AGI("SIP/ifvdbq1h-00000004", "agi://127.0.0.1/user_set_call_rights") in new stack
[Sep 27 13:58:20]  agi://127.0.0.1/user_set_call_rights: AGI handler 'user_set_call_rights' successfully executed
[Sep 27 13:58:20]     -- <SIP/ifvdbq1h-00000004>AGI Script agi://127.0.0.1/user_set_call_rights completed, returning 0
[Sep 27 13:58:20]     -- Executing [s@xivo-user_rights_check:2] GotoIf("SIP/ifvdbq1h-00000004", "ALLOW?:error,1") in new stack
[Sep 27 13:58:20]     -- Executing [s@xivo-user_rights_check:3] GotoIf("SIP/ifvdbq1h-00000004", "1?allow,1") in new stack
[Sep 27 13:58:20]     -- Goto (xivo-user_rights_check,allow,1)
[Sep 27 13:58:20]     -- Executing [allow@xivo-user_rights_check:1] NoOp("SIP/ifvdbq1h-00000004", "User allowed to make call") in new stack
[Sep 27 13:58:20]     -- Executing [allow@xivo-user_rights_check:2] Return("SIP/ifvdbq1h-00000004", "") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:10] AGI("SIP/ifvdbq1h-00000004", "agi://127.0.0.1/check_schedule") in new stack
[Sep 27 13:58:20]  agi://127.0.0.1/check_schedule: AGI handler 'check_schedule' successfully executed
[Sep 27 13:58:20]     -- <SIP/ifvdbq1h-00000004>AGI Script agi://127.0.0.1/check_schedule completed, returning 0
[Sep 27 13:58:20]     -- Executing [s@outcall:11] GotoIf("SIP/ifvdbq1h-00000004", "0?CLOSED,1") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:12] GotoIf("SIP/ifvdbq1h-00000004", "?:14") in new stack
[Sep 27 13:58:20]     -- Goto (outcall,s,14)
[Sep 27 13:58:20]     -- Executing [s@outcall:14] GotoIf("SIP/ifvdbq1h-00000004", "SIP/OXE?:error,1") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:15] Set("SIP/ifvdbq1h-00000004", "TRUNKINDEX=0") in new stack
[Sep 27 13:58:20]     -- Executing [s@outcall:16] Goto("SIP/ifvdbq1h-00000004", "dial,1") in new stack
[Sep 27 13:58:20]     -- Goto (outcall,dial,1)
[Sep 27 13:58:20]     -- Executing [dial@outcall:1] Set("SIP/ifvdbq1h-00000004", "INTERFACE=SIP/OXE") in new stack
[Sep 27 13:58:20]     -- Executing [dial@outcall:2] Set("SIP/ifvdbq1h-00000004", "TRUNKEXTEN=2266") in new stack
[Sep 27 13:58:20]     -- Executing [dial@outcall:3] Set("SIP/ifvdbq1h-00000004", "TRUNKSUFFIX=") in new stack
[Sep 27 13:58:20]     -- Executing [dial@outcall:4] Gosub("SIP/ifvdbq1h-00000004", "xivo-global-subroutine,s,1") in new stack
[Sep 27 13:58:20]     -- Executing [s@xivo-global-subroutine:1] GotoIf("SIP/ifvdbq1h-00000004", "1?:return") in new stack
[Sep 27 13:58:20]     -- Executing [s@xivo-global-subroutine:2] GotoIf("SIP/ifvdbq1h-00000004", "OUTCALL?:return") in new stack
[Sep 27 13:58:20]     -- Executing [s@xivo-global-subroutine:3] GotoIf("SIP/ifvdbq1h-00000004", "xivo-subrgbl-outcall?:return") in new stack
[Sep 27 13:58:20]     -- Executing [s@xivo-global-subroutine:4] GotoIf("SIP/ifvdbq1h-00000004", "0?:return") in new stack
[Sep 27 13:58:20]     -- Goto (xivo-global-subroutine,s,6)
[Sep 27 13:58:20]     -- Executing [s@xivo-global-subroutine:6] Return("SIP/ifvdbq1h-00000004", "") in new stack
[Sep 27 13:58:20]     -- Executing [dial@outcall:5] CELGenUserEvent("SIP/ifvdbq1h-00000004", "XIVO_OUTCALL") in new stack
[Sep 27 13:58:20]     -- Executing [dial@outcall:6] Set("SIP/ifvdbq1h-00000004", "CONNECTEDLINE(num,i)=2266") in new stack
[Sep 27 13:58:20]     -- Executing [dial@outcall:7] Dial("SIP/ifvdbq1h-00000004", "SIP/OXE/2266,,o(2266)") in new stack
[Sep 27 13:58:20]   == Using SIP RTP CoS mark 5
[Sep 27 13:58:20] Audio is at 10642
[Sep 27 13:58:20] Adding codec alaw to SDP
[Sep 27 13:58:20] Adding codec ulaw to SDP
[Sep 27 13:58:20] Adding codec g723 to SDP
[Sep 27 13:58:20] Adding non-codec 0x1 (telephone-event) to SDP
[Sep 27 13:58:20] Reliably Transmitting (no NAT) to XXX.XXX.XXX.XXX:5060:
[Sep 27 13:58:20] INVITE sip:2266@XXX.XXX.XXX.XXX:5060 SIP/2.0
[Sep 27 13:58:20] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK509a136f
[Sep 27 13:58:20] Max-Forwards: 70
[Sep 27 13:58:20] From: "jean-marc " <sip:667@XXX.XXX.XXX.XXX>;tag=as1944ec45
[Sep 27 13:58:20] To: <sip:2266@XXX.XXX.XXX.XXX:5060>
[Sep 27 13:58:20] Contact: <sip:667@XXX.XXX.XXX.XXX:5060>
[Sep 27 13:58:20] Call-ID: 50efa5d654782cd94aa9658e136beb0f@XXX.XXX.XXX.XXX
[Sep 27 13:58:20] CSeq: 102 INVITE
[Sep 27 13:58:20] User-Agent: XIVO PBX
[Sep 27 13:58:20] Date: Tue, 27 Sep 2016 11:58:20 GMT
[Sep 27 13:58:20] Session-Expires: 1800
[Sep 27 13:58:20] Min-SE: 300
[Sep 27 13:58:20] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
[Sep 27 13:58:20] Supported: replaces, timer
[Sep 27 13:58:20] Remote-Party-ID: "jean-marc " <sip:667@XXX.XXX.XXX.XXX>;party=calling;privacy=off;screen=no
[Sep 27 13:58:20] Content-Type: application/sdp
[Sep 27 13:58:20] Content-Length: 317
[Sep 27 13:58:20]
[Sep 27 13:58:20] v=0
[Sep 27 13:58:20] o=root 804414646 804414646 IN IP4 XXX.XXX.XXX.XXX
[Sep 27 13:58:20] s=Asterisk PBX 13.9.1
[Sep 27 13:58:20] c=IN IP4 XXX.XXX.XXX.XXX
[Sep 27 13:58:20] t=0 0
[Sep 27 13:58:20] m=audio 10642 RTP/AVP 8 0 4 101
[Sep 27 13:58:20] a=rtpmap:8 PCMA/8000
[Sep 27 13:58:20] a=rtpmap:0 PCMU/8000
[Sep 27 13:58:20] a=rtpmap:4 G723/8000
[Sep 27 13:58:20] a=fmtp:4 annexa=no
[Sep 27 13:58:20] a=rtpmap:101 telephone-event/8000
[Sep 27 13:58:20] a=fmtp:101 0-16
[Sep 27 13:58:20] a=ptime:20
[Sep 27 13:58:20] a=maxptime:150
[Sep 27 13:58:20] a=sendrecv
[Sep 27 13:58:20]
[Sep 27 13:58:20] ---
[Sep 27 13:58:20]     -- Called SIP/OXE/2266
[Sep 27 13:58:20]
[Sep 27 13:58:20] <--- SIP read from UDP:XXX.XXX.XXX.XXX:5060 --->
[Sep 27 13:58:20] SIP/2.0 100 Trying
[Sep 27 13:58:20] To: <sip:2266@XXX.XXX.XXX.XXX:5060>
[Sep 27 13:58:20] From: "jean-marc " <sip:667@XXX.XXX.XXX.XXX>;tag=as1944ec45
[Sep 27 13:58:20] Call-ID: 50efa5d654782cd94aa9658e136beb0f@XXX.XXX.XXX.XXX
[Sep 27 13:58:20] CSeq: 102 INVITE
[Sep 27 13:58:20] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK509a136f
[Sep 27 13:58:20] Content-Length: 0
[Sep 27 13:58:20]
[Sep 27 13:58:20]
[Sep 27 13:58:20] <------------->
[Sep 27 13:58:20] --- (7 headers 0 lines) ---
[Sep 27 13:58:20]
[Sep 27 13:58:20] <--- SIP read from UDP:XXX.XXX.XXX.XXX:5060 --->
[Sep 27 13:58:20] SIP/2.0 404 Not Found
[Sep 27 13:58:20] Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
[Sep 27 13:58:20] User-Agent: OmniPCX Enterprise R10.0 j1.410.49.a
[Sep 27 13:58:20] To: <sip:2266@XXX.XXX.XXX.XXX:5060>;tag=8662dea65b7c4794168f018c95a5a01c
[Sep 27 13:58:20] From: "jean-marc " <sip:667@XXX.XXX.XXX.XXX>;tag=as1944ec45
[Sep 27 13:58:20] Call-ID: 50efa5d654782cd94aa9658e136beb0f@XXX.XXX.XXX.XXX
[Sep 27 13:58:20] CSeq: 102 INVITE
[Sep 27 13:58:20] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK509a136f
[Sep 27 13:58:20] Content-Length: 0
[Sep 27 13:58:20]
[Sep 27 13:58:20]
[Sep 27 13:58:20] <------------->
[Sep 27 13:58:20] --- (9 headers 0 lines) ---
[Sep 27 13:58:20] Transmitting (no NAT) to XXX.XXX.XXX.XXX:5060:
[Sep 27 13:58:20] ACK sip:2266@XXX.XXX.XXX.XXX:5060 SIP/2.0
[Sep 27 13:58:20] Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK509a136f
[Sep 27 13:58:20] Max-Forwards: 70
[Sep 27 13:58:20] From: "jean-marc " <sip:667@XXX.XXX.XXX.XXX>;tag=as1944ec45
[Sep 27 13:58:20] To: <sip:2266@XXX.XXX.XXX.XXX:5060>;tag=8662dea65b7c4794168f018c95a5a01c
[Sep 27 13:58:20] Contact: <sip:667@XXX.XXX.XXX.XXX:5060>
[Sep 27 13:58:20] Call-ID: 50efa5d654782cd94aa9658e136beb0f@XXX.XXX.XXX.XXX
[Sep 27 13:58:20] CSeq: 102 ACK
[Sep 27 13:58:20] User-Agent: XIVO PBX
[Sep 27 13:58:20] Content-Length: 0
[Sep 27 13:58:20]
[Sep 27 13:58:20]
[Sep 27 13:58:20] ---
[Sep 27 13:58:20] Scheduling destruction of SIP dialog '50efa5d654782cd94aa9658e136beb0f@XXX.XXX.XXX.XXX' in 32000 ms (Method: INVITE)
[Sep 27 13:58:20]   == Everyone is busy/congested at this time (1:0/0/1)
[Sep 27 13:58:20]     -- Executing [dial@outcall:8] Goto("SIP/ifvdbq1h-00000004", "CHANUNAVAIL,1") in new stack
[Sep 27 13:58:20]     -- Goto (outcall,CHANUNAVAIL,1)
[Sep 27 13:58:20]     -- Executing [CHANUNAVAIL@outcall:1] Goto("SIP/ifvdbq1h-00000004", "redial,1") in new stack
[Sep 27 13:58:20]     -- Goto (outcall,redial,1)
[Sep 27 13:58:20]     -- Executing [redial@outcall:1] Set("SIP/ifvdbq1h-00000004", "TRUNKINDEX=1") in new stack
[Sep 27 13:58:20]     -- Executing [redial@outcall:2] GotoIf("SIP/ifvdbq1h-00000004", "?dial,1") in new stack
[Sep 27 13:58:20]     -- Executing [redial@outcall:3] Playback("SIP/ifvdbq1h-00000004", "congestion-call") in new stack
[Sep 27 13:58:21]        > 0x7f9148005df0 -- Probation passed - setting RTP source address to 10.51.55.168:16490
[Sep 27 13:58:21]     -- <SIP/ifvdbq1h-00000004> Playing 'congestion-call.slin' (language 'fr_FR')
[Sep 27 13:58:23]     -- SIP/ifvdbq1h-00000004 Internal Gosub(hangup_handlers,userevent,1) start
[Sep 27 13:58:23]     -- Executing [userevent@hangup_handlers:1] NoOp("SIP/ifvdbq1h-00000004", "Sending Hangup userevent") in new stack
[Sep 27 13:58:23]     -- Executing [userevent@hangup_handlers:2] UserEvent("SIP/ifvdbq1h-00000004", "Hangup,XIVO_USERUUID: 1c4c5c80-1952-43c5-97c7-009f7f1dc7de") in new stack
[Sep 27 13:58:23]     -- Executing [userevent@hangup_handlers:3] Return("SIP/ifvdbq1h-00000004", "") in new stack
[Sep 27 13:58:23]   == Spawn extension (outcall, redial, 3) exited non-zero on 'SIP/ifvdbq1h-00000004'
[Sep 27 13:58:23]     -- SIP/ifvdbq1h-00000004 Internal Gosub(hangup_handlers,userevent,1) complete GOSUB_RETVAL= complete GOSUB_RETVAL=

cavagnaro · Post by **cavagnaro** » 27 Sep 2016 14:47

404 not found...so...check the number you are calling

bwen · Post by **bwen** » 28 Sep 2016 06:51

I have 404 not found with all numbers (intern or extern), i must have miss something before but i have re-check my conf and I dont know how to investigate anymore. Could you please give me some hints to search deeper? i'm really noob in this domain.

tgn · Post by **tgn** » 28 Sep 2016 16:04

what have you configured until now? sip trunkgroup, sip external gateway, etc...

regards...

Gesendet von iPhone mit Tapatalk

bwen · Post by **bwen** » 29 Sep 2016 04:39

Hello,

I have configured trunk group, SIP GW, SIP EXT GW , routing number and ARS according to this post viewtopic.php?f=227&t=14231

bwen · Post by **bwen** » 29 Sep 2016 06:44

is there a way to have the "sip debug" equivalent on OXE side? traced give me nothing about the requested call from asterisk

cavagnaro · Post by **cavagnaro** » 29 Sep 2016 08:54

Ermmm motortrace = 3? Like mentioned on all sip docs

Enviado de meu E6633 usando Tapatalk

Alcatel Unleashed

trunk SIP OXE-XIVO

trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO

Re: trunk SIP OXE-XIVO