IVR not answering

I am using FreePBX 2.11.0.23.

I have created a simple IVR Auto Attendant, where option 1 rings my extension. The call never gets answered by the IVR.

Other inbound/outbound calls work. I also have a meet-me conference setup and working.

I have an inbound route defined for my IVR.

I have 2 inbound SIP trunks (cisco routers) defined using the following inbound settings:
type=friend
context=from-trunk

Here’s the SIP debug output (IP Addresses/Phones Masked):

[2014-02-12 12:42:44] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;x-route-tag=“tgrp:PRI-TRUNK”;branch=z9hG4bKC105B4
Remote-Party-ID: “INVOIP” sip:[email protected];party=calling;screen=yes;privacy=off
From: “INVOIP” sip:[email protected];tag=A6013890-266E
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:42:44 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 3924118595-2470646243-2758279189-3330587560
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392226964
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 250

v=0
o=CiscoSystemsSIP-GW-UserAgent 8585 4550 IN IP4 X.X.X.10
s=SIP Call
c=IN IP4 X.X.X.10
t=0 0
m=audio 16778 RTP/AVP 0 101
c=IN IP4 X.X.X.10
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
<------------->
[2014-02-12 12:42:44] VERBOSE[1746] chan_sip.c: — (21 headers 11 lines) —
[2014-02-12 12:42:44] VERBOSE[1746] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Found peer ‘X.X.X.10’ for ‘2485551212’ from X.X.X.10:64536
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Found RTP audio format 0
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Found RTP audio format 101
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Found audio description format PCMU for ID 0
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Found audio description format telephone-event for ID 101
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Capabilities: us - (ulaw), peer - audio=(ulaw)/video=(nothing)/text=(nothing), combined - (ulaw)
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Peer audio RTP is at port X.X.X.10:16778
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:42:44] VERBOSE[1746][C-00000070] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.10:5060;x-route-tag=“tgrp:PRI-TRUNK”;branch=z9hG4bKC105B4;received=X.X.X.10
From: “INVOIP” sip:[email protected];tag=A6013890-266E
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:42:44] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.10-00000079”, “”) in new stack
[2014-02-12 12:42:44] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.10-00000079”, “”) in new stack
[2014-02-12 12:42:44] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.10-00000079”, “”) in new stack
[2014-02-12 12:42:44] VERBOSE[4831][C-00000070] chan_sip.c: Audio is at 18008
[2014-02-12 12:42:44] VERBOSE[4831][C-00000070] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:42:44] VERBOSE[4831][C-00000070] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:42:44] VERBOSE[4831][C-00000070] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.10:5060;x-route-tag=“tgrp:PRI-TRUNK”;branch=z9hG4bKC105B4;received=X.X.X.10
From: “INVOIP” sip:[email protected];tag=A6013890-266E
To: sip:[email protected];tag=as6236e819
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 237

v=0
o=root 1294876577 1294876577 IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 18008 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:42:44] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.10-00000079”, “1”) in new stack
[2014-02-12 12:42:45] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:5] Progress(“SIP/X.X.X.10-00000079”, “”) in new stack
[2014-02-12 12:42:45] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:6] Playback(“SIP/X.X.X.10-00000079”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2014-02-12 12:42:45] VERBOSE[4831][C-00000070] file.c: – <SIP/X.X.X.10-00000079> Playing ‘silence/1.ulaw’ (language ‘en’)
[2014-02-12 12:42:46] VERBOSE[4831][C-00000070] file.c: – <SIP/X.X.X.10-00000079> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2014-02-12 12:42:49] VERBOSE[4831][C-00000070] file.c: – <SIP/X.X.X.10-00000079> Playing ‘check-number-dial-again.ulaw’ (language ‘en’)
[2014-02-12 12:42:51] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:7] Wait(“SIP/X.X.X.10-00000079”, “1”) in new stack
[2014-02-12 12:42:52] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:8] Congestion(“SIP/X.X.X.10-00000079”, “20”) in new stack
[2014-02-12 12:42:52] VERBOSE[4831][C-00000070] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP X.X.X.10:5060;x-route-tag=“tgrp:PRI-TRUNK”;branch=z9hG4bKC105B4;received=X.X.X.10
From: “INVOIP” sip:[email protected];tag=A6013890-266E
To: sip:[email protected];tag=as6236e819
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Content-Length: 0

