• V20: 3CX Re-engineered. Get V20 for increased security, better call management, a new admin console and Windows softphone. Learn More.

Calls that don't connect

Status
Not open for further replies.

AndrewT

Customer
Joined
Oct 7, 2015
Messages
19
Reaction score
0
We have an occasional problem with calls that come to an extension from outside DIDs on the 3CX windows softphones V12.5. The call comes into the extension, we click answer and the keypad changes like you have answered the call, but the call never connects. This has happened on multiple extensions over the last couple days.

I had this happen to me while I was making a test call to my extension from my cell phone. From the incoming callers end, all I heard was the hold music before the system eventually cut me off. From the receiving side, once I pressed the answer button, there was nothing I could do to make the call connect. I didn't press the End Call button ever, but eventually the call ended itself.

Here is a part of the server log that I couldn't interpret.
Code:
09-Oct-2015 08:21:56.580   L:3236.1[Line:10001<<7632421106]: Terminating targets, reason: SIP ;cause=200 ;text="Call completed elsewhere"
09-Oct-2015 08:21:56.580   L:3236.3[Extn] has joined to L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:56.580   Session 519974 of leg L:3236.3[Extn] is connected

What does "Call completed elsewhere" mean?

Then this is the next log entry,
Code:
09-Oct-2015 08:21:56.784   [CM503003]: Call(C:3236): Call to <sip:[email protected]:5060> has failed; Cause: 487 Request Terminated/INVITE from 127.0.0.1:5488
09-Oct-2015 08:21:56.784   Session 519976 has failed in leg L:3236.4[Extn] ; Cause: 487 Request Terminated/INVITE from xxx.0.0.1:5488
09-Oct-2015 08:21:56.585   [CM503007]: Call(C:3236): Extn:102 has joined, contact <sip:[email protected]>

And it continues to disconnect from there.

Any ideas out there?
 
It would help to have the entire log of the call. Call completed elsewhere is indicative of a leg of the call not being completed as a another different leg took the call.

A gross example might be if you have two devices with the same extension (sip forking). A call comes in and rings both devices but one answers. The system then terminates the leg to the second device as there is no need to continue.

There are other scenarios, but maybe this will give an idea......call pickup, forwarding, etc. Other possibilities.....phone make and model and full log of call.
 
Here is the full log. We are only using the 3CX Softphone for Windows, no hard phones.

