I have a new ViciBox in implementation phase right now. I have it setup (as far as I can tell) and have had a Vici consultant look at it quickly yesterday as well and he said everything looks good.
In testing last night I brought the system up and tried to make an outbound call but no go. Details of my system are in my sig line. The sip debug log I got via putty is kind of long but here is the relevent section (I think).
Any thoughts on what could be wrong?
Here is the sip debug:
[Kviciexp*CLI>
[Dec 16 08:18:35]
<--- SIP read from 66.51.127.173:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 216.###.###.###:5060;branch=z9hG4bK5b9639f4;rport=5060
From: "asterisk" <sip:asterisk@216.###.###.###>;tag=as7f839d1b
To: <sip:sip.ca1.link2voip.com;cpd=on>;tag=6da5cb3c58ecfc1b91772f44357856fa.8d3f
Call-ID: 306261ca2c9cb202326ac3463b035238@216.###.###.###
CSeq: 102 OPTIONS
Accept: */*
Accept-Encoding:
Accept-Language: en
Supported:
Content-Length: 0
<------------->
[Kviciexp*CLI>
[Dec 16 08:18:35] --- (11 headers 0 lines) ---
[Kviciexp*CLI>
[Dec 16 08:18:35] Really destroying SIP dialog '306261ca2c9cb202326ac3463b035238@216.###.###.###' Method: OPTIONS
[Kviciexp*CLI>
[Dec 16 08:18:39]
<--- SIP read from 192.168.3.148:13624 --->
INVITE sip:91519#######@192.168.3.9 SIP/2.0
Via: SIP/2.0/UDP 192.168.3.148:13624;branch=z9hG4bK-d87543-3f6ac7599448ad53-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:226@192.168.3.148:13624>
To: "91519#######"<sip:91519#######@192.168.3.9>
From: "Barb Thompson"<sip:226@192.168.3.9>;tag=0321500d
Call-ID: 4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
User-Agent: eyeBeam release 1003s stamp 31159
Content-Length: 546
v=0
o=- 9 2 IN IP4 192.168.3.148
s=CounterPath eyeBeam 1.5
c=IN IP4 192.168.3.148
t=0 0
m=audio 11604 RTP/AVP 107 100 106 6 0 105 18 3 5 101
a=alt:1 3 : mbHkWJwC hEL2+p/T 192.168.3.148 11604
a=alt:2 2 : nIZmOOtf 6KAUzHcL 192.168.75.1 11604
a=alt:3 1 : bFg1x43s 6LsuDJ9M 192.168.93.1 11604
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
a=x-rtp-session-id:A6CFDDC8A920404D96B33964A0BF5DF2
<------------->
[Kviciexp*CLI>
[Dec 16 08:18:39] --- (12 headers 18 lines) ---
[Dec 16 08:18:39] Sending to 192.168.3.148 : 13624 (NAT)
[Kviciexp*CLI>
[Dec 16 08:18:39] Using INVITE request as basis request - 4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.
[Dec 16 08:18:39]
<--- Reliably Transmitting (NAT) to 192.168.3.148:13624 --->
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 192.168.3.148:13624;branch=z9hG4bK-d87543-3f6ac7599448ad53-1--d87543-;received=192.168.3.148;rport=13624
From: "Barb Thompson"<sip:226@192.168.3.9>;tag=0321500d
To: "91519#######"<sip:91519#######@192.168.3.9>;tag=as783cdf10
Call-ID: 4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.
CSeq: 1 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces
Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="0375589e"
Content-Length: 0
<------------>
[Dec 16 08:18:39] Scheduling destruction of SIP dialog '4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.' in 32000 ms (Method: INVITE)
[Dec 16 08:18:39] Found user '226'
[Kviciexp*CLI>
[Dec 16 08:18:39]
<--- SIP read from 192.168.3.148:13624 --->
ACK sip:91519#######@192.168.3.9 SIP/2.0
Via: SIP/2.0/UDP 192.168.3.148:13624;branch=z9hG4bK-d87543-3f6ac7599448ad53-1--d87543-;rport
To: "91519#######"<sip:91519#######@192.168.3.9>;tag=as783cdf10
From: "Barb Thompson"<sip:226@192.168.3.9>;tag=0321500d
Call-ID: 4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.
CSeq: 1 ACK
Content-Length: 0
<------------->
[Kviciexp*CLI>
[Dec 16 08:18:39] --- (7 headers 0 lines) ---
[Kviciexp*CLI>
[Dec 16 08:18:40]
<--- SIP read from 192.168.3.148:13624 --->
INVITE sip:91519#######@192.168.3.9 SIP/2.0
Via: SIP/2.0/UDP 192.168.3.148:13624;branch=z9hG4bK-d87543-b10eb573282fd177-1--d87543-;rport
Max-Forwards: 70
Contact: <sip:226@192.168.3.148:13624>
To: "91519#######"<sip:91519#######@192.168.3.9>
From: "Barb Thompson"<sip:226@192.168.3.9>;tag=0321500d
Call-ID: 4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
Content-Type: application/sdp
Proxy-Authorization: Digest username="226",realm="asterisk",nonce="0375589e",uri="sip:91519#######@192.168.3.9",response="672c2e4f5ee49810f2edf8babd74e111",algorithm=MD5
User-Agent: eyeBeam release 1003s stamp 31159
Content-Length: 546
v=0
o=- 9 2 IN IP4 192.168.3.148
s=CounterPath eyeBeam 1.5
c=IN IP4 192.168.3.148
t=0 0
m=audio 11604 RTP/AVP 107 100 106 6 0 105 18 3 5 101
a=alt:1 3 : mbHkWJwC hEL2+p/T 192.168.3.148 11604
a=alt:2 2 : nIZmOOtf 6KAUzHcL 192.168.75.1 11604
a=alt:3 1 : bFg1x43s 6LsuDJ9M 192.168.93.1 11604
a=fmtp:18 annexb=no
a=fmtp:101 0-15
a=rtpmap:107 BV32/16000
a=rtpmap:100 SPEEX/16000
a=rtpmap:106 SPEEX-FEC/16000
a=rtpmap:105 SPEEX-FEC/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
a=x-rtp-session-id:A6CFDDC8A920404D96B33964A0BF5DF2
<------------->
[Kviciexp*CLI>
[Dec 16 08:18:40] --- (13 headers 18 lines) ---
[Dec 16 08:18:40] Sending to 192.168.3.148 : 13624 (NAT)
[Kviciexp*CLI>
[Dec 16 08:18:40] Using INVITE request as basis request - 4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.
[Dec 16 08:18:40] Found user '226'
[Dec 16 08:18:40] Found RTP audio format 107
[Kviciexp*CLI>
[Dec 16 08:18:40] Found RTP audio format 100
[Dec 16 08:18:40] Found RTP audio format 106
[Dec 16 08:18:40] Found RTP audio format 6
[Dec 16 08:18:40] Found RTP audio format 0
[Dec 16 08:18:40] Found RTP audio format 105
[Dec 16 08:18:40] Found RTP audio format 18
[Dec 16 08:18:40] Found RTP audio format 3
[Dec 16 08:18:40] Found RTP audio format 5
[Dec 16 08:18:40] Found RTP audio format 101
[Dec 16 08:18:40] Found unknown media description format BV32 for ID 107
[Dec 16 08:18:40] Found audio description format SPEEX for ID 100
[Dec 16 08:18:40] Found unknown media description format SPEEX-FEC for ID 106
[Dec 16 08:18:40] Found unknown media description format SPEEX-FEC for ID 105
[Dec 16 08:18:40] Found audio description format telephone-event for ID 101
[Dec 16 08:18:40] Capabilities: us - 0x6 (gsm|ulaw), peer - audio=0x326 (gsm|ulaw|adpcm|g729|speex)/video=0x0 (nothing), combined - 0x6 (gsm|ulaw)
[Dec 16 08:18:40] Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
[Dec 16 08:18:40] Peer audio RTP is at port 192.168.3.148:11604
[Dec 16 08:18:40] Looking for 91519####### in default (domain 192.168.3.9)
[Dec 16 08:18:40] list_route: hop: <sip:226@192.168.3.148:13624>
[Dec 16 08:18:40]
<--- Transmitting (NAT) to 192.168.3.148:13624 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.3.148:13624;branch=z9hG4bK-d87543-b10eb573282fd177-1--d87543-;received=192.168.3.148;rport=13624
From: "Barb Thompson"<sip:226@192.168.3.9>;tag=0321500d
To: "91519#######"<sip:91519#######@192.168.3.9>
Call-ID: 4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces
Contact: <sip:91519#######@192.168.3.9>
Content-Length: 0
<------------>
[Kviciexp*CLI>
[Dec 16 08:18:40] -- Executing [91519#######@default:1] [1;36;40mAGI[0;37;40m("[1;35;40mSIP/226-00000015[0;37;40m", "[1;35;40magi://127.0.0.1:4577/call_log[0;37;40m") in new stack
[Kviciexp*CLI>
[Dec 16 08:18:40] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Kviciexp*CLI>
[Dec 16 08:18:40] -- Executing [91519#######@default:2] [1;36;40mDial[0;37;40m("[1;35;40mSIP/226-00000015[0;37;40m", "[1;35;40mSIP/acelink2voip/$(EXTEN:1)||tTor[0;37;40m") in new stack
[Kviciexp*CLI>
[Dec 16 08:18:40] Audio is at 216.###.###.### port 17446
[Kviciexp*CLI>
[Dec 16 08:18:40] Adding codec 0x4 (ulaw) to SDP
[Kviciexp*CLI>
[Dec 16 08:18:40] Adding non-codec 0x1 (telephone-event) to SDP
[Kviciexp*CLI>
[Dec 16 08:18:40] Reliably Transmitting (NAT) to 66.51.127.173:5060:
INVITE sip:$(EXTEN:1)@sip.ca1.link2voip.com;cpd=on SIP/2.0
Via: SIP/2.0/UDP 216.###.###.###:5060;branch=z9hG4bK04c52c59;rport
From: "BARB THOMPSON" <sip:5197726813@216.###.###.###>;tag=as1798b565
To: <sip:$(EXTEN:1)@sip.ca1.link2voip.com;cpd=on>
Contact: <sip:5197726813@216.###.###.###>
Call-ID: 33b0f89d497510fc7945c9951b790484@216.###.###.###
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "BARB THOMPSON" <sip:5197726813@216.###.###.###>;privacy=off;screen=no
Date: Thu, 16 Dec 2010 13:18:40 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces
Content-Type: application/sdp
Content-Length: 239
v=0
o=root 2543 2543 IN IP4 216.###.###.###
s=session
c=IN IP4 216.###.###.###
t=0 0
m=audio 17446 RTP/AVP 0 96
a=rtpmap:0 PCMU/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
---
[Kviciexp*CLI>
[Dec 16 08:18:40] -- Called acelink2voip/$(EXTEN:1)
[Kviciexp*CLI>
[Dec 16 08:18:40]
<--- Transmitting (NAT) to 192.168.3.148:13624 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.3.148:13624;branch=z9hG4bK-d87543-b10eb573282fd177-1--d87543-;received=192.168.3.148;rport=13624
From: "Barb Thompson"<sip:226@192.168.3.9>;tag=0321500d
To: "91519#######"<sip:91519#######@192.168.3.9>;tag=as57de1692
Call-ID: 4f0b227f0b0b965cM2FiM2E2ZDNiMmU5MTg3MmM0YjNjY2ZjMGY1YzQyZjg.
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces
Contact: <sip:91519#######@192.168.3.9>
Content-Length: 0
<------------>
[Kviciexp*CLI>
[Dec 16 08:18:40]
<--- SIP read from 66.51.127.173:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 216.###.###.###:5060;branch=z9hG4bK04c52c59;rport=5060
From: "BARB THOMPSON" <sip:5197726813@216.###.###.###>;tag=as1798b565
To: <sip:$(EXTEN:1)@sip.ca1.link2voip.com;cpd=on>
Call-ID: 33b0f89d497510fc7945c9951b790484@216.###.###.###
CSeq: 102 INVITE
Content-Length: 0
<------------->
[Kviciexp*CLI>
[Dec 16 08:18:40] --- (7 headers 0 lines) ---
[Kviciexp*CLI>
[Dec 16 08:18:40]
<--- SIP read from 66.51.127.173:5060 --->
SIP/2.0 404 User Not Found
Via: SIP/2.0/UDP 216.###.###.###:5060;branch=z9hG4bK04c52c59;rport=5060
From: "BARB THOMPSON" <sip:5197726813@216.###.###.###>;tag=as1798b565
To: <sip:$(EXTEN:1)@sip.ca1.link2voip.com;cpd=on>;tag=6da5cb3c58ecfc1b91772f44357856fa.044f
Call-ID: 33b0f89d497510fc7945c9951b790484@216.###.###.###
CSeq: 102 INVITE
Content-Length: 0
<------------->
[Kviciexp*CLI>
[Dec 16 08:18:40] --- (7 headers 0 lines) ---
[Kviciexp*CLI>
[Dec 16 08:18:40] Transmitting (NAT) to 66.51.127.173:5060:
ACK sip:$(EXTEN:1)@sip.ca1.link2voip.com;cpd=on SIP/2.0
Via: SIP/2.0/UDP 216.###.###.###:5060;branch=z9hG4bK04c52c59;rport
From: "BARB THOMPSON" <sip:5197726813@216.###.###.###>;tag=as1798b565
To: <sip:$(EXTEN:1)@sip.ca1.link2voip.com;cpd=on>;tag=6da5cb3c58ecfc1b91772f44357856fa.044f
Contact: <sip:5197726813@216.###.###.###>
Call-ID: 33b0f89d497510fc7945c9951b790484@216.###.###.###
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "BARB THOMPSON" <sip:5197726813@216.###.###.###>;privacy=off;screen=no
Content-Length: 0
---
[Kviciexp*CLI>
[Dec 16 08:18:40] -- SIP/acelink2voip-00000016 is circuit-busy
[Kviciexp*CLI>
[Dec 16 08:18:40] == Everyone is busy/congested at this time (1:0/1/0)