<------------>
[2014-02-12 12:42:52] WARNING[4831][C-00000070] channel.c: Prodding channel ‘SIP/X.X.X.10-00000079’ failed
[2014-02-12 12:42:52] VERBOSE[4831][C-00000070] pbx.c: == Spawn extension (from-internal, 8482477275, 8) exited non-zero on ‘SIP/X.X.X.10-00000079’
[2014-02-12 12:42:52] VERBOSE[4831][C-00000070] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.10-00000079”, “”) in new stack
[2014-02-12 12:42:52] VERBOSE[4831][C-00000070] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.10-00000079’
[2014-02-12 12:42:52] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;x-route-tag=“tgrp:PRI-TRUNK”;branch=z9hG4bKC105B4
From: “INVOIP” sip:[email protected];tag=A6013890-266E
To: sip:[email protected];tag=as6236e819
Date: Wed, 12 Feb 2014 17:42:44 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:42:52] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:42:52] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2014-02-12 12:42:52] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1051FB6
Remote-Party-ID: sip:[email protected];party=calling;screen=yes;privacy=off
From: sip:[email protected];tag=A54D6BAC-1B50
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:42:52 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2163960087-2628956463-0268500226-1138130186
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392226972
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:42:52] VERBOSE[1746] chan_sip.c: — (19 headers 0 lines) —
[2014-02-12 12:42:52] VERBOSE[1746] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:42:52] VERBOSE[1746][C-00000071] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:42:52] VERBOSE[1746][C-00000071] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:42:52] VERBOSE[1746][C-00000071] chan_sip.c: Found peer ‘X.X.X.40’ for ‘2485551212’ from X.X.X.40:60518
[2014-02-12 12:42:52] VERBOSE[1746][C-00000071] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:42:52] VERBOSE[1746][C-00000071] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:42:52] VERBOSE[1746][C-00000071] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:42:52] VERBOSE[1746][C-00000071] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:42:52] VERBOSE[1746][C-00000071] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1051FB6;received=X.X.X.40
From: sip:[email protected];tag=A54D6BAC-1B50
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:42:52] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.40-0000007a”, “”) in new stack
[2014-02-12 12:42:52] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.40-0000007a”, “”) in new stack
[2014-02-12 12:42:52] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.40-0000007a”, “”) in new stack
[2014-02-12 12:42:52] VERBOSE[4832][C-00000071] chan_sip.c: Audio is at 18286
[2014-02-12 12:42:52] VERBOSE[4832][C-00000071] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:42:52] VERBOSE[4832][C-00000071] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:42:52] VERBOSE[4832][C-00000071] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1051FB6;received=X.X.X.40
From: sip:[email protected];tag=A54D6BAC-1B50
To: sip:[email protected];tag=as4a3e22ad
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 235

v=0
o=root 880107625 880107625 IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 18286 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:42:52] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.40-0000007a”, “1”) in new stack
[2014-02-12 12:42:53] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:5] Progress(“SIP/X.X.X.40-0000007a”, “”) in new stack
[2014-02-12 12:42:53] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:6] Playback(“SIP/X.X.X.40-0000007a”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2014-02-12 12:42:53] VERBOSE[4832][C-00000071] file.c: – <SIP/X.X.X.40-0000007a> Playing ‘silence/1.ulaw’ (language ‘en’)
[2014-02-12 12:42:54] VERBOSE[4832][C-00000071] file.c: – <SIP/X.X.X.40-0000007a> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: Reliably Transmitting (no NAT) to X.X.X.40:5060:
OPTIONS sip:X.X.X.40 SIP/2.0
Via: SIP/2.0/UDP X.X.X.57:5060;branch=z9hG4bK19b84692
Max-Forwards: 70
From: “Unknown” sip:[email protected];tag=as19f7b696
To: sip:X.X.X.40
Contact: sip:[email protected]:5060
Call-ID: 6cc67aa4316a8dc471d081a61c179b6X.X.X.1.57:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(11.7.0)
Date: Wed, 12 Feb 2014 17:42:56 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0