Code:
09-Oct-2015 08:22:28.590   L:3236.1[Line:10001<<7632421106]: Terminating targets, reason:
09-Oct-2015 08:22:28.590   Leg L:3236.1[Line:10001<<7632421106] is terminated: Cause: BYE from PBX
09-Oct-2015 08:22:28.590   L:3236.1[Line:10001<<7632421106] Sending: OnSendReq Send Req BYE from 0.0.0.0:0 tid=3f72df182410c80b [email protected]:
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-3f72df182410c80b-1---d8754z-;rport
Max-Forwards: 70
Route: <sip:63.247.69.226;lr;ftag=gK0744ff49;vsf=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAO2lzdXAtb2xpPTA7cHN0bi1wYXJhbXM9ODA4NDgxODA4ODgyO2NwYz1vcmRpbmFyeQ--;dlgcor=0de.f5d2>
Contact: <sip:[email protected]:5060>
To: "WIRELESS CALLER "<sip:[email protected]>;tag=gK0744ff49
From: <sip:[email protected]>;tag=0d707603
Call-ID: [email protected]
CSeq: 3 BYE
Content-Length: 0
09-Oct-2015 08:22:28.590   [CM503008]: Call(C:3236): Call is terminated
09-Oct-2015 08:22:28.589   L:3236.1[Line:10001<<7632421106]: Terminating targets, reason: SIP ;cause=200 ;text="Call terminated on user request"
09-Oct-2015 08:22:28.584   Blocking refers for the Call(C:3236)
09-Oct-2015 08:22:28.584   Call(C:3236) is terminated
09-Oct-2015 08:22:28.583   L:3236.3[Extn]: Terminating targets, reason: SIP ;cause=200 ;text="User hangup"
09-Oct-2015 08:22:28.583   Leg L:3236.3[Extn] is terminated: Cause: BYE from xx.xx.xx.xx:57803
09-Oct-2015 08:22:28.581   L:3236.3[Extn] got Terminated Recv Req BYE from xx.xx.xx.xx:57803 tid=Pjaa9816309d17449fb2d071ec25c7805d Call-ID=NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.:
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP xx.xx.xx.xx:57803;rport=57803;branch=z9hG4bKPjaa9816309d17449fb2d071ec25c7805d
Max-Forwards: 70
To: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=7870b31a
From: <sip:[email protected]:5060>;tag=d6addaec5662409fadf96f65867af5b5
Call-ID: NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.
CSeq: 9930 BYE
Content-Length: 0
09-Oct-2015 08:22:28.581   L:3236.3[Extn] Sending: OnSendResp Send 200/BYE from 0.0.0.0:0 tid=Pjaa9816309d17449fb2d071ec25c7805d Call-ID=NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:57803;rport=57803;branch=z9hG4bKPjaa9816309d17449fb2d071ec25c7805d
Contact: <sip:[email protected]:5060>
To: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=7870b31a
From: <sip:[email protected]:5060>;tag=d6addaec5662409fadf96f65867af5b5
Call-ID: NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.
CSeq: 9930 BYE
Content-Length: 0
09-Oct-2015 08:21:56.802   L:3236.2[Ivr]: Terminating targets, reason: SIP ;cause=200 ;text="User hangup"
09-Oct-2015 08:21:56.802   Leg L:3236.2[Ivr] is terminated: Cause: BYE from xx.xx.xx.xx:40600
09-Oct-2015 08:21:56.800   L:3236.2[Ivr] got Terminated Recv Req BYE from xx.xx.xx.xx:40600 tid=084cc22c47271579 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP xx.xx.xx.xx:40600;branch=z9hG4bK-d8754z-084cc22c47271579-1---d8754z-;rport=40600
Max-Forwards: 70
Contact: <sip:[email protected]:40600;rinstance=8d5dc13d74ad918b>
To: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
From: <sip:[email protected]:5060>;tag=58551a32
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 5 BYE
User-Agent: 3CX IVR
Reason: SIP;description="User Hung Up"
Content-Length: 0
09-Oct-2015 08:21:56.799   L:3236.2[Ivr] Sending: OnSendResp Send 200/BYE from 0.0.0.0:0 tid=084cc22c47271579 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:40600;branch=z9hG4bK-d8754z-084cc22c47271579-1---d8754z-;rport=40600
Contact: <sip:[email protected]:5060>
To: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
From: <sip:[email protected]:5060>;tag=58551a32
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 5 BYE
Content-Length: 0
09-Oct-2015 08:21:56.790   L:3236.4[Extn]: Terminating targets, reason: SIP ;cause=487 ;text="Request Terminated"
09-Oct-2015 08:21:56.789   Leg L:3236.4[Extn] is terminated: Cause: 487 Request Terminated/INVITE from xx.xx.xx.xx:5488
09-Oct-2015 08:21:56.785   L:3236.4[Extn] got Terminated Recv 487/INVITE from xx.xx.xx.xx:5488 tid=7a315a15e06e8f6a Call-ID=N2ZmMWFkYTUyOGExYTc5OTUyYTBlZWM0ZWMxZTllNDc.:
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK-d8754z-7a315a15e06e8f6a-1---d8754z-;rport=5060
To: <sip:[email protected]>;tag=ca4cf06b
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=6f60eb49
Call-ID: N2ZmMWFkYTUyOGExYTc5OTUyYTBlZWM0ZWMxZTllNDc.
CSeq: 1 INVITE
User-Agent: 3CX Push Service
Content-Length: 0
09-Oct-2015 08:21:56.784   L:3236.4[Extn] got Failure: Failure Recv 487/INVITE from xx.xx.xx.xx:5488 tid=7a315a15e06e8f6a Call-ID=N2ZmMWFkYTUyOGExYTc5OTUyYTBlZWM0ZWMxZTllNDc.:
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK-d8754z-7a315a15e06e8f6a-1---d8754z-;rport=5060
To: <sip:[email protected]>;tag=ca4cf06b
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=6f60eb49
Call-ID: N2ZmMWFkYTUyOGExYTc5OTUyYTBlZWM0ZWMxZTllNDc.
CSeq: 1 INVITE
User-Agent: 3CX Push Service
Content-Length: 0
09-Oct-2015 08:21:56.784   [CM503003]: Call(C:3236): Call to <sip:[email protected]:5060> has failed; Cause: 487 Request Terminated/INVITE from xx.xx.xx.xx:5488
09-Oct-2015 08:21:56.784   Session 519976 has failed in leg L:3236.4[Extn] ; Cause: 487 Request Terminated/INVITE from xx.xx.xx.xx:5488
09-Oct-2015 08:21:56.585   [CM503007]: Call(C:3236): Extn:102 has joined, contact <sip:[email protected]:57803>
09-Oct-2015 08:21:56.583   L:3236.1[Line:10001<<7632421106] Sending: OnSendResp Send 200/INVITE from 0.0.0.0:0 tid=ff49.9c4f157116257bee292b1be83cc1fb08.0 [email protected]:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 63.247.69.226;branch=z9hG4bKff49.9c4f157116257bee292b1be83cc1fb08.0
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK07Bb2317e186f063161
Record-Route: <sip:63.247.69.226;lr=on;ftag=gK0744ff49;vsf=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAO2lzdXAtb2xpPTA7cHN0bi1wYXJhbXM9ODA4NDgxODA4ODgyO2NwYz1vcmRpbmFyeQ--;dlgcor=0de.f5d2>
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]>;tag=0d707603
From: <sip:[email protected]>;tag=gK0744ff49
Call-ID: [email protected]
CSeq: 29738 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CXPhoneSystem 12.5.44178.1002 (40218)
Content-Length: 272

v=0
o=3cxPS 98683584512 416007847939 IN IP4 xx.xx.xx.xx
s=3cxPS Audio call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 9074 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendonly
09-Oct-2015 08:21:56.580   L:3236.1[Line:10001<<7632421106]: Terminating targets, reason: SIP ;cause=200 ;text="Call completed elsewhere"
09-Oct-2015 08:21:56.580   L:3236.3[Extn] has joined to L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:56.580   Session 519974 of leg L:3236.3[Extn] is connected
09-Oct-2015 08:21:56.580   L:3236.3[Extn] got Connected.UAC Recv 200/INVITE from xx.xx.xx.xx:57803 tid=0974895bf46e7017 Call-ID=NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;rport=5060;received=xx.xx.xx.xx;branch=z9hG4bK-d8754z-0974895bf46e7017-1---d8754z-
Contact: "Andrew Tapper"<sip:[email protected]:57803;ob>
To: <sip:[email protected]:5060>;tag=d6addaec5662409fadf96f65867af5b5
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=7870b31a
Call-ID: NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.
CSeq: 1 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Type: application/sdp
Supported: replaces, 100rel, timer, norefersub
Content-Length: 349

