Got outgoing working but get busy tone on incoming

nojstevens · June 6, 2009, 1:20pm

Hello,

After two days of frustration, I finally got my Broadvoice trunk registered, and I can make outgoing calls from my extensions.

I cannot receive incoming calls, however. I just get a fast busy tone.

When I look in the Asterisk log I can see a comment that says call from “MYBROADVOICEDID” to “MYBROADVOICEDID” rejected because extension not found

This tells me that the Broadvoice server is sending the call and it is getting through to my system, I guess it is not being accepted.

Could someone help me troubleshoot this? I feel I am almost there, but this last bit has me stumped.

Many thanks

Jon

AndrewZ · June 6, 2009, 8:43pm

Show us:

how you configured the trunk
how you’re registering with this provider
incoming SIP INVITE message you’re receiving from this provider
how you configured Inbound Route for that DID

nojstevens · June 6, 2009, 10:34pm

Thankyou AndrewZ

My trunk settings are

DIal Rules=1|NXXNXXXXXX

Trunk Name= sip.broadvoice.com

PEER Details:

port=5060 ; Port to bind to (SIP is 5060)
bindaddr=0.0.0.0 ; Address to bind to (all addresses on machine)
disallow=all
allow=ulaw
context=default ; Added to Test&from-broadvoice
srvlookup=yes ; Added to Test
pedantic=no ; Added to Test
type=peer
user=phone
host=sip.broadvoice.com
fromdomain=sip.broadvoice.com
fromuser=NNNNNNNNNN
secret=PPPPPPPP
username=NNNNNNNNNN
insecure=very
authname=NNNNNNNNNN
dtmfmode=inband
dtmf=inband
canreinvite=no

User Context and User details are Blank

Register String
[email protected]:PPPPPPPPPP:[email protected]/201

My only Inbound Route is any incoming DID (ie blank) is set to ring extension 201

Log details from Asterisk Log

[2009-06-06 17:06:34] VERBOSE[2969] logger.c: — (11 headers 14 lines) —
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Sending to 206.15.136.221 : 5060 (NAT)
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Using INVITE request as basis request - [email protected]
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found peer ‘sip.broadvoice.com’
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found RTP audio format 0
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found RTP audio format 18
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found RTP audio format 8
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found RTP audio format 96
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found RTP audio format 97
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found RTP audio format 101
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Peer audio RTP is at port 206.15.136.250:33746
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found audio description format PCMU for ID 0
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found audio description format G729 for ID 18
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found audio description format PCMA for ID 8
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found audio description format iLBC for ID 96
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found unknown media description format t38 for ID 97
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Found audio description format telephone-event for ID 101
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x50c (ulaw|alaw|g729|ilbc)/video=0x0 (nothing), combined - 0x4 (ulaw)
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Non-codec capabilities (dtmf): us - 0x0 (nothing), peer - 0x1 (telephone-event), combined - 0x0 (nothing)
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Peer audio RTP is at port 206.15.136.250:33746
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Looking for 8593848714 in from-broadvoice (domain 96.28.43.39)
[2009-06-06 17:06:34] VERBOSE[2969] logger.c:
<— Reliably Transmitting (NAT) to 206.15.136.221:5060 —>
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 206.15.136.221:5060;received=206.15.136.221
From: "Covington KY"sip:[email protected];user=phone;tag=nopq
To: "Jon Stevens"sip:[email protected];tag=as087d935f
Call-ID: [email protected]
CSeq: 1 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0

<------------>
[2009-06-06 17:06:34] NOTICE[2969] chan_sip.c: Call from ‘8593848714’ to extension ‘8593848714’ rejected because extension not found.
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Scheduling destruction of SIP dialog ‘[email protected]’ in 32000 ms (Method: INVITE)
[2009-06-06 17:06:34] VERBOSE[2969] logger.c:
<— SIP read from 206.15.136.221:5060 —>
ACK sip:[email protected]:5060 SIP/2.0
Call-ID: [email protected]
CSeq: 1 ACK
From: "Covington KY"sip:[email protected];user=phone;tag=nopq
To: "Jon Stevens"sip:[email protected];tag=as087d935f
Via: SIP/2.0/UDP 206.15.136.221:5060
Content-Length: 0

<------------->
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: — (7 headers 0 lines) —
[2009-06-06 17:06:34] VERBOSE[2969] logger.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2009-06-06 17:06:34] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.21:5060 —>

<------------->
[2009-06-06 17:06:42] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.21:5060 —>