[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP X.X.X.57:5060;branch=z9hG4bK19b84692
From: “Unknown” sip:[email protected];tag=as19f7b696
To: sip:X.X.X.40;tag=A54D77FC-CAB
Date: Wed, 12 Feb 2014 17:42:56 GMT
Call-ID: 6cc67aa4316a8dc471d081a61c179b6X.X.X.1.57:5060
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 102 OPTIONS
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Content-Type: application/sdp
Content-Length: 454

v=0
o=CiscoSystemsSIP-GW-UserAgent 9983 8329 IN IP4 X.X.X.40
s=SIP Call
c=IN IP4 X.X.X.40
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15
c=IN IP4 X.X.X.40
m=image 0 udptl t38
c=IN IP4 X.X.X.40
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxFillBitRemoval:0
a=T38FaxTranscodingMMR:0
a=T38FaxTranscodingJBIG:0
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:320
a=T38FaxUdpEC:t38UDPRedundancy
<------------->
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: — (14 headers 18 lines) —
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘6cc67aa4316a8dc471d081a61c179b6X.X.X.1.57:5060’ Method: OPTIONS
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: Reliably Transmitting (no NAT) to X.X.X.10:5060:
OPTIONS sip:X.X.X.10 SIP/2.0
Via: SIP/2.0/UDP X.X.X.57:5060;branch=z9hG4bK0abd949a
Max-Forwards: 70
From: “Unknown” sip:[email protected];tag=as04f772df
To: sip:X.X.X.10
Contact: sip:[email protected]:5060
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(11.7.0)
Date: Wed, 12 Feb 2014 17:42:56 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0


[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP X.X.X.57:5060;branch=z9hG4bK0abd949a
From: “Unknown” sip:[email protected];tag=as04f772df
To: sip:X.X.X.10;tag=A60165B8-1C3A
Date: Wed, 12 Feb 2014 17:42:56 GMT
Call-ID: [email protected]:5060
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 102 OPTIONS
Supported: 100rel,resource-priority,replaces,sdp-anat
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
Allow-Events: telephone-event
Accept: application/sdp
Content-Type: application/sdp
Content-Length: 456

v=0
o=CiscoSystemsSIP-GW-UserAgent 1582 4230 IN IP4 X.X.X.10
s=SIP Call
c=IN IP4 X.X.X.10
t=0 0
m=audio 0 RTP/AVP 18 0 8 9 4 2 15 3
c=IN IP4 X.X.X.10
m=image 0 udptl t38
c=IN IP4 X.X.X.10
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxFillBitRemoval:0
a=T38FaxTranscodingMMR:0
a=T38FaxTranscodingJBIG:0
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:180
a=T38FaxUdpEC:t38UDPRedundancy
<------------->
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: — (14 headers 18 lines) —
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]:5060’ Method: OPTIONS
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: Reliably Transmitting (no NAT) to 10.254.38.98:5060:
OPTIONS sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.57:5060;branch=z9hG4bK71239d78
Max-Forwards: 70
From: “Unknown” sip:[email protected];tag=as12868ab7
To: sip:[email protected]:5060
Contact: sip:[email protected]:5060
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(11.7.0)
Date: Wed, 12 Feb 2014 17:42:56 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0


[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:10.254.38.98:5060 —>
SIP/2.0 200 OK
To: sip:[email protected]:5060;tag=b3ee85e327883c0bi0
From: “Unknown” sip:[email protected];tag=as12868ab7
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP X.X.X.57:5060;branch=z9hG4bK71239d78
Server: Cisco/SPA504G-7.5.3
Content-Length: 0
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER, UPDATE
Supported: replaces

<------------->
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:42:56] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]:5060’ Method: OPTIONS
[2014-02-12 12:42:57] VERBOSE[4832][C-00000071] file.c: – <SIP/X.X.X.40-0000007a> Playing ‘check-number-dial-again.ulaw’ (language ‘en’)
[2014-02-12 12:42:59] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:7] Wait(“SIP/X.X.X.40-0000007a”, “1”) in new stack
[2014-02-12 12:43:00] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:8] Congestion(“SIP/X.X.X.40-0000007a”, “20”) in new stack
[2014-02-12 12:43:00] VERBOSE[4832][C-00000071] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1051FB6;received=X.X.X.40
From: sip:[email protected];tag=A54D6BAC-1B50
To: sip:[email protected];tag=as4a3e22ad
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Content-Length: 0

<------------>
[2014-02-12 12:43:00] WARNING[4832][C-00000071] channel.c: Prodding channel ‘SIP/X.X.X.40-0000007a’ failed
[2014-02-12 12:43:00] VERBOSE[4832][C-00000071] pbx.c: == Spawn extension (from-internal, 8482477275, 8) exited non-zero on ‘SIP/X.X.X.40-0000007a’
[2014-02-12 12:43:00] VERBOSE[4832][C-00000071] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.40-0000007a”, “”) in new stack
[2014-02-12 12:43:00] VERBOSE[4832][C-00000071] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.40-0000007a’
[2014-02-12 12:43:00] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1051FB6
From: sip:[email protected];tag=A54D6BAC-1B50
To: sip:[email protected];tag=as4a3e22ad
Date: Wed, 12 Feb 2014 17:42:52 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:00] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:43:00] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2014-02-12 12:43:00] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKCX.X.X.
Remote-Party-ID: sip:[email protected];party=calling;screen=yes;privacy=off
From: sip:[email protected];tag=A6017748-893
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:00 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2158964252-2763174191-474808322-1138130216
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392226980
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:00] VERBOSE[1746] chan_sip.c: — (19 headers 0 lines) —
[2014-02-12 12:43:00] VERBOSE[1746] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:00] VERBOSE[1746][C-00000072] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:00] VERBOSE[1746][C-00000072] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:43:00] VERBOSE[1746][C-00000072] chan_sip.c: Found peer ‘X.X.X.10’ for ‘2485551212’ from X.X.X.10:64536
[2014-02-12 12:43:00] VERBOSE[1746][C-00000072] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:43:00] VERBOSE[1746][C-00000072] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:43:00] VERBOSE[1746][C-00000072] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:43:00] VERBOSE[1746][C-00000072] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:43:00] VERBOSE[1746][C-00000072] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKCX.X.X.;received=X.X.X.10
From: sip:[email protected];tag=A6017748-893
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:43:00] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.10-0000007b”, “”) in new stack
[2014-02-12 12:43:00] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.10-0000007b”, “”) in new stack
[2014-02-12 12:43:00] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.10-0000007b”, “”) in new stack
[2014-02-12 12:43:00] VERBOSE[4836][C-00000072] chan_sip.c: Audio is at 19642
[2014-02-12 12:43:00] VERBOSE[4836][C-00000072] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:43:00] VERBOSE[4836][C-00000072] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:43:00] VERBOSE[4836][C-00000072] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKCX.X.X.;received=X.X.X.10
From: sip:[email protected];tag=A6017748-893
To: sip:[email protected];tag=as3e081ebe
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 235