v=0
o=- 3653367716 3653367716 IN IP4 xx.xx.xx.xx
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 42002 RTP/AVP 0 8 3 96
c=IN IP4 xx.xx.xx.xx
b=TIAS:64000
b=AS:84
a=rtcp:42003
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-15
m=video 0 RTP/AVP 31
c=IN IP4 xx.xx.xx.xx
09-Oct-2015 08:21:56.580   Remote SDP is set for leg L:3236.3[Extn]
09-Oct-2015 08:21:56.580   Offer SDP arrived on Leg L:3236.3[Extn];
OnOffer Recv 200/INVITE from xx.xx.xx.xx:57803 tid=0974895bf46e7017 Call-ID=NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;rport=5060;received=xx.xx.xx.xx;branch=z9hG4bK-d8754z-0974895bf46e7017-1---d8754z-
Contact: "Andrew Tapper"<sip:[email protected]:57803;ob>
To: <sip:[email protected]:5060>;tag=d6addaec5662409fadf96f65867af5b5
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=7870b31a
Call-ID: NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.
CSeq: 1 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Type: application/sdp
Supported: replaces, 100rel, timer, norefersub
Content-Length: 349

v=0
o=- 3653367716 3653367716 IN IP4 xx.xx.xx.xx
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 42002 RTP/AVP 0 8 3 96
c=IN IP4 xx.xx.xx.xx
b=TIAS:64000
b=AS:84
a=rtcp:42003
a=sendrecv
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-15
m=video 0 RTP/AVP 31
c=IN IP4 xx.xx.xx.xx
09-Oct-2015 08:21:53.909   L:3236.2[Ivr] Sending: OnSendResp Send 200/INVITE from 0.0.0.0:0 tid=4a3f9e5716794f40 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:40600;branch=z9hG4bK-d8754z-4a3f9e5716794f40-1---d8754z-;rport=40600
Contact: <sip:[email protected]:5060>
To: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
From: <sip:[email protected]:5060>;tag=58551a32
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CXPhoneSystem 12.5.44178.1002 (40218)
Content-Length: 272

v=0
o=3cxPS 101250498560 149183004674 IN IP4 xx.xx.xx.xx
s=3cxPS Audio call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 7298 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=recvonly
09-Oct-2015 08:21:53.809   Provisional response arrived for session 519976 of Leg L:3236.4[Extn]
09-Oct-2015 08:21:53.809   L:3236.4[Extn] got Provisional Recv 180/INVITE from xx.xx.xx.xx:5488 tid=7a315a15e06e8f6a Call-ID=N2ZmMWFkYTUyOGExYTc5OTUyYTBlZWM0ZWMxZTllNDc.:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK-d8754z-7a315a15e06e8f6a-1---d8754z-;rport=5060
Contact: <sip:[email protected]:5488;rinstance=78d4d0f1adbcbbe3>
To: <sip:[email protected]>;tag=ca4cf06b
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=6f60eb49
Call-ID: N2ZmMWFkYTUyOGExYTc5OTUyYTBlZWM0ZWMxZTllNDc.
CSeq: 1 INVITE
User-Agent: 3CX Push Service
Content-Length: 0
09-Oct-2015 08:21:53.809   [CM503002]: Call(C:3236): Alerting Extn:102 by contact <sip:[email protected]:5488>
09-Oct-2015 08:21:53.807   UacSession 519976 has formed leg L:3236.4[Extn]
09-Oct-2015 08:21:53.708   Provisional response arrived for session 519974 of Leg L:3236.3[Extn]
09-Oct-2015 08:21:53.708   L:3236.3[Extn] got Provisional Recv 180/INVITE from xx.xx.xx.xx:57803 tid=0974895bf46e7017 Call-ID=NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;rport=5060;received=xx.xx.xx.xx;branch=z9hG4bK-d8754z-0974895bf46e7017-1---d8754z-
Contact: "Andrew Tapper"<sip:[email protected]:57803;ob>
To: <sip:[email protected]:5060>;tag=d6addaec5662409fadf96f65867af5b5
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=7870b31a
Call-ID: NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.
CSeq: 1 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length: 0
09-Oct-2015 08:21:53.708   [CM503002]: Call(C:3236): Alerting Extn:102 by contact <sip:[email protected]:57803>
09-Oct-2015 08:21:53.705   UacSession 519974 has formed leg L:3236.3[Extn]
09-Oct-2015 08:21:53.705   Reneg. from L:3236.2[Ivr], answer
09-Oct-2015 08:21:53.705   Leg L:3236.2[Ivr] raised event RecvdRemoteSdp
09-Oct-2015 08:21:53.705   Remote SDP is set for leg L:3236.2[Ivr]
09-Oct-2015 08:21:53.705   Answer SDP arrived on Leg L:3236.2[Ivr];
OnAnswer Recv 200/INVITE from xx.xx.xx.xx:40600 tid=093e9b2f5e1f2f0d Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK-d8754z-093e9b2f5e1f2f0d-1---d8754z-;rport=5060
Contact: <sip:[email protected]:40600;rinstance=8d5dc13d74ad918b>
To: <sip:[email protected]:5060>;tag=58551a32
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CX IVR
Content-Length: 269

