asked on
Avaya IP Office in tandem
I had setup a SIP trunk between an Avaya IP Office 6.0.14 and an Asterisk 1.6.0.26-FONCORE-r78. Everything works fine. The Avaya phones and the Asterisk phones works in the two ways for all extensions.
PSTN access is behind the IP Office. The problem is when I want to make PSTN outbound calls from any Asterisk extension via the IP Office.
I see the request arriving in the IP Office but the IP Office search a user and do not try to use the ARS. Because the user does not exist the IP Office rejects the call and I get a congestion signal on the Asterisk.
All extensions must dial 9 for PSTN calls.
Asterisk has a Outbound route with Dial Pattern 9. sending the call to the Avaya trunk.
IP Office has the SIP line with Incoming Group 180.
IP Office has an Incoming Call Group with Line Group ID 180 and Destination "."
IP Office has a Short Code 9N, feature Dial Speech, Telephone Number N, Line Group ID 50. That is the same short code that all Avaya extensions use to place outbound PSTN calls and works perfectly.
IP Office has an ARS route ID 50 that places the call.
This is the Asterisk log for the call:
[Sep 9 10:03:16] VERBOSE[3200] logger.c: == Using SIP RTP TOS bits 184
[Sep 9 10:03:16] VERBOSE[3200] logger.c: == Using SIP RTP CoS mark 5
[Sep 9 10:03:16] VERBOSE[3200] logger.c: == Using SIP VRTP TOS bits 136
[Sep 9 10:03:16] VERBOSE[3200] logger.c: == Using SIP VRTP CoS mark 6
[Sep 9 10:03:16] DEBUG[3153] pbx.c: FONALITY: This thread has already held the conlock, skip locking
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/801-00000016", "AMPUSER=801") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/801-00000016", "AMPUSER=801") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/801-00000016", "AMPUSERCIDNAME=Francisco Braz") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/801-00000016", "AMPUSERCID=801") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/801-00000016", "CALLERID(all)="Francisco Braz" <801>") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:9] ExecIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:10]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-user-callerid,s,19)
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:19]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-record-enable,s,4)
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/801-00000016", "recordingcheck,20100909-1
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/
[Sep 9 10:03:16] VERBOSE[11683] logger.c: recordingcheck,20100909-10
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- <SIP/801-00000016>AGI Script recordingcheck completed, returning 0
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/801-0000001
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/801-00000016", "DIAL_TRUNK=1") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/801-00000016"
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/801-00000016", "DIAL_NUMBER=94162312309")
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/801-00000016", "DIAL_TRUNK_OPTIONS=tr") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/801-00000016", "OUTBOUND_GROUP=OUT_1") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:10]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:11]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-outbound-callerid,s
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-outbound-callerid,s
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:12]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:13]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:14]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:15]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:16]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk-pre
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:17]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:18]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:19]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Using SIP RTP TOS bits 184
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Using SIP RTP CoS mark 5
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Using SIP VRTP TOS bits 136
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Using SIP VRTP CoS mark 6
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Called avaya/94162312309
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- SIP/avaya-00000017 is circuit-busy
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:20]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-dialout-trunk,s-CON
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s-CONGESTION@macro-dialou
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-dialout-trunk,s-CON
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s-CONGESTION@macro-dialou
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outisbusy:1] Playback("SIP/801-00000016
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- <SIP/801-00000016> Playing 'all-circuits-busy-now.ula
[Sep 9 10:03:16] WARNING[11683] rtp.c: RTP Read too short
[Sep 9 10:03:18] VERBOSE[11683] logger.c: -- Executing [s@macro-outisbusy:2] Playback("SIP/801-00000016
[Sep 9 10:03:18] VERBOSE[11683] logger.c: -- <SIP/801-00000016> Playing 'pls-try-call-later.ulaw' (language 'en')
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-outisbusy:3] Macro("SIP/801-00000016", "hangupcall") in new stack
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,4)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,7)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,9)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/801-00000016' in macro 'hangupcall'
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (macro-outisbusy, s, 3) exited non-zero on 'SIP/801-00000016' in macro 'outisbusy'
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (from-internal, 94162312309, 6) exited non-zero on 'SIP/801-00000016'
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [h@from-internal:1] Macro("SIP/801-00000016", "hangupcall") in new stack
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,4)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,7)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,9)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/801-00000016' in macro 'hangupcall'
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/801-00000016'
[Sep 9 10:03:20] DEBUG[3153] pbx.c: FONALITY: This thread has already held the conlock, skip locking
This is the Avaya monitor log for the call:
96951512mS SIP Rx: UDP 192.168.14.20:5060 -> 192.168.14.10:5060
INVITE sip:94162312309@192.168.14
Via: SIP/2.0/UDP 192.168.14.20:5060;branch=
Max-Forwards: 70
From: "Francisco Braz" <sip:801@192.168.14.20>;ta
To: <sip:94162312309@192.168.1
Contact: <sip:801@192.168.14.20>
Call-ID: 159589f5207126ef11b8f3f119
CSeq: 102 INVITE
User-Agent: Asterisk PBX 1.6.0.26-FONCORE-r78
Date: Thu, 09 Sep 2010 13:15:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 278
v=0
o=root 1972484822 1972484822 IN IP4 192.168.14.20
s=Asterisk PBX 1.6.0.26-FONCORE-r78
c=IN IP4 192.168.14.20
t=0 0
m=audio 13598 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
96951515mS CMCallEvt: 0.14234.0 -1 BaseEP: NEW CMEndpoint f5b2f1dc TOTAL NOW=6 CALL_LIST=1
96951518mS SIP Tx: UDP 192.168.14.10:5060 -> 192.168.14.20:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.14.20:5060;branch=
From: "Francisco Braz" <sip:801@192.168.14.20>;ta
To: <sip:94162312309@192.168.1
Call-ID: 159589f5207126ef11b8f3f119
CSeq: 102 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0
96951519mS SIP Tx: UDP 192.168.14.10:5060 -> 192.168.14.20:5060
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 192.168.14.20:5060;branch=
From: "Francisco Braz" <sip:801@192.168.14.20>;ta
To: <sip:94162312309@192.168.1
Call-ID: 159589f5207126ef11b8f3f119
CSeq: 102 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0
96951521mS SIP Rx: UDP 192.168.14.20:5060 -> 192.168.14.10:5060
ACK sip:94162312309@192.168.14
Via: SIP/2.0/UDP 192.168.14.20:5060;branch=
Max-Forwards: 70
From: "Francisco Braz" <sip:801@192.168.14.20>;ta
To: <sip:94162312309@192.168.1
Contact: <sip:801@192.168.14.20>
Call-ID: 159589f5207126ef11b8f3f119
CSeq: 102 ACK
User-Agent: Asterisk PBX 1.6.0.26-FONCORE-r78
Content-Length: 0
Any help would be much appreciated.
Thank you.
PSTN access is behind the IP Office. The problem is when I want to make PSTN outbound calls from any Asterisk extension via the IP Office.
I see the request arriving in the IP Office but the IP Office search a user and do not try to use the ARS. Because the user does not exist the IP Office rejects the call and I get a congestion signal on the Asterisk.
All extensions must dial 9 for PSTN calls.
Asterisk has a Outbound route with Dial Pattern 9. sending the call to the Avaya trunk.
IP Office has the SIP line with Incoming Group 180.
IP Office has an Incoming Call Group with Line Group ID 180 and Destination "."
IP Office has a Short Code 9N, feature Dial Speech, Telephone Number N, Line Group ID 50. That is the same short code that all Avaya extensions use to place outbound PSTN calls and works perfectly.
IP Office has an ARS route ID 50 that places the call.
This is the Asterisk log for the call:
[Sep 9 10:03:16] VERBOSE[3200] logger.c: == Using SIP RTP TOS bits 184
[Sep 9 10:03:16] VERBOSE[3200] logger.c: == Using SIP RTP CoS mark 5
[Sep 9 10:03:16] VERBOSE[3200] logger.c: == Using SIP VRTP TOS bits 136
[Sep 9 10:03:16] VERBOSE[3200] logger.c: == Using SIP VRTP CoS mark 6
[Sep 9 10:03:16] DEBUG[3153] pbx.c: FONALITY: This thread has already held the conlock, skip locking
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/801-00000016", "AMPUSER=801") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/801-00000016", "AMPUSER=801") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/801-00000016", "AMPUSERCIDNAME=Francisco Braz") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/801-00000016", "AMPUSERCID=801") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/801-00000016", "CALLERID(all)="Francisco Braz" <801>") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:9] ExecIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:10]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-user-callerid,s,19)
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-user-callerid:19]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-record-enable,s,4)
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/801-00000016", "recordingcheck,20100909-1
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/
[Sep 9 10:03:16] VERBOSE[11683] logger.c: recordingcheck,20100909-10
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- <SIP/801-00000016>AGI Script recordingcheck completed, returning 0
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/801-0000001
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/801-00000016", "DIAL_TRUNK=1") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/801-00000016"
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/801-00000016", "DIAL_NUMBER=94162312309")
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/801-00000016", "DIAL_TRUNK_OPTIONS=tr") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/801-00000016", "OUTBOUND_GROUP=OUT_1") in new stack
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/801-00000016",
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:10]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:11]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-outbound-callerid,s
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-outbound-callerid,s
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outbound-callerid
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:12]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:13]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:14]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:15]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:16]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk-pre
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:17]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:18]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:19]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Using SIP RTP TOS bits 184
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Using SIP RTP CoS mark 5
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Using SIP VRTP TOS bits 136
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Using SIP VRTP CoS mark 6
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Called avaya/94162312309
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- SIP/avaya-00000017 is circuit-busy
[Sep 9 10:03:16] VERBOSE[11683] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-dialout-trunk:20]
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-dialout-trunk,s-CON
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s-CONGESTION@macro-dialou
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Goto (macro-dialout-trunk,s-CON
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s-CONGESTION@macro-dialou
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [94162312309@from-internal
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- Executing [s@macro-outisbusy:1] Playback("SIP/801-00000016
[Sep 9 10:03:16] VERBOSE[11683] logger.c: -- <SIP/801-00000016> Playing 'all-circuits-busy-now.ula
[Sep 9 10:03:16] WARNING[11683] rtp.c: RTP Read too short
[Sep 9 10:03:18] VERBOSE[11683] logger.c: -- Executing [s@macro-outisbusy:2] Playback("SIP/801-00000016
[Sep 9 10:03:18] VERBOSE[11683] logger.c: -- <SIP/801-00000016> Playing 'pls-try-call-later.ulaw' (language 'en')
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-outisbusy:3] Macro("SIP/801-00000016", "hangupcall") in new stack
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,4)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,7)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,9)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/801-00000016' in macro 'hangupcall'
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (macro-outisbusy, s, 3) exited non-zero on 'SIP/801-00000016' in macro 'outisbusy'
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (from-internal, 94162312309, 6) exited non-zero on 'SIP/801-00000016'
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [h@from-internal:1] Macro("SIP/801-00000016", "hangupcall") in new stack
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,4)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,7)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Goto (macro-hangupcall,s,9)
[Sep 9 10:03:20] VERBOSE[11683] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/801-00000016",
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/801-00000016' in macro 'hangupcall'
[Sep 9 10:03:20] VERBOSE[11683] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/801-00000016'
[Sep 9 10:03:20] DEBUG[3153] pbx.c: FONALITY: This thread has already held the conlock, skip locking
This is the Avaya monitor log for the call:
96951512mS SIP Rx: UDP 192.168.14.20:5060 -> 192.168.14.10:5060
INVITE sip:94162312309@192.168.14
Via: SIP/2.0/UDP 192.168.14.20:5060;branch=
Max-Forwards: 70
From: "Francisco Braz" <sip:801@192.168.14.20>;ta
To: <sip:94162312309@192.168.1
Contact: <sip:801@192.168.14.20>
Call-ID: 159589f5207126ef11b8f3f119
CSeq: 102 INVITE
User-Agent: Asterisk PBX 1.6.0.26-FONCORE-r78
Date: Thu, 09 Sep 2010 13:15:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 278
v=0
o=root 1972484822 1972484822 IN IP4 192.168.14.20
s=Asterisk PBX 1.6.0.26-FONCORE-r78
c=IN IP4 192.168.14.20
t=0 0
m=audio 13598 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
96951515mS CMCallEvt: 0.14234.0 -1 BaseEP: NEW CMEndpoint f5b2f1dc TOTAL NOW=6 CALL_LIST=1
96951518mS SIP Tx: UDP 192.168.14.10:5060 -> 192.168.14.20:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.14.20:5060;branch=
From: "Francisco Braz" <sip:801@192.168.14.20>;ta
To: <sip:94162312309@192.168.1
Call-ID: 159589f5207126ef11b8f3f119
CSeq: 102 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0
96951519mS SIP Tx: UDP 192.168.14.10:5060 -> 192.168.14.20:5060
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 192.168.14.20:5060;branch=
From: "Francisco Braz" <sip:801@192.168.14.20>;ta
To: <sip:94162312309@192.168.1
Call-ID: 159589f5207126ef11b8f3f119
CSeq: 102 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Content-Length: 0
96951521mS SIP Rx: UDP 192.168.14.20:5060 -> 192.168.14.10:5060
ACK sip:94162312309@192.168.14
Via: SIP/2.0/UDP 192.168.14.20:5060;branch=
Max-Forwards: 70
From: "Francisco Braz" <sip:801@192.168.14.20>;ta
To: <sip:94162312309@192.168.1
Contact: <sip:801@192.168.14.20>
Call-ID: 159589f5207126ef11b8f3f119
CSeq: 102 ACK
User-Agent: Asterisk PBX 1.6.0.26-FONCORE-r78
Content-Length: 0
Any help would be much appreciated.
Thank you.
IP TelephonyVoice Over IP
Last Comment
Alex Bahar
ASKER
I agree that is the problem: IP Office does not know what to do with 94162312309.
As far as I know I cannot define number plans for a specific trunk. What I did is:
- defined a Incoming Call Route with ID matching the Incoming Group ID set at the SIP URI tab for the SIP line;
- this Incoming Call Route has the Destination set to ".". I understand the "." will pass whatever number is received. This works fine for any extension;
- IP Office will decide what to do with the number based on the Short Codes and ARS.
- There is one Short Code for "9N", striping the "9" ans sending "N" to ARS.
- ARS dials the number using its rules.
Please correct me if the process is not right.
Seems that something is missing and the number is not getting to the Short Codes or ARS evaluation.
As far as I know I cannot define number plans for a specific trunk. What I did is:
- defined a Incoming Call Route with ID matching the Incoming Group ID set at the SIP URI tab for the SIP line;
- this Incoming Call Route has the Destination set to ".". I understand the "." will pass whatever number is received. This works fine for any extension;
- IP Office will decide what to do with the number based on the Short Codes and ARS.
- There is one Short Code for "9N", striping the "9" ans sending "N" to ARS.
- ARS dials the number using its rules.
Please correct me if the process is not right.
Seems that something is missing and the number is not getting to the Short Codes or ARS evaluation.
It looks good to me. I assume you have defined the * entry to match all incoming numbers from the SIP line group. Is this correct? Check the incoming call routing example>
http://www.carrollcommunications.com/manager1/incallrouteover.html
http://www.carrollcommunications.com/manager1/incallrouteover.html
ASKER
Thanks for the hint abahar.
Actually I had the Incoming Number field blank on the ICR for the SIP trunk because Avaya help says: "A blank entry matches all calls that do not match other entries".
I tried to put the * there but no luck. IP Office still tries to find a user to match the 94162312309 number and does not find it.
Actually I had the Incoming Number field blank on the ICR for the SIP trunk because Avaya help says: "A blank entry matches all calls that do not match other entries".
I tried to put the * there but no luck. IP Office still tries to find a user to match the 94162312309 number and does not find it.
SOLUTION
THIS SOLUTION ONLY AVAILABLE TO MEMBERS.
View this solution by signing up for a free trial.
Members can start a 7-Day free trial and enjoy unlimited access to the platform.
ASKER
So I should have a line short code for the sip line. The question is how to create it for PSTN outgoing calls in tandem with the SIP line?
I have a short code that matches the Asterisk extensions number plan (8XX/N/sip line). It works fine. If a user dials 801, for example, Avaya uses the sip line to place the call to the Asterisk.
When Asterisk sends the call to Avaya, if it is an existing extension Avays finds it and places the call. If it is a 9N number it only tries to find a user and does not use the existing short code for 9N that sends the call to the ARS.
I tried to create a short code "sip:"9N"@192.168.14.10" sending to the ARS but I get the same error.
The piece that is missing in this puzzle is how to tell Avaya to use the short code 9N (or any other that matches) when is receives the call from the Asterisk via the SIP line.
I have a short code that matches the Asterisk extensions number plan (8XX/N/sip line). It works fine. If a user dials 801, for example, Avaya uses the sip line to place the call to the Asterisk.
When Asterisk sends the call to Avaya, if it is an existing extension Avays finds it and places the call. If it is a 9N number it only tries to find a user and does not use the existing short code for 9N that sends the call to the ARS.
I tried to create a short code "sip:"9N"@192.168.14.10" sending to the ARS but I get the same error.
The piece that is missing in this puzzle is how to tell Avaya to use the short code 9N (or any other that matches) when is receives the call from the Asterisk via the SIP line.
ASKER CERTIFIED SOLUTION
THIS SOLUTION ONLY AVAILABLE TO MEMBERS.
View this solution by signing up for a free trial.
Members can start a 7-Day free trial and enjoy unlimited access to the platform.
I am glad using "*" got it working.
Check you number plan for calls coming from the SIP trunk. Is there a matching pattern for 94162312309 ? Does it strip the digit "9" before it dials "4162312309" to the PSTN?