v=0
o=root 532803352 532803352 IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 19642 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:43:00] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.10-0000007b”, “1”) in new stack
[2014-02-12 12:43:01] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:5] Progress(“SIP/X.X.X.10-0000007b”, “”) in new stack
[2014-02-12 12:43:01] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:6] Playback(“SIP/X.X.X.10-0000007b”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2014-02-12 12:43:01] VERBOSE[4836][C-00000072] file.c: – <SIP/X.X.X.10-0000007b> Playing ‘silence/1.ulaw’ (language ‘en’)
[2014-02-12 12:43:02] VERBOSE[4836][C-00000072] file.c: – <SIP/X.X.X.10-0000007b> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2014-02-12 12:43:05] VERBOSE[4836][C-00000072] file.c: – <SIP/X.X.X.10-0000007b> Playing ‘check-number-dial-again.ulaw’ (language ‘en’)
[2014-02-12 12:43:07] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:7] Wait(“SIP/X.X.X.10-0000007b”, “1”) in new stack
[2014-02-12 12:43:08] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:8] Congestion(“SIP/X.X.X.10-0000007b”, “20”) in new stack
[2014-02-12 12:43:08] VERBOSE[4836][C-00000072] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKCX.X.X.;received=X.X.X.10
From: sip:[email protected];tag=A6017748-893
To: sip:[email protected];tag=as3e081ebe
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Content-Length: 0

<------------>
[2014-02-12 12:43:08] WARNING[4836][C-00000072] channel.c: Prodding channel ‘SIP/X.X.X.10-0000007b’ failed
[2014-02-12 12:43:08] VERBOSE[4836][C-00000072] pbx.c: == Spawn extension (from-internal, 8482477275, 8) exited non-zero on ‘SIP/X.X.X.10-0000007b’
[2014-02-12 12:43:08] VERBOSE[4836][C-00000072] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.10-0000007b”, “”) in new stack
[2014-02-12 12:43:08] VERBOSE[4836][C-00000072] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.10-0000007b’
[2014-02-12 12:43:08] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKCX.X.X.
From: sip:[email protected];tag=A6017748-893
To: sip:[email protected];tag=as3e081ebe
Date: Wed, 12 Feb 2014 17:43:00 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:08] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:43:08] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2014-02-12 12:43:08] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1061298
Remote-Party-ID: sip:[email protected];party=calling;screen=yes;privacy=off
From: sip:[email protected];tag=A54DA9A0-AF8
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:08 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2154033952-2897391919-0285212930-1138130186
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392226988
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:08] VERBOSE[1746] chan_sip.c: — (19 headers 0 lines) —
[2014-02-12 12:43:08] VERBOSE[1746] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:43:08] VERBOSE[1746][C-00000073] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:43:08] VERBOSE[1746][C-00000073] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:43:08] VERBOSE[1746][C-00000073] chan_sip.c: Found peer ‘X.X.X.40’ for ‘2485551212’ from X.X.X.40:60518
[2014-02-12 12:43:08] VERBOSE[1746][C-00000073] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:43:08] VERBOSE[1746][C-00000073] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:43:08] VERBOSE[1746][C-00000073] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:43:08] VERBOSE[1746][C-00000073] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:43:08] VERBOSE[1746][C-00000073] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1061298;received=X.X.X.40
From: sip:[email protected];tag=A54DA9A0-AF8
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:43:08] VERBOSE[4844][C-00000073] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.40-0000007c”, “”) in new stack
[2014-02-12 12:43:08] VERBOSE[4844][C-00000073] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.40-0000007c”, “”) in new stack
[2014-02-12 12:43:08] VERBOSE[4844][C-00000073] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.40-0000007c”, “”) in new stack
[2014-02-12 12:43:08] VERBOSE[4844][C-00000073] chan_sip.c: Audio is at 14192
[2014-02-12 12:43:08] VERBOSE[4844][C-00000073] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:43:08] VERBOSE[4844][C-00000073] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:43:08] VERBOSE[4844][C-00000073] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1061298;received=X.X.X.40
From: sip:[email protected];tag=A54DA9A0-AF8
To: sip:[email protected];tag=as6c10651e
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 233

v=0
o=root 61370045 61370045 IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 14192 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:43:08] VERBOSE[4844][C-00000073] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.40-0000007c”, “1”) in new stack
[2014-02-12 12:43:09] VERBOSE[4844][C-00000073] pbx.c: – Executing [[email protected]:5] Progress(“SIP/X.X.X.40-0000007c”, “”) in new stack
[2014-02-12 12:43:09] VERBOSE[4844][C-00000073] pbx.c: – Executing [[email protected]:6] Playback(“SIP/X.X.X.40-0000007c”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2014-02-12 12:43:09] VERBOSE[4844][C-00000073] file.c: – <SIP/X.X.X.40-0000007c> Playing ‘silence/1.ulaw’ (language ‘en’)
[2014-02-12 12:43:10] VERBOSE[4844][C-00000073] file.c: – <SIP/X.X.X.40-0000007c> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
CANCEL sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1061298
From: sip:[email protected];tag=A54DA9A0-AF8
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:08 GMT
Call-ID: [email protected]
CSeq: 101 CANCEL
Max-Forwards: 70
Timestamp: 1392226991
Reason: Q.850;cause=47
Content-Length: 0