v=0
o=3cxVCE 282848640 176385362 IN IP4 xx.xx.xx.xx
s=3cxVCE Audio Call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 40666 RTP/AVP 0 8 9 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=sendonly
09-Oct-2015 08:21:53.705   L:3236.2[Ivr] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=b9506d0b3977ad29 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
ACK sip:[email protected]:40600;rinstance=8d5dc13d74ad918b SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-b9506d0b3977ad29-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>;tag=58551a32
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 2 ACK
Content-Length: 0
09-Oct-2015 08:21:53.657   [CM503025]: Call(C:3236): Calling T:Extn:102@[Dev:sip:[email protected]:57803;ob,Dev:sip:[email protected]:5488;rinstance=78d4d0f1adbcbbe3] for L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:53.657   Route to L:3236.4[Extn] sends Invite-OUT Send Req INVITE from 0.0.0.0:0 tid=975151324255f427 Call-ID=N2ZmMWFkYTUyOGExYTc5OTUyYTBlZWM0ZWMxZTllNDc.:
INVITE sip:[email protected]:5488;rinstance=78d4d0f1adbcbbe3 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-975151324255f427-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]>
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=6f60eb49
Call-ID: N2ZmMWFkYTUyOGExYTc5OTUyYTBlZWM0ZWMxZTllNDc.
CSeq: 1 INVITE
Alert-Info: <http://www.notused.invalidtld>;info=external
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Supported: replaces
Referred-By: <sip:[email protected]:5060>
Content-Length: 0
09-Oct-2015 08:21:53.652   Added leg L:3236.4[Extn]
09-Oct-2015 08:21:53.652   [CM503025]: Call(C:3236): Calling T:Extn:102@[Dev:sip:[email protected]:57803;ob,Dev:sip:[email protected]:5488;rinstance=78d4d0f1adbcbbe3] for L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:53.652   Route to L:3236.3[Extn] sends Invite-OUT Send Req INVITE from 0.0.0.0:0 tid=0d633e4be421b73c Call-ID=NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.:
INVITE sip:[email protected]:57803;ob SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-0d633e4be421b73c-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=7870b31a
Call-ID: NjQ3YzU3ZGVkYjQ2MmM4YzRhMDIyN2Q1YTM0YThhZWI.
CSeq: 1 INVITE
Alert-Info: <http://www.notused.invalidtld>;info=external
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Supported: replaces
Referred-By: <sip:[email protected]:5060>
Content-Length: 0

09-Oct-2015 08:21:53.647   Added leg L:3236.3[Extn]
09-Oct-2015 08:21:53.598   [Flow] Call(C:3236): making call from L:3236.1[Line:10001<<7632421106] to T:Extn:102@[Dev:sip:[email protected]:57803;ob,Dev:sip:[email protected]:5488;rinstance=78d4d0f1adbcbbe3]
09-Oct-2015 08:21:53.598   [CM503027]: Call(C:3236): From: Line:10001<<7632421106 ("Andrew Tapper" <sip:[email protected]:5060>)  to  T:Extn:102@[Dev:sip:[email protected]:57803;ob,Dev:sip:[email protected]:5488;rinstance=78d4d0f1adbcbbe3]
09-Oct-2015 08:21:53.598   [CM503004]: Call(C:3236): Route 1: from L:3236.1[Line:10001<<7632421106] to T:Extn:102@[Dev:sip:[email protected]:57803;ob,Dev:sip:[email protected]:5488;rinstance=78d4d0f1adbcbbe3]
09-Oct-2015 08:21:53.598   [Flow] Call(C:3236): has built target endpoint: Extn:102 for call from L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:53.597   [CM503010]: Call(C:3236): Making route(s) from Line:10001<<7632421106 to <sip:[email protected]:5060>
09-Oct-2015 08:21:53.597   L:3236.1[Line:10001<<7632421106]:  Updated OutCID: '17632517550' and DNName: 'Andrew Tapper'
09-Oct-2015 08:21:53.597   Call(C:3236): Referred(i): src="Andrew Tapper"<sip:[email protected]:5060;nf=e>; dst=<sip:[email protected]:5060>
09-Oct-2015 08:21:53.597   Call transfer requested on Leg L:3236.2[Ivr];
Refer Recv Req REFER from xx.xx.xx.xx:40600 tid=c938e80a5a1d7a19 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
REFER sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP xx.xx.xx.xx:40600;branch=z9hG4bK-d8754z-c938e80a5a1d7a19-1---d8754z-;rport=40600
Max-Forwards: 70
Contact: <sip:[email protected]:40600;rinstance=8d5dc13d74ad918b>
To: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
From: <sip:[email protected]:5060>;tag=58551a32
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 3 REFER
User-Agent: 3CX IVR
Refer-To: <sip:[email protected]:5060>
Referred-By: <sip:[email protected]:5060>
Content-Length: 0
09-Oct-2015 08:21:53.561   L:3236.2[Ivr] Sending: OnSendReq Send Req INVITE from 0.0.0.0:0 tid=f50e7e6563016c51 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
INVITE sip:[email protected]:40600;rinstance=8d5dc13d74ad918b SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-f50e7e6563016c51-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>;tag=58551a32
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 2 INVITE
Alert-Info: <http://www.notused.invalidtld>;info=external
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CXPhoneSystem 12.5.44178.1002 (40218)
Content-Length: 272

