One Phone Ringing Busy

Hi

I am totally new to Asterisks and pretty new to telephony, so please bare with me.

At our office we use a Asterisks core with a Elastix GUi and Polycom phones. All our normal SoundPoint IP phones 500’s and we have one P4000 in a conference room.
Problem is that from all other phones in the office we cannot call the P4000, it rings busy. I believe they all use the same configuration files. I suspect that it might be because there are 2 different models the configs might need ot be different.

I know might be a lot to ask, without understanding the system (but I plan on learning it soon), if anyone has seen this before or could suggest a fix.

Thank you

hi

I’m afraid I don’t have a solution for you but was wondering did you ever get this working? I have exactly the same situation, a single Polycom 4000 which can make call but rings busy when dialled?

thanks
Mike

You need to provide sip debugging information. It could be the state of the phone, which this will reveal, or you may be confusing a busy with unavailable. If you have allowguest=yes, default but not normally advisable, anyone can make calls, even if they don’t have a valid sip.conf entry.

thanks David

I thought it might be a specific tweak with Polycom’s so didn’t really include details, but here they are:

It does regsister with Asterisk successfully:

205/205 192.168.1.208 D N A 5060 OK (26 ms)

but when I try to dial the Polycom I get a busy signal and the log (I’m dialling from ext. 204 to 205) shows:

== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [205@from-internal:1] Macro(“SIP/204-0000014a”, “exten-vm,novm,205”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/204-0000014a”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/204-0000014a”, “AMPUSER=204”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/204-0000014a”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/204-0000014a”, “1?Set(REALCALLERIDNUM=204)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/204-0000014a”, “AMPUSER=204”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/204-0000014a”, “AMPUSERCIDNAME=MC”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/204-0000014a”, “0?report”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/204-0000014a”, “AMPUSERCID=204”) in new stack
– Executing [s@macro-user-callerid:8] Set(“SIP/204-0000014a”, “CALLERID(all)=“MC” <204>”) in new stack
– Executing [s@macro-user-callerid:9] ExecIf(“SIP/204-0000014a”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:10] GotoIf(“SIP/204-0000014a”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/204-0000014a”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/204-0000014a”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,19)
– Executing [s@macro-user-callerid:19] Set(“SIP/204-0000014a”, “CALLERID(number)=204”) in new stack
– Executing [s@macro-user-callerid:20] Set(“SIP/204-0000014a”, “CALLERID(name)=MC”) in new stack
– Executing [s@macro-user-callerid:21] NoOp(“SIP/204-0000014a”, “Using CallerID “MC” <204>”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/204-0000014a”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/204-0000014a”, “VMBOX=novm”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/204-0000014a”, “__EXTTOCALL=205”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/204-0000014a”, “CFUEXT=”) in new stack
– Executing [s@macro-exten-vm:6] Set(“SIP/204-0000014a”, “CFBEXT=”) in new stack
– Executing [s@macro-exten-vm:7] Set(“SIP/204-0000014a”, “RT=”"") in new stack
– Executing [s@macro-exten-vm:8] Macro(“SIP/204-0000014a”, “record-enable,205,IN”) in new stack
– Executing [s@macro-record-enable:1] GotoIf(“SIP/204-0000014a”, “1?check”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [s@macro-record-enable:4] ExecIf(“SIP/204-0000014a”, “0?MacroExit()”) in new stack
– Executing [s@macro-record-enable:5] GotoIf(“SIP/204-0000014a”, “0?Group:OUT”) in new stack
– Goto (macro-record-enable,s,15)
– Executing [s@macro-record-enable:15] GotoIf(“SIP/204-0000014a”, “1?IN”) in new stack
– Goto (macro-record-enable,s,20)
– Executing [s@macro-record-enable:20] ExecIf(“SIP/204-0000014a”, “1?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:9] Macro(“SIP/204-0000014a”, “dial-one,”",trwW,205") in new stack
– Executing [s@macro-dial-one:1] Set(“SIP/204-0000014a”, “DEXTEN=205”) in new stack
– Executing [s@macro-dial-one:2] Set(“SIP/204-0000014a”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“SIP/204-0000014a”, “0?screen,1”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“SIP/204-0000014a”, “0?cf,1”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“SIP/204-0000014a”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“SIP/204-0000014a”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“SIP/204-0000014a”, “0?continue”) in new stack
– Executing [s@macro-dial-one:10] Set(“SIP/204-0000014a”, “EXTHASCW=”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“SIP/204-0000014a”, “1?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,12)
– Executing [s@macro-dial-one:12] GotoIf(“SIP/204-0000014a”, “0?docfu:skip3”) in new stack
– Goto (macro-dial-one,s,16)
– Executing [s@macro-dial-one:16] GotoIf(“SIP/204-0000014a”, “1?next2:continue”) in new stack
– Goto (macro-dial-one,s,17)
– Executing [s@macro-dial-one:17] GotoIf(“SIP/204-0000014a”, “1?continue”) in new stack
– Goto (macro-dial-one,s,25)
– Executing [s@macro-dial-one:25] GotoIf(“SIP/204-0000014a”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“SIP/204-0000014a”, “1?dstring,1:dlocal,1”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“SIP/204-0000014a”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“SIP/204-0000014a”, “DEVICES=205”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“SIP/204-0000014a”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“SIP/204-0000014a”, “0?Set(DEVICES=05)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“SIP/204-0000014a”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“SIP/204-0000014a”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“SIP/204-0000014a”, “THISDIAL=SIP/205”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“SIP/204-0000014a”, “1?zap2dahdi,1”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/204-0000014a”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/204-0000014a”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/204-0000014a”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/204-0000014a”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/204-0000014a”, “THISPART2=SIP/205”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/204-0000014a”, “0?Set(THISPART2=DAHDI/205)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/204-0000014a”, “NEWDIAL=SIP/205&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/204-0000014a”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/204-0000014a”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/204-0000014a”, “THISDIAL=SIP/205”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/204-0000014a”, “”) in new stack
– Executing [dstring@macro-dial-one:9] Set(“SIP/204-0000014a”, “DSTRING=SIP/205&”) in new stack
– Executing [dstring@macro-dial-one:10] Set(“SIP/204-0000014a”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“SIP/204-0000014a”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:12] Set(“SIP/204-0000014a”, “DSTRING=SIP/205”) in new stack
– Executing [dstring@macro-dial-one:13] Return(“SIP/204-0000014a”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“SIP/204-0000014a”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“SIP/204-0000014a”, “1?skiptrace”) in new stack
– Goto (macro-dial-one,s,30)
– Executing [s@macro-dial-one:30] Set(“SIP/204-0000014a”, “D_OPTIONS=trwW”) in new stack
– Executing [s@macro-dial-one:31] ExecIf(“SIP/204-0000014a”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [s@macro-dial-one:32] ExecIf(“SIP/204-0000014a”, “0?SIPAddHeader()”) in new stack
– Executing [s@macro-dial-one:33] ExecIf(“SIP/204-0000014a”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:34] GosubIf(“SIP/204-0000014a”, “0?qwait,1”) in new stack
– Executing [s@macro-dial-one:35] Set(“SIP/204-0000014a”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:36] Set(“SIP/204-0000014a”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:37] Dial(“SIP/204-0000014a”, “SIP/205,”",trwW") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called 205
– SIP/205-0000014b is circuit-busy
== Everyone is busy/congested at this time (1:0/1/0)
– Executing [s@macro-dial-one:38] ExecIf(“SIP/204-0000014a”, “0?Set(DIALSTATUS=)”) in new stack
– Executing [s@macro-dial-one:39] GosubIf(“SIP/204-0000014a”, “0?s-CONGESTION,1”) in new stack
– Executing [s@macro-dial-one:40] MacroExit(“SIP/204-0000014a”, “”) in new stack
– Executing [s@macro-exten-vm:10] GotoIf(“SIP/204-0000014a”, “0?exit”) in new stack
– Executing [s@macro-exten-vm:11] Set(“SIP/204-0000014a”, “SV_DIALSTATUS=CONGESTION”) in new stack
– Executing [s@macro-exten-vm:12] GosubIf(“SIP/204-0000014a”, “0?docfu,1”) in new stack
– Executing [s@macro-exten-vm:13] GosubIf(“SIP/204-0000014a”, “0?docfb,1”) in new stack
– Executing [s@macro-exten-vm:14] Set(“SIP/204-0000014a”, “DIALSTATUS=CONGESTION”) in new stack
– Executing [s@macro-exten-vm:15] NoOp(“SIP/204-0000014a”, “Voicemail is ‘novm’”) in new stack
– Executing [s@macro-exten-vm:16] GotoIf(“SIP/204-0000014a”, “1?s-CONGESTION,1”) in new stack
– Goto (macro-exten-vm,s-CONGESTION,1)
– Executing [s-CONGESTION@macro-exten-vm:1] NoOp(“SIP/204-0000014a”, "IVR_RETVM: IVR_CONTEXT: ") in new stack
– Executing [s-CONGESTION@macro-exten-vm:2] GotoIf(“SIP/204-0000014a”, “0?exit,1”) in new stack
– Executing [s-CONGESTION@macro-exten-vm:3] PlayTones(“SIP/204-0000014a”, “congestion”) in new stack
– Executing [s-CONGESTION@macro-exten-vm:4] Congestion(“SIP/204-0000014a”, “10”) in new stack
== Spawn extension (macro-exten-vm, s-CONGESTION, 4) exited non-zero on ‘SIP/204-0000014a’ in macro ‘exten-vm’
== Spawn extension (from-internal, 205, 1) exited non-zero on ‘SIP/204-0000014a’
– Executing [h@from-internal:1] Macro(“SIP/204-0000014a”, “hangupcall”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/204-0000014a”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,4)
– Executing [s@macro-hangupcall:4] GotoIf(“SIP/204-0000014a”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [s@macro-hangupcall:7] GotoIf(“SIP/204-0000014a”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [s@macro-hangupcall:9] Hangup(“SIP/204-0000014a”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/204-0000014a’ in macro ‘hangupcall’
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/204-0000014a’

thanks for your help
Mike

I asked for the SIP trace. That’s not it.

Also, this dialplan looks quite complex, you should really ask the person who wrote it.

sorry, here’s the SIP debug. We’re using FreePBX, so the dialplan would have been created from the web interface.

[Feb 17 11:41:54] VERBOSE[18144] chan_sip.c:
<— SIP read from UDP:192.168.1.211:51318 —>
OPTIONS sip:192.168.1.101 SIP/2.0
Call-ID: 18cdb4571a201c2145a018d98da8d7d2@192.168.1.211
CSeq: 2865 OPTIONS
From: “214” sip:214@192.168.1.101;tag=3421843377
To: “214” sip:214@192.168.1.101
Via: SIP/2.0/UDP 192.168.1.211:51318;branch=z9hG4bK8389869176f3379309bc5ef5067fab01343730;rport
Max-Forwards: 70
User-Agent: SIPAUA/0.1.001
Content-Length: 0

<------------->
[Feb 17 11:41:54] VERBOSE[18144] chan_sip.c: — (9 headers 0 lines) —
[Feb 17 11:41:54] VERBOSE[18144] chan_sip.c: Looking for in from-sip-external (domain 192.168.1.101)
[Feb 17 11:41:54] VERBOSE[18144] chan_sip.c:
<— Transmitting (NAT) to 192.168.1.211:51318 —>
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 192.168.1.211:51318;branch=z9hG4bK8389869176f3379309bc5ef5067fab01343730;received=192.168.1.211;rport=51318
From: “214” sip:214@192.168.1.101;tag=3421843377
To: “214” sip:214@192.168.1.101;tag=as62e40a27
Call-ID: 18cdb4571a201c2145a018d98da8d7d2@192.168.1.211
CSeq: 2865 OPTIONS
Server: FPBX-2.8.1(1.8.2.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Accept: application/sdp
Content-Length: 0

<------------>
[Feb 17 11:41:54] VERBOSE[18144] chan_sip.c: Scheduling destruction of SIP dialog ‘18cdb4571a201c2145a018d98da8d7d2@192.168.1.211’ in 32000 ms (Method: OPTIONS)
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Reliably Transmitting (NAT) to 192.168.1.207:5063:
OPTIONS sip:203@192.168.1.207:5063 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.101:5060;branch=z9hG4bK10920af9;rport
Max-Forwards: 70
From: “Unknown” sip:Unknown@192.168.1.101;tag=as6aa219d6
To: sip:203@192.168.1.207:5063
Contact: sip:Unknown@192.168.1.101:5060
Call-ID: 06dfef3833eca63f6d53fa93205a7fdd@192.168.1.101:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.8.1(1.8.2.2)
Date: Fri, 17 Feb 2012 11:41:55 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0


[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c:
<— SIP read from UDP:192.168.1.207:5063 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.101:5060;branch=z9hG4bK10920af9;rport=5060
From: “Unknown” sip:Unknown@192.168.1.101;tag=as6aa219d6
To: sip:203@192.168.1.207:5063;tag=1006051087
Call-ID: 06dfef3833eca63f6d53fa93205a7fdd@192.168.1.101:5060
CSeq: 102 OPTIONS
User-Agent: Yealink SIP-T20P 9.50.23.2
Content-Length: 0

<------------->
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: — (8 headers 0 lines) —
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Really destroying SIP dialog ‘06dfef3833eca63f6d53fa93205a7fdd@192.168.1.101:5060’ Method: OPTIONS
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c:
<— SIP read from UDP:192.168.1.213:5062 —>
INVITE sip:205@192.168.1.101 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.213:5062;branch=z9hG4bK1803076392
From: “Mike” sip:204@192.168.1.101;tag=293705150
To: sip:205@192.168.1.101
Call-ID: 173578265@192.168.1.213
CSeq: 1 INVITE
Contact: sip:204@192.168.1.213:5062
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
Max-Forwards: 70
User-Agent: Yealink SIP-T26P 6.50.23.4
Supported: replaces
Expires: 360
Allow-Events: talk,hold,conference,refer,check-sync
Content-Length: 296

v=0
o=- 20300 20300 IN IP4 192.168.1.213
s=SDP data
c=IN IP4 192.168.1.213
t=0 0
m=audio 11786 RTP/AVP 0 8 18 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:9 G722/8000
a=fmtp:101 0-15
a=rtpmap:101 telephone-event/8000
a=sendrecv
<------------->
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: — (15 headers 14 lines) —
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Sending to 192.168.1.213:5062 (NAT)
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Using INVITE request as basis request - 173578265@192.168.1.213
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found peer ‘204’ for ‘204’ from 192.168.1.213:5062
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c:
<— Reliably Transmitting (NAT) to 192.168.1.213:5062 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.213:5062;branch=z9hG4bK1803076392;received=192.168.1.213;rport=5062
From: “Mike” sip:204@192.168.1.101;tag=293705150
To: sip:205@192.168.1.101;tag=as72b072f1
Call-ID: 173578265@192.168.1.213
CSeq: 1 INVITE
Server: FPBX-2.8.1(1.8.2.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="61ca39bd"
Content-Length: 0

<------------>
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Scheduling destruction of SIP dialog ‘173578265@192.168.1.213’ in 6400 ms (Method: INVITE)
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c:
<— SIP read from UDP:192.168.1.213:5062 —>
ACK sip:205@192.168.1.101 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.213:5062;branch=z9hG4bK1803076392
From: “Mike” sip:204@192.168.1.101;tag=293705150
To: sip:205@192.168.1.101;tag=as72b072f1
Call-ID: 173578265@192.168.1.213
CSeq: 1 ACK
Content-Length: 0

<------------->
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: — (7 headers 0 lines) —
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c:
<— SIP read from UDP:192.168.1.213:5062 —>
INVITE sip:205@192.168.1.101 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.213:5062;branch=z9hG4bK2082975035
From: “Mike” sip:204@192.168.1.101;tag=293705150
To: sip:205@192.168.1.101
Call-ID: 173578265@192.168.1.213
CSeq: 2 INVITE
Contact: sip:204@192.168.1.213:5062
Authorization: Digest username=“204”, realm=“asterisk”, nonce=“61ca39bd”, uri="sip:205@192.168.1.101", response=“6d73d2a7c7e97a1a1ecc5647c34f6d72”, algorithm=MD5
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
Max-Forwards: 70
User-Agent: Yealink SIP-T26P 6.50.23.4
Supported: replaces
Expires: 360
Allow-Events: talk,hold,conference,refer,check-sync
Content-Length: 296

v=0
o=- 20300 20300 IN IP4 192.168.1.213
s=SDP data
c=IN IP4 192.168.1.213
t=0 0
m=audio 11786 RTP/AVP 0 8 18 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:9 G722/8000
a=fmtp:101 0-15
a=rtpmap:101 telephone-event/8000
a=sendrecv
<------------->
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: — (16 headers 14 lines) —
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Sending to 192.168.1.213:5062 (NAT)
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Using INVITE request as basis request - 173578265@192.168.1.213
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found peer ‘204’ for ‘204’ from 192.168.1.213:5062
[Feb 17 11:41:55] VERBOSE[18144] netsock2.c: == Using SIP RTP TOS bits 184
[Feb 17 11:41:55] VERBOSE[18144] netsock2.c: == Using SIP RTP CoS mark 5
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found RTP audio format 0
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found RTP audio format 8
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found RTP audio format 18
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found RTP audio format 9
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found RTP audio format 101
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found audio description format PCMU for ID 0
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found audio description format PCMA for ID 8
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found audio description format G729 for ID 18
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found audio description format G722 for ID 9
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Found audio description format telephone-event for ID 101
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Capabilities: us - 0x110f (g723|gsm|ulaw|alaw|g729|g722), peer - audio=0x110c (ulaw|alaw|g729|g722)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x110c (ulaw|alaw|g729|g722)
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Peer audio RTP is at port 192.168.1.213:11786
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Looking for 205 in from-internal (domain 192.168.1.101)
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: list_route: hop: sip:204@192.168.1.213:5062
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c:
<— Transmitting (NAT) to 192.168.1.213:5062 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.213:5062;branch=z9hG4bK2082975035;received=192.168.1.213;rport=5062
From: “Mike” sip:204@192.168.1.101;tag=293705150
To: sip:205@192.168.1.101
Call-ID: 173578265@192.168.1.213
CSeq: 2 INVITE
Server: FPBX-2.8.1(1.8.2.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Contact: sip:205@192.168.1.101:5060
Content-Length: 0

<------------>
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [205@from-internal:1] Macro(“SIP/204-00000162”, “exten-vm,novm,205”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/204-00000162”, “user-callerid,”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/204-00000162”, “AMPUSER=204”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/204-00000162”, “0?report”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/204-00000162”, “1?Set(REALCALLERIDNUM=204)”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/204-00000162”, “AMPUSER=204”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/204-00000162”, “AMPUSERCIDNAME=MC”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/204-00000162”, “0?report”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/204-00000162”, “AMPUSERCID=204”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/204-00000162”, “CALLERID(all)=“MC” <204>”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:9] ExecIf(“SIP/204-00000162”, “0?Set(CHANNEL(language)=)”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:10] GotoIf(“SIP/204-00000162”, “0?continue”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/204-00000162”, “__TTL=64”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/204-00000162”, “1?continue”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-user-callerid,s,19)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:19] Set(“SIP/204-00000162”, “CALLERID(number)=204”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:20] Set(“SIP/204-00000162”, “CALLERID(name)=MC”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-user-callerid:21] NoOp(“SIP/204-00000162”, “Using CallerID “MC” <204>”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/204-00000162”, “RingGroupMethod=none”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/204-00000162”, “VMBOX=novm”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/204-00000162”, “__EXTTOCALL=205”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/204-00000162”, “CFUEXT=”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:6] Set(“SIP/204-00000162”, “CFBEXT=”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:7] Set(“SIP/204-00000162”, “RT=”"") in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:8] Macro(“SIP/204-00000162”, “record-enable,205,IN”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/204-00000162”, “1?check”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-record-enable,s,4)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-record-enable:4] ExecIf(“SIP/204-00000162”, “0?MacroExit()”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-record-enable:5] GotoIf(“SIP/204-00000162”, “0?Group:OUT”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-record-enable,s,15)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-record-enable:15] GotoIf(“SIP/204-00000162”, “1?IN”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-record-enable,s,20)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-record-enable:20] ExecIf(“SIP/204-00000162”, “1?MacroExit()”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:9] Macro(“SIP/204-00000162”, “dial-one,”",trwW,205") in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:1] Set(“SIP/204-00000162”, “DEXTEN=205”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/204-00000162”, “DIALSTATUS_CW=”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/204-00000162”, “0?screen,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/204-00000162”, “0?cf,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:5] GotoIf(“SIP/204-00000162”, “1?skip1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-dial-one,s,8)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:8] GotoIf(“SIP/204-00000162”, “0?nodial”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/204-00000162”, “0?continue”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/204-00000162”, “EXTHASCW=”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:11] GotoIf(“SIP/204-00000162”, “1?next1:cwinusebusy”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-dial-one,s,12)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:12] GotoIf(“SIP/204-00000162”, “0?docfu:skip3”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-dial-one,s,16)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:16] GotoIf(“SIP/204-00000162”, “1?next2:continue”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-dial-one,s,17)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:17] GotoIf(“SIP/204-00000162”, “1?continue”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-dial-one,s,25)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:25] GotoIf(“SIP/204-00000162”, “0?nodial”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:26] GosubIf(“SIP/204-00000162”, “1?dstring,1:dlocal,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:1] Set(“SIP/204-00000162”, “DSTRING=”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/204-00000162”, “DEVICES=205”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/204-00000162”, “0?Return()”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:4] ExecIf(“SIP/204-00000162”, “0?Set(DEVICES=05)”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:5] Set(“SIP/204-00000162”, “LOOPCNT=1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:6] Set(“SIP/204-00000162”, “ITER=1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:7] Set(“SIP/204-00000162”, “THISDIAL=SIP/205”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:8] GosubIf(“SIP/204-00000162”, “1?zap2dahdi,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/204-00000162”, “0?Return()”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/204-00000162”, “NEWDIAL=”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/204-00000162”, “LOOPCNT2=1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/204-00000162”, “ITER2=1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/204-00000162”, “THISPART2=SIP/205”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/204-00000162”, “0?Set(THISPART2=DAHDI/205)”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/204-00000162”, “NEWDIAL=SIP/205&”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/204-00000162”, “ITER2=2”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/204-00000162”, “0?begin2”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/204-00000162”, “THISDIAL=SIP/205”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/204-00000162”, “”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:9] Set(“SIP/204-00000162”, “DSTRING=SIP/205&”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:10] Set(“SIP/204-00000162”, “ITER=2”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/204-00000162”, “0?begin”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“SIP/204-00000162”, “DSTRING=SIP/205”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“SIP/204-00000162”, “”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/204-00000162”, “0?nodial”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:28] GotoIf(“SIP/204-00000162”, “1?skiptrace”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-dial-one,s,30)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:30] Set(“SIP/204-00000162”, “D_OPTIONS=trwW”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:31] ExecIf(“SIP/204-00000162”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:32] ExecIf(“SIP/204-00000162”, “0?SIPAddHeader()”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:33] ExecIf(“SIP/204-00000162”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:34] GosubIf(“SIP/204-00000162”, “0?qwait,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:35] Set(“SIP/204-00000162”, “__CWIGNORE=”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:36] Set(“SIP/204-00000162”, “__KEEPCID=TRUE”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:37] Dial(“SIP/204-00000162”, “SIP/205,”",trwW") in new stack
[Feb 17 11:41:55] VERBOSE[6376] netsock2.c: == Using SIP RTP TOS bits 184
[Feb 17 11:41:55] VERBOSE[6376] netsock2.c: == Using SIP RTP CoS mark 5
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c: Audio is at 5060
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c: Adding codec 0x4 (ulaw) to SDP
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c: Adding codec 0x8 (alaw) to SDP
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c: Adding codec 0x2 (gsm) to SDP
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c: Adding codec 0x1000 (g722) to SDP
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c: Reliably Transmitting (NAT) to 192.168.1.208:5060:
INVITE sip:205@192.168.1.208 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.101:5060;branch=z9hG4bK594cc3b6;rport
Max-Forwards: 70
From: “MC” sip:204@192.168.1.101;tag=as5e8c63ad
To: sip:205@192.168.1.208
Contact: sip:204@192.168.1.101:5060
Call-ID: 4d9ee1bd40e1ff6f15df6cc13619628c@192.168.1.101:5060
CSeq: 102 INVITE
User-Agent: FPBX-2.8.1(1.8.2.2)
Date: Fri, 17 Feb 2012 11:41:55 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 332