<------------->
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: — (11 headers 0 lines) —
[2014-02-12 12:43:11] VERBOSE[1746][C-00000073] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:43:11] VERBOSE[1746][C-00000073] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1061298;received=X.X.X.40
From: sip:[email protected];tag=A54DA9A0-AF8
To: sip:[email protected];tag=as6c10651e
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
[2014-02-12 12:43:11] VERBOSE[1746][C-00000073] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1061298;received=X.X.X.40
From: sip:[email protected];tag=A54DA9A0-AF8
To: sip:[email protected];tag=as6c10651e
Call-ID: [email protected]
CSeq: 101 CANCEL
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
[2014-02-12 12:43:11] VERBOSE[4844][C-00000073] pbx.c: == Spawn extension (from-internal, 8482477275, 6) exited non-zero on ‘SIP/X.X.X.40-0000007c’
[2014-02-12 12:43:11] VERBOSE[4844][C-00000073] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.40-0000007c”, “”) in new stack
[2014-02-12 12:43:11] VERBOSE[4844][C-00000073] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.40-0000007c’
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK1061298
From: sip:[email protected];tag=A54DA9A0-AF8
To: sip:[email protected];tag=as6c10651e
Date: Wed, 12 Feb 2014 17:43:08 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK107B33
Remote-Party-ID: sip:[email protected];party=calling;screen=yes;privacy=off
From: sip:[email protected];tag=A54DB4F8-B2B
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:11 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 0002604322-2947723567-0301990658-1138130186
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392226991
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: — (19 headers 0 lines) —
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:43:11] VERBOSE[1746][C-00000074] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:43:11] VERBOSE[1746][C-00000074] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:43:11] VERBOSE[1746][C-00000074] chan_sip.c: Found peer ‘X.X.X.40’ for ‘2485551212’ from X.X.X.40:60518
[2014-02-12 12:43:11] VERBOSE[1746][C-00000074] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:43:11] VERBOSE[1746][C-00000074] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:43:11] VERBOSE[1746][C-00000074] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:43:11] VERBOSE[1746][C-00000074] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:43:11] VERBOSE[1746][C-00000074] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK107B33;received=X.X.X.40
From: sip:[email protected];tag=A54DB4F8-B2B
To: sip:[email protected]
Call-ID: F9F1B[email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:43:11] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.40-0000007d”, “”) in new stack
[2014-02-12 12:43:11] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.40-0000007d”, “”) in new stack
[2014-02-12 12:43:11] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.40-0000007d”, “”) in new stack
[2014-02-12 12:43:11] VERBOSE[4848][C-00000074] chan_sip.c: Audio is at 14040
[2014-02-12 12:43:11] VERBOSE[4848][C-00000074] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:43:11] VERBOSE[4848][C-00000074] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:43:11] VERBOSE[4848][C-00000074] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK107B33;received=X.X.X.40
From: sip:[email protected];tag=A54DB4F8-B2B
To: sip:[email protected];tag=as777cc5b1
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 235

v=0
o=root 373274860 373274860 IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 14040 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:43:11] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.40-0000007d”, “1”) in new stack
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC127D5
Remote-Party-ID: sip:[email protected];party=calling;screen=yes;privacy=off
From: sip:[email protected];tag=A601A1A8-1CED
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:11 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2604322-2947723567-491586050-1138130216
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392226991
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: — (19 headers 0 lines) —
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c: Found peer ‘X.X.X.10’ for ‘2485551212’ from X.X.X.10:64536
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC127D5;received=X.X.X.10
From: sip:[email protected];tag=A601A1A8-1CED
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.10-0000007e”, “”) in new stack
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.10-0000007e”, “”) in new stack
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.10-0000007e”, “”) in new stack
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] chan_sip.c: Audio is at 12906
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC127D5;received=X.X.X.10
From: sip:[email protected];tag=A601A1A8-1CED
To: sip:[email protected];tag=as331bf420
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 237

v=0
o=root 1997130091 1997130091 IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 12906 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.10-0000007e”, “1”) in new stack
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
CANCEL sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC127D5
From: sip:[email protected];tag=A601A1A8-1CED
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:11 GMT
Call-ID: [email protected]
CSeq: 101 CANCEL
Max-Forwards: 70
Timestamp: 1392226991
Reason: Q.850;cause=47
Content-Length: 0