v=0
o=3cxPS 101250498560 149183004675 IN IP4 xx.xx.xx.xx
s=3cxPS Audio call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 7298 RTP/AVP 0 8 9 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=recvonly
09-Oct-2015 08:21:53.559   Providing SDP for L:3236.2[Ivr]
09-Oct-2015 08:21:53.559   Reneg. from L:3236.1[Line:10001<<7632421106], offer
09-Oct-2015 08:21:53.559   Leg L:3236.1[Line:10001<<7632421106] raised event RecvdRemoteSdp
09-Oct-2015 08:21:53.559   Remote SDP is set for leg L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:53.559   Offer SDP arrived on Leg L:3236.1[Line:10001<<7632421106];
OnOffer Recv Req INVITE from 63.247.69.226:5060 tid=ff49.9c4f157116257bee292b1be83cc1fb08.0 [email protected]:
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 63.247.69.226;branch=z9hG4bKff49.9c4f157116257bee292b1be83cc1fb08.0
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK07Bb2317e186f063161
Max-Forwards: 32
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]>;tag=0d707603
From: <sip:[email protected]>;tag=gK0744ff49
Call-ID: [email protected]
CSeq: 29738 INVITE
Session-Expires: 1800;refresher=uac
Min-SE: 90
Accept: application/sdp, application/isup, application/dtmf, application/dtmf-relay, multipart/mixed
Allow: INVITE, ACK, CANCEL, BYE, REGISTER, REFER, INFO, SUBSCRIBE, NOTIFY, UPDATE, OPTIONS
Content-Disposition: session;handling=required
Content-Type: application/sdp
Supported: timer
Content-Length: 281

v=0
o=Sonus_UAC 26954 22931 IN IP4 xx.xx.xx.xx
s=SIP Media Capabilities
c=IN IP4 208.93.226.14
t=0 0
m=audio 25196 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=recvonly
a=ptime:20
09-Oct-2015 08:21:53.385   L:3236.2[Ivr] Sending: OnSendResp Send 200/INVITE from 0.0.0.0:0 tid=4a3f9e5716794f40 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:40600;branch=z9hG4bK-d8754z-4a3f9e5716794f40-1---d8754z-;rport=40600
Contact: <sip:[email protected]:5060>
To: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
From: <sip:[email protected]:5060>;tag=58551a32
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
Content-Length: 272

v=0
o=3cxPS 101250498560 149183004674 IN IP4 xx.xx.xx.xx
s=3cxPS Audio call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 7298 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=recvonly
09-Oct-2015 08:21:53.383   Providing SDP for L:3236.2[Ivr]
09-Oct-2015 08:21:53.383   Reneg. from L:3236.1[Line:10001<<7632421106], answer
09-Oct-2015 08:21:53.383   Leg L:3236.1[Line:10001<<7632421106] raised event RecvdRemoteSdp
09-Oct-2015 08:21:53.383   Remote SDP is set for leg L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:53.383   Answer SDP arrived on Leg L:3236.1[Line:10001<<7632421106];
OnAnswer Recv 200/INVITE from 63.247.69.226:5060 tid=ff49fc3b8d64402e [email protected]:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;received=xx.xx.xx.xx;branch=z9hG4bK-d8754z-ff49fc3b8d64402e-1---d8754z-;rport=5060
Contact: <sip:[email protected]:5060>
To: "WIRELESS CALLER "<sip:[email protected]>;tag=gK0744ff49
From: <sip:[email protected]>;tag=0d707603
Call-ID: [email protected]
CSeq: 2 INVITE
Session-Expires: 1800;refresher=uas
Accept: application/sdp, application/isup, application/dtmf, application/dtmf-relay, multipart/mixed
Allow: INVITE, ACK, CANCEL, BYE, REGISTER, REFER, INFO, SUBSCRIBE, NOTIFY, UPDATE, OPTIONS
Content-Disposition: session; handling=required
Content-Type: application/sdp
Supported: timer
Content-Length: 234

v=0
o=Sonus_UAC 26954 22930 IN IP4 xx.xx.xx.xx
s=SIP Media Capabilities
c=IN IP4 208.93.226.14
t=0 0
m=audio 25196 RTP/AVP 0 100
a=rtpmap:0 PCMU/8000
a=rtpmap:100 telephone-event/8000
a=fmtp:100 0-15
a=recvonly
a=ptime:20
09-Oct-2015 08:21:53.383   L:3236.1[Line:10001<<7632421106] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=c606a72e724ad022 [email protected]:
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-c606a72e724ad022-1---d8754z-;rport
Max-Forwards: 70
Route: <sip:63.247.69.226;lr;ftag=gK0744ff49;vsf=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAO2lzdXAtb2xpPTA7cHN0bi1wYXJhbXM9ODA4NDgxODA4ODgyO2NwYz1vcmRpbmFyeQ--;dlgcor=0de.f5d2>
Contact: <sip:[email protected]:5060>
To: "WIRELESS CALLER "<sip:[email protected]>;tag=gK0744ff49
From: <sip:[email protected]>;tag=0d707603
Call-ID: [email protected]
CSeq: 2 ACK
Content-Length: 0
09-Oct-2015 08:21:52.957   L:3236.1[Line:10001<<7632421106] Sending: OnSendReq Send Req INVITE from 0.0.0.0:0 tid=ec08c80ab9154b2a [email protected]:
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-ec08c80ab9154b2a-1---d8754z-;rport
Max-Forwards: 70
Route: <sip:63.247.69.226;lr;ftag=gK0744ff49;vsf=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAO2lzdXAtb2xpPTA7cHN0bi1wYXJhbXM9ODA4NDgxODA4ODgyO2NwYz1vcmRpbmFyeQ--;dlgcor=0de.f5d2>
Contact: <sip:[email protected]:5060>
To: "WIRELESS CALLER "<sip:[email protected]>;tag=gK0744ff49
From: <sip:[email protected]>;tag=0d707603
Call-ID: [email protected]
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
Content-Length: 272