<------------->
[2009-06-06 17:06:43] VERBOSE[3310] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [2009-06-06 17:06:43] VERBOSE[3310] logger.c: Found
[2009-06-06 17:06:43] VERBOSE[3310] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [2009-06-06 17:06:43] VERBOSE[3310] logger.c: Found
[2009-06-06 17:06:43] VERBOSE[3310] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [2009-06-06 17:06:43] VERBOSE[3310] logger.c: Found
[2009-06-06 17:06:43] VERBOSE[3310] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[2009-06-06 17:06:45] NOTICE[2969] chan_sip.c: – Re-registration for [email protected]@sip.broadvoice.com
[2009-06-06 17:06:45] DEBUG[2969] chan_sip.c: >>> Re-using Auth data for [email protected]@sip.broadvoice.com
[2009-06-06 17:06:45] VERBOSE[2969] logger.c: REGISTER 13 headers, 0 lines
[2009-06-06 17:06:45] VERBOSE[2969] logger.c: Reliably Transmitting (NAT) to 206.15.136.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 96.28.43.39:5060;branch=z9hG4bK31a4e5e6;rport
From: sip:[email protected];tag=as5da9770d
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 319 REGISTER
User-Agent: Asterisk PBX
Max-Forwards: 70
Authorization: Digest username=“8593848714”, realm=“BroadWorks”, algorithm=MD5, uri=“sip:sip.broadvoice.com”, nonce=“BroadWorksXfvmqc4a9T8zrov7BW”, response=“8de57008653201f7161f57929ecdd281”, qop=auth, cnonce=“1c1e6da8”, nc=000000d9
Expires: 120
Contact: sip:[email protected]
Event: registration
Content-Length: 0

[2009-06-06 17:06:45] VERBOSE[2969] logger.c:
<— SIP read from 206.15.136.221:5060 —>
SIP/2.0 200 OK
Call-ID: [email protected]
CSeq: 319 REGISTER
From: sip:[email protected];tag=as5da9770d
To: sip:[email protected]
Via: SIP/2.0/UDP 96.28.43.39:5060;branch=z9hG4bK31a4e5e6
Contact: sip:[email protected]
Expires: 30
Event: registration
Content-Length: 0

<------------->
[2009-06-06 17:06:45] VERBOSE[2969] logger.c: — (10 headers 0 lines) —
[2009-06-06 17:06:45] VERBOSE[2969] logger.c: Scheduling destruction of SIP dialog ‘[email protected]’ in 32000 ms (Method: REGISTER)
[2009-06-06 17:06:45] NOTICE[2969] chan_sip.c: Outbound Registration: Expiry for sip.broadvoice.com is 30 sec (Scheduling reregistration in 23 s)
[2009-06-06 17:06:46] VERBOSE[3310] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[2009-06-06 17:06:47] VERBOSE[3313] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [2009-06-06 17:06:47] VERBOSE[3313] logger.c: Found
[2009-06-06 17:06:47] VERBOSE[3313] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [2009-06-06 17:06:47] VERBOSE[3313] logger.c: Found
[2009-06-06 17:06:47] VERBOSE[3313] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [2009-06-06 17:06:47] VERBOSE[3313] logger.c: Found
[2009-06-06 17:06:47] VERBOSE[3313] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[2009-06-06 17:06:49] VERBOSE[3313] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[2009-06-06 17:06:51] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.21:5060 —>

<------------->
[2009-06-06 17:06:53] VERBOSE[2969] logger.c: Really destroying SIP dialog ‘[email protected]’ Method: REGISTER
[2009-06-06 17:06:58] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.21:5060 —>

<------------->
[2009-06-06 17:07:03] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.21:5060 —>

<------------->
[2009-06-06 17:07:09] NOTICE[2969] chan_sip.c: – Re-registration for [email protected]@sip.broadvoice.com
[2009-06-06 17:07:09] DEBUG[2969] chan_sip.c: >>> Re-using Auth data for [email protected]@sip.broadvoice.com
[2009-06-06 17:07:09] VERBOSE[2969] logger.c: REGISTER 13 headers, 0 lines
[2009-06-06 17:07:09] VERBOSE[2969] logger.c: Reliably Transmitting (NAT) to 206.15.136.221:5060:
REGISTER sip:sip.broadvoice.com SIP/2.0
Via: SIP/2.0/UDP 96.28.43.39:5060;branch=z9hG4bK4be1a8ae;rport
From: sip:[email protected];tag=as2a34cd48
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 320 REGISTER
User-Agent: Asterisk PBX
Max-Forwards: 70
Authorization: Digest username=“8593848714”, realm=“BroadWorks”, algorithm=MD5, uri=“sip:sip.broadvoice.com”, nonce=“BroadWorksXfvmqc4a9T8zrov7BW”, response=“8fc014653549c03ccd633e974ac87d1e”, qop=auth, cnonce=“17400bc0”, nc=000000da
Expires: 120
Contact: sip:[email protected]
Event: registration
Content-Length: 0

[2009-06-06 17:07:09] VERBOSE[2969] logger.c:
<— SIP read from 206.15.136.221:5060 —>
SIP/2.0 200 OK
Call-ID: [email protected]
CSeq: 320 REGISTER
From: sip:[email protected];tag=as2a34cd48
To: sip:[email protected]
Via: SIP/2.0/UDP 96.28.43.39:5060;branch=z9hG4bK4be1a8ae
Contact: sip:[email protected]
Expires: 30
Event: registration
Content-Length: 0