<------------->
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: — (11 headers 0 lines) —
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC127D5;received=X.X.X.10
From: sip:[email protected];tag=A601A1A8-1CED
To: sip:[email protected];tag=as331bf420
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
[2014-02-12 12:43:11] VERBOSE[1746][C-00000075] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC127D5;received=X.X.X.10
From: sip:[email protected];tag=A601A1A8-1CED
To: sip:[email protected];tag=as331bf420
Call-ID: [email protected]
CSeq: 101 CANCEL
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] pbx.c: == Spawn extension (from-internal, 8482477275, 4) exited non-zero on ‘SIP/X.X.X.10-0000007e’
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.10-0000007e”, “”) in new stack
[2014-02-12 12:43:11] VERBOSE[4849][C-00000075] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.10-0000007e’
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC127D5
From: sip:[email protected];tag=A601A1A8-1CED
To: sip:[email protected];tag=as331bf420
Date: Wed, 12 Feb 2014 17:43:11 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:43:11] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2014-02-12 12:43:12] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:5] Progress(“SIP/X.X.X.40-0000007d”, “”) in new stack
[2014-02-12 12:43:12] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:6] Playback(“SIP/X.X.X.40-0000007d”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2014-02-12 12:43:12] VERBOSE[4848][C-00000074] file.c: – <SIP/X.X.X.40-0000007d> Playing ‘silence/1.ulaw’ (language ‘en’)
[2014-02-12 12:43:13] VERBOSE[4848][C-00000074] file.c: – <SIP/X.X.X.40-0000007d> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2014-02-12 12:43:16] VERBOSE[4848][C-00000074] file.c: – <SIP/X.X.X.40-0000007d> Playing ‘check-number-dial-again.ulaw’ (language ‘en’)
[2014-02-12 12:43:18] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:7] Wait(“SIP/X.X.X.40-0000007d”, “1”) in new stack
[2014-02-12 12:43:19] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:8] Congestion(“SIP/X.X.X.40-0000007d”, “20”) in new stack
[2014-02-12 12:43:19] VERBOSE[4848][C-00000074] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK107B33;received=X.X.X.40
From: sip:[email protected];tag=A54DB4F8-B2B
To: sip:[email protected];tag=as777cc5b1
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Content-Length: 0

<------------>
[2014-02-12 12:43:19] WARNING[4848][C-00000074] channel.c: Prodding channel ‘SIP/X.X.X.40-0000007d’ failed
[2014-02-12 12:43:19] VERBOSE[4848][C-00000074] pbx.c: == Spawn extension (from-internal, 8482477275, 8) exited non-zero on ‘SIP/X.X.X.40-0000007d’
[2014-02-12 12:43:19] VERBOSE[4848][C-00000074] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.40-0000007d”, “”) in new stack
[2014-02-12 12:43:19] VERBOSE[4848][C-00000074] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.40-0000007d’
[2014-02-12 12:43:19] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK107B33
From: sip:[email protected];tag=A54DB4F8-B2B
To: sip:[email protected];tag=as777cc5b1
Date: Wed, 12 Feb 2014 17:43:11 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:19] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:43:19] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2014-02-12 12:43:19] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC131E6B
Remote-Party-ID: sip:[email protected];party=calling;screen=yes;privacy=off
From: sip:[email protected];tag=A601C094-1758
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:19 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 14385447-3081941295-508363778-1138130216
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392226999
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:19] VERBOSE[1746] chan_sip.c: — (19 headers 0 lines) —
[2014-02-12 12:43:19] VERBOSE[1746] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:19] VERBOSE[1746][C-00000076] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:19] VERBOSE[1746][C-00000076] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:43:19] VERBOSE[1746][C-00000076] chan_sip.c: Found peer ‘X.X.X.10’ for ‘2485551212’ from X.X.X.10:64536
[2014-02-12 12:43:19] VERBOSE[1746][C-00000076] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:43:19] VERBOSE[1746][C-00000076] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:43:19] VERBOSE[1746][C-00000076] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:43:19] VERBOSE[1746][C-00000076] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:43:19] VERBOSE[1746][C-00000076] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC131E6B;received=X.X.X.10
From: sip:[email protected];tag=A601C094-1758
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:43:19] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.10-0000007f”, “”) in new stack
[2014-02-12 12:43:19] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.10-0000007f”, “”) in new stack
[2014-02-12 12:43:19] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.10-0000007f”, “”) in new stack
[2014-02-12 12:43:19] VERBOSE[4864][C-00000076] chan_sip.c: Audio is at 18484
[2014-02-12 12:43:19] VERBOSE[4864][C-00000076] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:43:19] VERBOSE[4864][C-00000076] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:43:19] VERBOSE[4864][C-00000076] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC131E6B;received=X.X.X.10
From: sip:[email protected];tag=A601C094-1758
To: sip:[email protected];tag=as4b0b9c7f
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 237

v=0
o=root 15701X.X.X.15701X.X.X.IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 18484 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:43:19] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.10-0000007f”, “1”) in new stack
[2014-02-12 12:43:20] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:5] Progress(“SIP/X.X.X.10-0000007f”, “”) in new stack
[2014-02-12 12:43:20] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:6] Playback(“SIP/X.X.X.10-0000007f”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2014-02-12 12:43:20] VERBOSE[4864][C-00000076] file.c: – <SIP/X.X.X.10-0000007f> Playing ‘silence/1.ulaw’ (language ‘en’)
[2014-02-12 12:43:21] VERBOSE[4864][C-00000076] file.c: – <SIP/X.X.X.10-0000007f> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2014-02-12 12:43:24] VERBOSE[4864][C-00000076] file.c: – <SIP/X.X.X.10-0000007f> Playing ‘check-number-dial-again.ulaw’ (language ‘en’)
[2014-02-12 12:43:26] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:7] Wait(“SIP/X.X.X.10-0000007f”, “1”) in new stack
[2014-02-12 12:43:27] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:8] Congestion(“SIP/X.X.X.10-0000007f”, “20”) in new stack
[2014-02-12 12:43:27] VERBOSE[4864][C-00000076] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC131E6B;received=X.X.X.10
From: sip:[email protected];tag=A601C094-1758
To: sip:[email protected];tag=as4b0b9c7f
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Content-Length: 0