v=0
o=3cxPS 98683584512 416007847938 IN IP4 xx.xx.xx.xx
s=3cxPS Audio call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 9074 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendonly
09-Oct-2015 08:21:52.955   Providing SDP for L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:52.955   Reneg. from L:3236.2[Ivr], offer
09-Oct-2015 08:21:52.955   Leg L:3236.2[Ivr] raised event RecvdRemoteSdp
09-Oct-2015 08:21:52.954   Remote SDP is set for leg L:3236.2[Ivr]
09-Oct-2015 08:21:52.954   Offer SDP arrived on Leg L:3236.2[Ivr];
OnOffer Recv Req INVITE from xx.xx.xx.xx:40600 tid=4a3f9e5716794f40 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP xx.xx.xx.xx:40600;branch=z9hG4bK-d8754z-4a3f9e5716794f40-1---d8754z-;rport=40600
Max-Forwards: 70
Contact: <sip:[email protected]:40600;rinstance=8d5dc13d74ad918b>
To: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
From: <sip:[email protected]:5060>;tag=58551a32
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CX IVR
Content-Length: 360

v=0
o=3cxVCE 282848640 176385361 IN IP4 xx.xx.xx.xx
s=3cxVCE Audio Call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 40666 RTP/AVP 0 8 3 13 9 110 99 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:13 CN/8000
a=rtpmap:9 G722/8000
a=rtpmap:110 iLBC/8000
a=rtpmap:99 SPEEX/8000
a=rtpmap:101 telephone-event/8000
a=ptime:20
a=sendonly
09-Oct-2015 08:21:41.984   Session 519956 of leg L:3236.1[Line:10001<<7632421106] is confirmed
09-Oct-2015 08:21:41.984   L:3236.1[Line:10001<<7632421106] got Confirmed Recv Req ACK from 63.247.69.226:5060 tid=1f49.a0a75ea718b85085893d01fbf61420c6.0 [email protected]:
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 63.247.69.226;branch=z9hG4bK1f49.a0a75ea718b85085893d01fbf61420c6.0
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK07Bb18231f06f063161
Max-Forwards: 32
To: <sip:[email protected]>;tag=0d707603
From: <sip:[email protected]>;tag=gK0744ff49
Call-ID: [email protected]
CSeq: 29737 ACK
Content-Length: 0
09-Oct-2015 08:21:41.812   [CM503007]: Call(C:3236): Ivr:701 has joined, contact <sip:[email protected]:40600>
09-Oct-2015 08:21:41.794   [CM503007]: Call(C:3236): Line:10001<<7632421106 has joined, contact <sip:[email protected]:5060>
09-Oct-2015 08:21:41.794   Session 519956 of leg L:3236.1[Line:10001<<7632421106] is connected
09-Oct-2015 08:21:41.794   L:3236.1[Line:10001<<7632421106] got Connected.UAS Send 200/INVITE from 0.0.0.0:0 tid=1f49.4116bce2d2cf10a57478a69c75eb981f.0 [email protected]:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 63.247.69.226;branch=z9hG4bK1f49.4116bce2d2cf10a57478a69c75eb981f.0
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK07Bb17d605e712a2bf4
Record-Route: <sip:63.247.69.226;lr=on;ftag=gK0744ff49;vsf=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAO2lzdXAtb2xpPTA7cHN0bi1wYXJhbXM9ODA4NDgxODA4ODgyO2NwYz1vcmRpbmFyeQ--;dlgcor=0de.f5d2>
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]>;tag=0d707603
From: "WIRELESS CALLER "<sip:[email protected]>;tag=gK0744ff49
Call-ID: [email protected]
CSeq: 29737 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CXPhoneSystem 12.5.44178.1002 (40218)
Content-Length: 272

v=0
o=3cxPS 98683584512 416007847937 IN IP4 xx.xx.xx.xx
s=3cxPS Audio call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 9074 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
09-Oct-2015 08:21:41.794   L:3236.1[Line:10001<<7632421106] Sending: OnSendResp Send 200/INVITE from 0.0.0.0:0 tid=1f49.4116bce2d2cf10a57478a69c75eb981f.0 [email protected]:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 63.247.69.226;branch=z9hG4bK1f49.4116bce2d2cf10a57478a69c75eb981f.0
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK07Bb17d605e712a2bf4
Record-Route: <sip:63.247.69.226;lr=on;ftag=gK0744ff49;vsf=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAO2lzdXAtb2xpPTA7cHN0bi1wYXJhbXM9ODA4NDgxODA4ODgyO2NwYz1vcmRpbmFyeQ--;dlgcor=0de.f5d2>
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]>;tag=0d707603
From: "WIRELESS CALLER "<sip:[email protected]>;tag=gK0744ff49
Call-ID: [email protected]
CSeq: 29737 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
Content-Length: 272