v=0
o=root 71990654 71990654 IN IP4 192.168.1.101
s=Asterisk PBX 1.8.2.2
c=IN IP4 192.168.1.101
t=0 0
m=audio 19438 RTP/AVP 0 8 3 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv


[Feb 17 11:41:55] VERBOSE[6376] app_dial.c: – Called 205
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c:
<— Transmitting (NAT) to 192.168.1.213:5062 —>
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.1.213:5062;branch=z9hG4bK2082975035;received=192.168.1.213;rport=5062
From: “Mike” sip:204@192.168.1.101;tag=293705150
To: sip:205@192.168.1.101;tag=as29f15cf2
Call-ID: 173578265@192.168.1.213
CSeq: 2 INVITE
Server: FPBX-2.8.1(1.8.2.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Contact: sip:205@192.168.1.101:5060
Content-Length: 0

<------------>
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c:
<— SIP read from UDP:192.168.1.208:5060 —>
SIP/2.0 488 Not Acceptable Here
Via: SIP/2.0/UDP 192.168.1.101:5060;branch=z9hG4bK594cc3b6;rport
From: “MC” sip:204@192.168.1.101;tag=as5e8c63ad
To: sip:205@192.168.1.208;tag=CC4F985C-BA29E523
CSeq: 102 INVITE
Call-ID: 4d9ee1bd40e1ff6f15df6cc13619628c@192.168.1.101:5060
Contact: sip:205@192.168.1.208
User-Agent: PolycomSoundStationIP-SSIP_4000-UA/1.6.3.0071
Content-Length: 0

<------------->
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: — (9 headers 0 lines) —
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Transmitting (NAT) to 192.168.1.208:5060:
ACK sip:205@192.168.1.208 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.101:5060;branch=z9hG4bK594cc3b6;rport
Max-Forwards: 70
From: “MC” sip:204@192.168.1.101;tag=as5e8c63ad
To: sip:205@192.168.1.208;tag=CC4F985C-BA29E523
Contact: sip:204@192.168.1.101:5060
Call-ID: 4d9ee1bd40e1ff6f15df6cc13619628c@192.168.1.101:5060
CSeq: 102 ACK
User-Agent: FPBX-2.8.1(1.8.2.2)
Content-Length: 0


[Feb 17 11:41:55] VERBOSE[6376] app_dial.c: – SIP/205-00000163 is circuit-busy
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Really destroying SIP dialog ‘4d9ee1bd40e1ff6f15df6cc13619628c@192.168.1.101:5060’ Method: INVITE
[Feb 17 11:41:55] VERBOSE[6376] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:38] ExecIf(“SIP/204-00000162”, “0?Set(DIALSTATUS=)”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:39] GosubIf(“SIP/204-00000162”, “0?s-CONGESTION,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-dial-one:40] MacroExit(“SIP/204-00000162”, “”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:10] GotoIf(“SIP/204-00000162”, “0?exit”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:11] Set(“SIP/204-00000162”, “SV_DIALSTATUS=CONGESTION”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:12] GosubIf(“SIP/204-00000162”, “0?docfu,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:13] GosubIf(“SIP/204-00000162”, “0?docfb,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:14] Set(“SIP/204-00000162”, “DIALSTATUS=CONGESTION”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:15] NoOp(“SIP/204-00000162”, “Voicemail is ‘novm’”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-exten-vm:16] GotoIf(“SIP/204-00000162”, “1?s-CONGESTION,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-exten-vm,s-CONGESTION,1)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s-CONGESTION@macro-exten-vm:1] NoOp(“SIP/204-00000162”, "IVR_RETVM: IVR_CONTEXT: ") in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s-CONGESTION@macro-exten-vm:2] GotoIf(“SIP/204-00000162”, “0?exit,1”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s-CONGESTION@macro-exten-vm:3] PlayTones(“SIP/204-00000162”, “congestion”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s-CONGESTION@macro-exten-vm:4] Congestion(“SIP/204-00000162”, “10”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] chan_sip.c:
<— Reliably Transmitting (NAT) to 192.168.1.213:5062 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.1.213:5062;branch=z9hG4bK2082975035;received=192.168.1.213;rport=5062
From: “Mike” sip:204@192.168.1.101;tag=293705150
To: sip:205@192.168.1.101;tag=as29f15cf2
Call-ID: 173578265@192.168.1.213
CSeq: 2 INVITE
Server: FPBX-2.8.1(1.8.2.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
X-Asterisk-HangupCause: Bearer capability not available
X-Asterisk-HangupCauseCode: 58
Content-Length: 0

<------------>
[Feb 17 11:41:55] VERBOSE[6376] app_macro.c: == Spawn extension (macro-exten-vm, s-CONGESTION, 4) exited non-zero on ‘SIP/204-00000162’ in macro ‘exten-vm’
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: == Spawn extension (from-internal, 205, 1) exited non-zero on ‘SIP/204-00000162’
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [h@from-internal:1] Macro(“SIP/204-00000162”, “hangupcall”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/204-00000162”, “1?skiprg”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-hangupcall,s,4)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/204-00000162”, “1?skipblkvm”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-hangupcall,s,7)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/204-00000162”, “1?theend”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Goto (macro-hangupcall,s,9)
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: – Executing [s@macro-hangupcall:9] Hangup(“SIP/204-00000162”, “”) in new stack
[Feb 17 11:41:55] VERBOSE[6376] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/204-00000162’ in macro ‘hangupcall’
[Feb 17 11:41:55] VERBOSE[6376] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/204-00000162’
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c:
<— SIP read from UDP:192.168.1.213:5062 —>
ACK sip:205@192.168.1.101 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.213:5062;branch=z9hG4bK2082975035
From: “Mike” sip:204@192.168.1.101;tag=293705150
To: sip:205@192.168.1.101;tag=as29f15cf2
Call-ID: 173578265@192.168.1.213
CSeq: 2 ACK
Content-Length: 0