<------------>
[2014-02-12 12:43:27] WARNING[4864][C-00000076] channel.c: Prodding channel ‘SIP/X.X.X.10-0000007f’ failed
[2014-02-12 12:43:27] VERBOSE[4864][C-00000076] pbx.c: == Spawn extension (from-internal, 8482477275, 8) exited non-zero on ‘SIP/X.X.X.10-0000007f’
[2014-02-12 12:43:27] VERBOSE[4864][C-00000076] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.10-0000007f”, “”) in new stack
[2014-02-12 12:43:27] VERBOSE[4864][C-00000076] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.10-0000007f’
[2014-02-12 12:43:27] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC131E6B
From: sip:[email protected];tag=A601C094-1758
To: sip:[email protected];tag=as4b0b9c7f
Date: Wed, 12 Feb 2014 17:43:19 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:27] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:43:27] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2014-02-12 12:43:27] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK10814FB
Remote-Party-ID: sip:[email protected];party=calling;screen=yes;privacy=off
From: sip:[email protected];tag=A54DF388-1BB
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:27 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 0009389612-3216159023-0318768898-1138130186
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392227007
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:27] VERBOSE[1746] chan_sip.c: — (19 headers 0 lines) —
[2014-02-12 12:43:27] VERBOSE[1746] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:43:27] VERBOSE[1746][C-00000077] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:43:27] VERBOSE[1746][C-00000077] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:43:27] VERBOSE[1746][C-00000077] chan_sip.c: Found peer ‘X.X.X.40’ for ‘2485551212’ from X.X.X.40:60518
[2014-02-12 12:43:27] VERBOSE[1746][C-00000077] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:43:27] VERBOSE[1746][C-00000077] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:43:27] VERBOSE[1746][C-00000077] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:43:27] VERBOSE[1746][C-00000077] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:43:27] VERBOSE[1746][C-00000077] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK10814FB;received=X.X.X.40
From: sip:[email protected];tag=A54DF388-1BB
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:43:27] VERBOSE[4865][C-00000077] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.40-00000080”, “”) in new stack
[2014-02-12 12:43:27] VERBOSE[4865][C-00000077] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.40-00000080”, “”) in new stack
[2014-02-12 12:43:27] VERBOSE[4865][C-00000077] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.40-00000080”, “”) in new stack
[2014-02-12 12:43:27] VERBOSE[4865][C-00000077] chan_sip.c: Audio is at 16462
[2014-02-12 12:43:27] VERBOSE[4865][C-00000077] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:43:27] VERBOSE[4865][C-00000077] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:43:27] VERBOSE[4865][C-00000077] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK10814FB;received=X.X.X.40
From: sip:[email protected];tag=A54DF388-1BB
To: sip:[email protected];tag=as2a9e8c24
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 235

v=0
o=root 695473181 695473181 IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 16462 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:43:27] VERBOSE[4865][C-00000077] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.40-00000080”, “1”) in new stack
[2014-02-12 12:43:28] VERBOSE[4865][C-00000077] pbx.c: – Executing [[email protected]:5] Progress(“SIP/X.X.X.40-00000080”, “”) in new stack
[2014-02-12 12:43:28] VERBOSE[4865][C-00000077] pbx.c: – Executing [[email protected]:6] Playback(“SIP/X.X.X.40-00000080”, “silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer”) in new stack
[2014-02-12 12:43:28] VERBOSE[4865][C-00000077] file.c: – <SIP/X.X.X.40-00000080> Playing ‘silence/1.ulaw’ (language ‘en’)
[2014-02-12 12:43:29] VERBOSE[4865][C-00000077] file.c: – <SIP/X.X.X.40-00000080> Playing ‘cannot-complete-as-dialed.ulaw’ (language ‘en’)
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
CANCEL sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK10814FB
From: sip:[email protected];tag=A54DF388-1BB
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:27 GMT
Call-ID: [email protected]
CSeq: 101 CANCEL
Max-Forwards: 70
Timestamp: 1392227010
Reason: Q.850;cause=47
Content-Length: 0