v=0
o=3cxPS 98683584512 416007847937 IN IP4 xx.xx.xx.xx
s=3cxPS Audio call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 9074 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
09-Oct-2015 08:21:41.791   L:3236.1[Line:10001<<7632421106]: Terminating targets, reason: SIP ;cause=200 ;text="Call completed elsewhere"
09-Oct-2015 08:21:41.791   L:3236.2[Ivr] has joined to L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:41.791   Session 519959 of leg L:3236.2[Ivr] is connected
09-Oct-2015 08:21:41.791   L:3236.2[Ivr] got Connected.UAC Recv 200/INVITE from xx.xx.xx.xx:40600 tid=4f468b182620cc4e Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK-d8754z-4f468b182620cc4e-1---d8754z-;rport=5060
Contact: <sip:[email protected]:40600;rinstance=8d5dc13d74ad918b>
To: <sip:[email protected]:5060>;tag=58551a32
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CX IVR
Content-Length: 257

v=0
o=3cxVCE 282848640 176385360 IN IP4 xx.xx.xx.xx
s=3cxVCE Audio Call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 40666 RTP/AVP 0 8 9 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000

09-Oct-2015 08:21:41.790   Remote SDP is set for leg L:3236.2[Ivr]
09-Oct-2015 08:21:41.790   Answer SDP arrived on Leg L:3236.2[Ivr];
OnAnswer Recv 200/INVITE from xx.xx.xx.xx:40600 tid=4f468b182620cc4e Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK-d8754z-4f468b182620cc4e-1---d8754z-;rport=5060
Contact: <sip:[email protected]:40600;rinstance=8d5dc13d74ad918b>
To: <sip:[email protected]:5060>;tag=58551a32
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CX IVR
Content-Length: 257

v=0
o=3cxVCE 282848640 176385360 IN IP4 xx.xx.xx.xx
s=3cxVCE Audio Call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 40666 RTP/AVP 0 8 9 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
09-Oct-2015 08:21:41.790   L:3236.2[Ivr] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=5159046dd622291f Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
ACK sip:[email protected]:40600;rinstance=8d5dc13d74ad918b SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-5159046dd622291f-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>;tag=58551a32
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 1 ACK
Content-Length: 0
09-Oct-2015 08:21:41.790   L:3236.1[Line:10001<<7632421106] Sending: OnSendResp Send 180/INVITE from 0.0.0.0:0 tid=1f49.4116bce2d2cf10a57478a69c75eb981f.0 [email protected]:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 63.247.69.226;branch=z9hG4bK1f49.4116bce2d2cf10a57478a69c75eb981f.0
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK07Bb17d605e712a2bf4
Record-Route: <sip:63.247.69.226;lr=on;ftag=gK0744ff49;vsf=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAO2lzdXAtb2xpPTA7cHN0bi1wYXJhbXM9ODA4NDgxODA4ODgyO2NwYz1vcmRpbmFyeQ--;dlgcor=0de.f5d2>
Contact: <sip:[email protected]:5060;rinstance=80e9bf0191a25d0a>
To: <sip:[email protected]>;tag=0d707603
From: "WIRELESS CALLER "<sip:[email protected]>;tag=gK0744ff49
Call-ID: [email protected]
CSeq: 29737 INVITE
Content-Length: 0
09-Oct-2015 08:21:41.790   Provisional response arrived for session 519959 of Leg L:3236.2[Ivr]
09-Oct-2015 08:21:41.790   L:3236.2[Ivr] got Provisional Recv 180/INVITE from xx.xx.xx.xx:40600 tid=4f468b182620cc4e Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK-d8754z-4f468b182620cc4e-1---d8754z-;rport=5060
Contact: <sip:[email protected]:40600;rinstance=8d5dc13d74ad918b>
To: <sip:[email protected]:5060>;tag=58551a32
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 1 INVITE
User-Agent: 3CX IVR
Content-Length: 0
09-Oct-2015 08:21:41.790   [CM503002]: Call(C:3236): Alerting Ivr:701 by contact <sip:[email protected]:40600>
09-Oct-2015 08:21:41.788   UacSession 519959 has formed leg L:3236.2[Ivr]
09-Oct-2015 08:21:41.647   [CM503025]: Call(C:3236): Calling T:Ivr:701@[Dev:sip:[email protected]:40600;rinstance=8d5dc13d74ad918b] for L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:41.647   Route to L:3236.2[Ivr] sends Invite-OUT Send Req INVITE from 0.0.0.0:0 tid=6f3d162e3a66e459 Call-ID=ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.:
INVITE sip:[email protected]:40600;rinstance=8d5dc13d74ad918b SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-6f3d162e3a66e459-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>
From: "Andrew Tapper"<sip:[email protected]:5060;nf=e>;tag=0053413d
Call-ID: ZmE3OGY0NGVjZTdhMzgwODExNzFmYmYxNzE2ODhmNTM.
CSeq: 1 INVITE
Alert-Info: <http://www.notused.invalidtld>;info=external
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
Content-Length: 272