<------------->
[2009-06-06 17:07:09] VERBOSE[2969] logger.c: — (10 headers 0 lines) —
[2009-06-06 17:07:09] VERBOSE[2969] logger.c: Scheduling destruction of SIP dialog ‘[email protected]’ in 32000 ms (Method: REGISTER)
[2009-06-06 17:07:09] NOTICE[2969] chan_sip.c: Outbound Registration: Expiry for sip.broadvoice.com is 30 sec (Scheduling reregistration in 23 s)
[2009-06-06 17:07:10] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.21:5060 —>

<------------->
[2009-06-06 17:07:14] VERBOSE[2969] logger.c: Reliably Transmitting (NAT) to 10.0.1.21:5060:
OPTIONS sip:[email protected]:5060;LINEID=f290fee176c1 SIP/2.0
Via: SIP/2.0/UDP 10.0.1.22:5060;branch=z9hG4bK374b55f9;rport
From: “Unknown” sip:[email protected];tag=as4386b6bb
To: sip:[email protected]:5060;LINEID=f290fee176c1
Contact: sip:[email protected]
Call-ID: [email protected]
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Sat, 06 Jun 2009 21:07:14 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0

[2009-06-06 17:07:14] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.21:5060 —>
SIP/2.0 200 OK
From: “Unknown” sip:[email protected];tag=as4386b6bb
To: sip:[email protected]:5060;LINEID=f290fee176c1
Call-Id: [email protected]
Cseq: 102 OPTIONS
Via: SIP/2.0/UDP 10.0.1.22:5060;branch=z9hG4bK374b55f9;rport=5060
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, PING, INFO, MESSAGE, REGISTER, NOTIFY
Supported: replaces
Contact: sip:10.0.1.21
Content-Length: 0

<------------->
[2009-06-06 17:07:14] VERBOSE[2969] logger.c: — (10 headers 0 lines) —
[2009-06-06 17:07:14] VERBOSE[2969] logger.c: Really destroying SIP dialog ‘[email protected]’ Method: OPTIONS
[2009-06-06 17:07:16] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.21:5060 —>

<------------->
[2009-06-06 17:07:17] VERBOSE[2969] logger.c: Really destroying SIP dialog ‘[email protected]’ Method: REGISTER
[2009-06-06 17:07:22] VERBOSE[3318] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [2009-06-06 17:07:22] VERBOSE[3318] logger.c: Found
[2009-06-06 17:07:22] VERBOSE[3318] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [2009-06-06 17:07:22] VERBOSE[3318] logger.c: Found
[2009-06-06 17:07:22] VERBOSE[3318] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [2009-06-06 17:07:22] VERBOSE[3318] logger.c: Found
[2009-06-06 17:07:22] VERBOSE[3318] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[2009-06-06 17:07:23] VERBOSE[2969] logger.c: Reliably Transmitting (NAT) to 10.0.1.105:4186:
OPTIONS sip:[email protected]:4186;rinstance=8863d1a882ec70d6 SIP/2.0
Via: SIP/2.0/UDP 10.0.1.22:5060;branch=z9hG4bK3ba91460;rport
From: “Unknown” sip:[email protected];tag=as0afb768c
To: sip:[email protected]:4186;rinstance=8863d1a882ec70d6
Contact: sip:[email protected]
Call-ID: [email protected]
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Sat, 06 Jun 2009 21:07:23 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Content-Length: 0

[2009-06-06 17:07:23] VERBOSE[2969] logger.c:
<— SIP read from 10.0.1.105:4186 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.1.22:5060;branch=z9hG4bK3ba91460;rport=5060
Contact: sip:10.0.1.105:4186
To: sip:[email protected]:4186;rinstance=8863d1a882ec70d6;tag=a127004f
From: "Unknown"sip:[email protected];tag=as0afb768c
Call-ID: [email protected]
CSeq: 102 OPTIONS
Accept: application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
User-Agent: X-Lite release 1014k stamp 47051
Content-Length: 0

<------------->
[2009-06-06 17:07:23] VERBOSE[2969] logger.c: — (12 headers 0 lines) —
[2009-06-06 17:07:23] VERBOSE[2969] logger.c: Really destroying SIP dialog ‘[email protected]’ Method: OPTIONS

AndrewZ · June 6, 2009, 10:47pm

First, set “context=from-trunk” in the PEER Details and remove all the unnecessary settings you put everywhere.

nojstevens · June 6, 2009, 10:52pm

After spending all day reading every google link I could find on Broadvoice, and getting nowhere, you have got me working first time.

I am very very grateful - I hate it when I can’t get things to work - my weekend just got much better.

Thanks AndrewZ

Jon