<------------->
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c: — (11 headers 0 lines) —
[2014-02-12 12:43:30] VERBOSE[1746][C-00000077] chan_sip.c: Sending to X.X.X.40:5060 (no NAT)
[2014-02-12 12:43:30] VERBOSE[1746][C-00000077] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK10814FB;received=X.X.X.40
From: sip:[email protected];tag=A54DF388-1BB
To: sip:[email protected];tag=as2a9e8c24
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
[2014-02-12 12:43:30] VERBOSE[1746][C-00000077] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.40:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK10814FB;received=X.X.X.40
From: sip:[email protected];tag=A54DF388-1BB
To: sip:[email protected];tag=as2a9e8c24
Call-ID: [email protected]
CSeq: 101 CANCEL
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
[2014-02-12 12:43:30] VERBOSE[4865][C-00000077] pbx.c: == Spawn extension (from-internal, 8482477275, 6) exited non-zero on ‘SIP/X.X.X.40-00000080’
[2014-02-12 12:43:30] VERBOSE[4865][C-00000077] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.40-00000080”, “”) in new stack
[2014-02-12 12:43:30] VERBOSE[4865][C-00000077] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.40-00000080’
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.40:60518 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.40:5060;branch=z9hG4bK10814FB
From: sip:[email protected];tag=A54DF388-1BB
To: sip:[email protected];tag=as2a9e8c24
Date: Wed, 12 Feb 2014 17:43:27 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC141158
Remote-Party-ID: sip:[email protected];party=calling;screen=yes;privacy=off
From: sip:[email protected];tag=A601EA58-22F9
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:30 GMT
Call-ID: [email protected]
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 2152861742-3266490671-541919234-1138130216
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Max-Forwards: 70
Timestamp: 1392227010
Contact: sip:[email protected]:5060
Expires: 180
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c: — (19 headers 0 lines) —
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c: Using INVITE request as basis request - [email protected]
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c: Found peer ‘X.X.X.10’ for ‘2485551212’ from X.X.X.10:64536
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] netsock2.c: == Using SIP RTP TOS bits 184
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] netsock2.c: == Using SIP RTP CoS mark 5
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c: Looking for 8482477275 in from-internal (domain X.X.X.57)
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c: list_route: hop: sip:[email protected]:5060
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC141158;received=X.X.X.10
From: sip:[email protected];tag=A601EA58-22F9
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] pbx.c: – Executing [[email protected]:1] ResetCDR(“SIP/X.X.X.10-00000081”, “”) in new stack
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] pbx.c: – Executing [[email protected]:2] NoCDR(“SIP/X.X.X.10-00000081”, “”) in new stack
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] pbx.c: – Executing [[email protected]:3] Progress(“SIP/X.X.X.10-00000081”, “”) in new stack
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] chan_sip.c: Audio is at 19180
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC141158;received=X.X.X.10
From: sip:[email protected];tag=A601EA58-22F9
To: sip:[email protected];tag=as60a409cf
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Require: timer
Content-Length: 237

v=0
o=root 1938395423 1938395423 IN IP4 X.X.X.57
s=Asterisk PBX 11.7.0
c=IN IP4 X.X.X.57
t=0 0
m=audio 19180 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] pbx.c: – Executing [[email protected]:4] Wait(“SIP/X.X.X.10-00000081”, “1”) in new stack
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
CANCEL sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC141158
From: sip:[email protected];tag=A601EA58-22F9
To: sip:[email protected]
Date: Wed, 12 Feb 2014 17:43:30 GMT
Call-ID: [email protected]
CSeq: 101 CANCEL
Max-Forwards: 70
Timestamp: 1392227010
Reason: Q.850;cause=47
Content-Length: 0

<------------->
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c: — (11 headers 0 lines) —
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c: Sending to X.X.X.10:5060 (no NAT)
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c:
<— Reliably Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC141158;received=X.X.X.10
From: sip:[email protected];tag=A601EA58-22F9
To: sip:[email protected];tag=as60a409cf
Call-ID: [email protected]
CSeq: 101 INVITE
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
[2014-02-12 12:43:30] VERBOSE[1746][C-00000078] chan_sip.c:
<— Transmitting (no NAT) to X.X.X.10:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC141158;received=X.X.X.10
From: sip:[email protected];tag=A601EA58-22F9
To: sip:[email protected];tag=as60a409cf
Call-ID: [email protected]
CSeq: 101 CANCEL
Server: FPBX-2.11.0(11.7.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] pbx.c: == Spawn extension (from-internal, 8482477275, 4) exited non-zero on ‘SIP/X.X.X.10-00000081’
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] pbx.c: – Executing [[email protected]:1] Hangup(“SIP/X.X.X.10-00000081”, “”) in new stack
[2014-02-12 12:43:30] VERBOSE[4866][C-00000078] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/X.X.X.10-00000081’
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c:
<— SIP read from UDP:X.X.X.10:64536 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP X.X.X.10:5060;branch=z9hG4bKC141158
From: sip:[email protected];tag=A601EA58-22F9
To: sip:[email protected];tag=as60a409cf
Date: Wed, 12 Feb 2014 17:43:30 GMT
Call-ID: [email protected]
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

<------------->
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c: — (10 headers 0 lines) —
[2014-02-12 12:43:30] VERBOSE[1746] chan_sip.c: Really destroying SIP dialog ‘[email protected]’ Method: ACK

Any help would be appreciated. It has to be something simple.
Thanks!
FreePBX4Life