v=0
o=3cxPS 101250498560 149183004673 IN IP4 xx.xx.xx.xx
s=3cxPS Audio call
c=IN IP4 xx.xx.xx.xx
t=0 0
m=audio 7298 RTP/AVP 0 8 9 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv
09-Oct-2015 08:21:41.642   Added leg L:3236.2[Ivr]
09-Oct-2015 08:21:41.637   [Flow] Call(C:3236): making call from L:3236.1[Line:10001<<7632421106] to T:Ivr:701@[Dev:sip:[email protected]:40600;rinstance=8d5dc13d74ad918b]
09-Oct-2015 08:21:41.637   [CM503027]: Call(C:3236): From: Line:10001<<7632421106 ("Andrew Tapper" <sip:[email protected]:5060>)  to  T:Ivr:701@[Dev:sip:[email protected]:40600;rinstance=8d5dc13d74ad918b]
09-Oct-2015 08:21:41.637   [CM503004]: Call(C:3236): Route 1: from L:3236.1[Line:10001<<7632421106] to T:Ivr:701@[Dev:sip:[email protected]:40600;rinstance=8d5dc13d74ad918b]
09-Oct-2015 08:21:41.636   [Flow] Call(C:3236): has built target endpoint: Ivr:701 for call from L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:41.636   [CM503010]: Call(C:3236): Making route(s) from Line:10001<<7632421106 to <sip:[email protected]:5060>
09-Oct-2015 08:21:41.636   Remote SDP is set for leg L:3236.1[Line:10001<<7632421106]
09-Oct-2015 08:21:41.634   [CM500002]: Call(C:3236): Info on incoming INVITE from Line:10001<<7632421106:
Invite-IN Recv Req INVITE from 63.247.69.226:5060 tid=1f49.4116bce2d2cf10a57478a69c75eb981f.0 [email protected]:
INVITE sip:[email protected]:5060;rinstance=80e9bf0191a25d0a SIP/2.0
Via: SIP/2.0/UDP 63.247.69.226;branch=z9hG4bK1f49.4116bce2d2cf10a57478a69c75eb981f.0
Via: SIP/2.0/UDP xx.xx.xx.xx:5060;branch=z9hG4bK07Bb17d605e712a2bf4
Max-Forwards: 17
Record-Route: <sip:63.247.69.226;lr;ftag=gK0744ff49;vsf=AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAO2lzdXAtb2xpPTA7cHN0bi1wYXJhbXM9ODA4NDgxODA4ODgyO2NwYz1vcmRpbmFyeQ--;dlgcor=0de.f5d2>
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]>
From: "WIRELESS CALLER "<sip:[email protected]>;tag=gK0744ff49
Call-ID: [email protected]
CSeq: 29737 INVITE
Session-Expires: 1800
Min-SE: 90
Accept: application/sdp, application/isup, application/dtmf, application/dtmf-relay, multipart/mixed
Allow: INVITE, ACK, CANCEL, BYE, REGISTER, REFER, INFO, SUBSCRIBE, NOTIFY, UPDATE, OPTIONS
Content-Disposition: session;handling=required
Content-Type: application/sdp
Supported: timer
Privacy: off
Content-Length: 281
Diversion: <sip:[email protected]:5060>;privacy=off;screen=no; reason=unconditional; counter=1

v=0
o=Sonus_UAC 26954 22929 IN IP4 xx.xx.xx.xx
s=SIP Media Capabilities
c=IN IP4 208.93.226.14
t=0 0
m=audio 25196 RTP/AVP 0 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=ptime:20
09-Oct-2015 08:21:41.634   [CM503001]: Call(C:3236): Incoming call from Line:10001<<7632421106 to <sip:[email protected]:5060>
09-Oct-2015 08:21:41.586   IncomingCall: C:3236 from <sip:[email protected]:5060> to <sip:[email protected]:5060>
09-Oct-2015 08:21:41.586   Added leg L:C:3236.1[No endpoint yet]
 
A few things -

It really helps to get the details of all aspects of the call. The logs show that the call came into an IVR, which was not mentioned originally. This then leaves open the question of whether the call really did connect as somehow the call appears to have been answered and then subsequently transferred to extension 102.

So, upon reaching the IVR did you hear any messages about how to make your selection or the "please wait while I transfer your call"? Did you input a response via the keypad to get request 102?

The call did reach 102, but as the IP addresses are completely masked, it is somewhat difficult to determine internal versus external.

This makes me think that perhaps there may be an issue in my understanding of how you described the problem.

What it really sounds like is that the call got answered and gave all the indications of being answered, but that there was no audio which is how you determine if the call really was completed. Because there was no audio, you thought there was no connection; hence "was nothing I could do to make the call connect. I didn't press the End Call button ever, but eventually the call ended itself.


Assuming this is correct, then it leads me to the RTP streams (audio).
What make and model of router and is SIP ALG off?
Is the provider coming in on a cable feed and if so, is the modem set to bridge mode and its internal SIP ALG off?

Have you run the firewall checker and does it pass?
For the extension of interest (102), what codec is in use and what does your SIP provider support?
IS the PBX provides audio check box on?
You may want to look and test the functionality of re-invites and replaces (turn off and on and test under each condition)

These are all conditions that may impact the call.
 
Status
Not open for further replies.
Get 3CX - Absolutely Free!

Link up your team and customers Phone System Live Chat Video Conferencing

Hosted or Self-managed. Up to 10 users free forever. No credit card. Try risk free.

3CX
A 3CX Account with that email already exists. You will be redirected to the Customer Portal to sign in or reset your password if you've forgotten it.