<------------->
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: — (7 headers 0 lines) —
[Feb 17 11:41:55] VERBOSE[18144] chan_sip.c: Really destroying SIP dialog ‘173578265@192.168.1.213’ Method: ACK
[Feb 17 11:41:57] VERBOSE[18144] chan_sip.c: Really destroying SIP dialog ‘e67f30746392e43e2391bf38ee50e423@192.168.1.211’ Method: OPTIONS
[Feb 17 11:42:00] VERBOSE[18144] chan_sip.c:
<— SIP read from UDP:192.168.1.207:5063 —>

RFC 3261:

[quote]21.4.26 488 Not Acceptable Here

The response has the same meaning as 606 (Not Acceptable), but only
applies to the specific resource addressed by the Request-URI and the
request may succeed elsewhere.

A message body containing a description of media capabilities MAY be
present in the response, which is formatted according to the Accept
header field in the INVITE (or application/sdp if not present), the
same as a message body in a 200 (OK) response to an OPTIONS request.[/quote]

This normally means that there are no codecs in common between the two parties. It SHOULD be accompanied by a Warning header, explaining why it was produced, but that does not seem to be the case here.

[quote]21.6.4 606 Not Acceptable

The user’s agent was contacted successfully but some aspects of the
session description such as the requested media, bandwidth, or
addressing style were not acceptable.

A 606 (Not Acceptable) response means that the user wishes to
communicate, but cannot adequately support the session described.
The 606 (Not Acceptable) response MAY contain a list of reasons in a
Warning header field describing why the session described cannot be
supported. Warning reason codes are listed in Section 20.43.

A message body containing a description of media capabilities MAY be
present in the response, which is formatted according to the Accept
header field in the INVITE (or application/sdp if not present), the
same as a message body in a 200 (OK) response to an OPTIONS request.

It is hoped that negotiation will not frequently be needed, and when
a new user is being invited to join an already existing conference,
negotiation may not be possible. It is up to the invitation
initiator to decide whether or not to act on a 606 (Not Acceptable)
response.

This status response is returned only if the client knows that no
other end point will answer the request.[/quote]

thanks for that, it points me in the right direction - the Polycom uses G729ab (the only other alternative is G711), I presume that’s something to do with it. I’d discounted this as the problem because I can make calls from the Polycom to other extensions. I’ll dig around and see what I come up with.

thanks again
Mike

this was probably obvious to everyone else but I’ve discovered that G.711a and G.711u are actually alaw and ulaw! :smile:. Configuring the Polycom to use G.711 has resolved the problem.

thanks again
Mike