who ended this call?

Discussion in '3CX Phone System - General' started by cfive, Nov 21, 2012.

Thread Status:
Not open for further replies.
  1. cfive

    cfive Member

    Joined:
    Aug 20, 2009
    Messages:
    284
    Likes Received:
    6
    Hi, i could use a lesson in reading call logs - thanks to anyone who can teach me.

    Following is a call log; the call ended prematurely - as in the caller 'got cut off', and the person who answered said 'the call dropped'. Which end of this call caused it to end?

    The high level call routing inbound is PSTN > Patton 4114 > ext 401 > queue 500 > ext 221

    Code:
    21-Nov-2012 12:00:23.613	L:776.1[Line:10009<<2047271422]: Terminating targets, reason:
    21-Nov-2012 12:00:23.613	Leg L:776.1[Line:10009<<2047271422] is terminated: Cause: BYE from PBX
    21-Nov-2012 12:00:23.613	L:776.1[Line:10009<<2047271422] Sending: OnSendReq Send Req BYE from 0.0.0.0:0 tid=e31e624ddc14ad20 Call-ID=0ec5d086aaf4fbdd:
    			BYE sip:2047271422@192.168.16.103:5062 SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-e31e624ddc14ad20-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:221@192.168.16.100:5060>
    			To: "C.HARRIS"<sip:2047271422@192.168.16.103:5062>;tag=57d29224d5
    			From: "Chantale Juliak"<sip:221@192.168.16.100:5060>;tag=4922234b
    			Call-ID: 0ec5d086aaf4fbdd
    			CSeq: 3 BYE
    			Content-Length: 0
    21-Nov-2012 12:00:23.613	[CM503008]: Call(C:776): Call is terminated
    21-Nov-2012 12:00:23.613	L:776.1[Line:10009<<2047271422]: Terminating targets, reason: SIP ;cause=200 ;text="Call terminated on user request"
    21-Nov-2012 12:00:23.611	Blocking refers for the Call(C:776)
    21-Nov-2012 12:00:23.611	Call(C:776) is terminated
    21-Nov-2012 12:00:23.610	L:776.3[Extn]: Terminating targets, reason: SIP ;cause=200 ;text="User hangup"
    21-Nov-2012 12:00:23.610	Leg L:776.3[Extn] is terminated: Cause: BYE from 192.168.16.71:5060
    21-Nov-2012 12:00:23.609	L:776.3[Extn] got Terminated Recv Req BYE from 192.168.16.71:5060 tid=1694715409 Call-ID=YzlhZTk1Yjg0ZDdkM2U1ODc0YzFhM2FmYTBjNzEzYWE.:
    			BYE sip:2047271422@192.168.16.100:5060 SIP/2.0
    			Via: SIP/2.0/UDP 192.168.16.71:5060;branch=z9hG4bK1694715409;rport=5060
    			Max-Forwards: 70
    			Contact: <sip:221@192.168.16.71:5060;user=phone>
    			To: "C.HARRIS"<sip:2047271422@192.168.16.100:5060;nf=e>;tag=01431b2c
    			From: <sip:221@192.168.16.100>;tag=215449278
    			Call-ID: YzlhZTk1Yjg0ZDdkM2U1ODc0YzFhM2FmYTBjNzEzYWE.
    			CSeq: 2 BYE
    			Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
    			Supported: replaces, path, timer
    			User-Agent: Grandstream GXP1450 1.0.4.23
    			Content-Length: 0
    21-Nov-2012 12:00:23.609	L:776.3[Extn] Sending: OnSendResp Send 200/BYE from 0.0.0.0:0 tid=1694715409 Call-ID=YzlhZTk1Yjg0ZDdkM2U1ODc0YzFhM2FmYTBjNzEzYWE.:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 192.168.16.71:5060;branch=z9hG4bK1694715409;rport=5060
    			Contact: <sip:2047271422@192.168.16.100:5060>
    			To: "C.HARRIS"<sip:2047271422@192.168.16.100:5060;nf=e>;tag=01431b2c
    			From: <sip:221@192.168.16.100>;tag=215449278
    			Call-ID: YzlhZTk1Yjg0ZDdkM2U1ODc0YzFhM2FmYTBjNzEzYWE.
    			CSeq: 2 BYE
    			Content-Length: 0
    21-Nov-2012 12:00:09.507	L:776.3[Extn] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=8e7450033e041255 Call-ID=YzlhZTk1Yjg0ZDdkM2U1ODc0YzFhM2FmYTBjNzEzYWE.:
    			ACK sip:221@192.168.16.71:5060;user=phone SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-8e7450033e041255-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:2047271422@192.168.16.100:5060>
    			To: <sip:221@192.168.16.100>;tag=215449278
    			From: "C.HARRIS"<sip:2047271422@192.168.16.100:5060;nf=e>;tag=01431b2c
    			Call-ID: YzlhZTk1Yjg0ZDdkM2U1ODc0YzFhM2FmYTBjNzEzYWE.
    			CSeq: 1 ACK
    			Content-Type: application/sdp
    			Content-Length: 299
    			21-Nov-2012 11:59:21.509	[CM503001]: Call(C:776): Incoming call from Line:10009<<2047271422 to <sip:401@192.168.16.100:5060>
    21-Nov-2012 11:59:21.508	NAT/ALG check:L:776.1[Line:10009<<2047271422] REQUEST 'INVITE' - basic check passed. No information for extended checks
    21-Nov-2012 11:59:21.499	Offer SDP arrived on Leg L:776.1[Unknown:];
    OnOffer Recv Req INVITE from 192.168.16.103:5062 tid=99fab6cd13b3182cd Call-ID=0ec5d086aaf4fbdd:
    			INVITE sip:10009@192.168.16.100:5060 SIP/2.0
    			Via: SIP/2.0/UDP 192.168.16.103:5062;branch=z9hG4bK99fab6cd13b3182cd
    			Max-Forwards: 70
    			Contact: <sip:2047271422@192.168.16.103:5062>
    			To: <sip:anonymous@192.168.16.100:5060>
    			From: "C.HARRIS"<sip:2047271422@192.168.16.103:5062>;tag=57d29224d5
    			Call-ID: 0ec5d086aaf4fbdd
    			CSeq: 16370 INVITE
    			Content-Type: application/sdp
    			Proxy-Authorization: Digest username="10009",realm="3CXPhoneSystem",nonce="414d535c06bda77935:7bdb37973e4e45be34b3c7719fb32ec4",uri="sip:10009@192.168.16.100:5060",response="26191d98fe0c33e4e0248e180b61b2bb",algorithm=MD5
    			Supported: replaces
    			User-Agent: Patton SN4114 JO EUI 00A0BA079227 R6.2 2012-09-11 H323 SIP FXS FXO M5T SIP Stack/4.0.30.30
    			Content-Length: 271
    			
    			v=0
    			o=MxSIP 0 839 IN IP4 192.168.16.103
    			s=SIP Call
    			c=IN IP4 192.168.16.103
    			t=0 0
    			m=audio 4986 RTP/AVP 8 0 18 101
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:18 G729/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:18 annexb=no
    			a=fmtp:101 0-16
    			a=sendrecv
    21-Nov-2012 11:59:21.497	IncomingCall: C:776 from <sip:2047271422@192.168.16.103:5062> to <sip:10009@192.168.16.100:5060>
    21-Nov-2012 11:59:21.497	Added leg L:C:776.1[No endpoint yet]
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  2. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    10,757
    Likes Received:
    286
    Says it received a SIP 200 "BYE" message from this IP/port
     
  3. SY

    SY Well-Known Member
    3CX Support

    Joined:
    Jan 26, 2007
    Messages:
    1,825
    Likes Received:
    2
    leejor,
    +1
    Les, please use code tag when add quotation from the logs.
    Thanks :)
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  4. cfive

    cfive Member

    Joined:
    Aug 20, 2009
    Messages:
    284
    Likes Received:
    6
    Thanks leejor and Sy (I'll figure out the 'code' thingy ;))

    That's extension 221, the extension that received the outside call. The user of this extension is saying the 'call dropped', and has been saying that it happens fairly frequentlly. It's not intentional, so I suppose there are really only two possiblities?

    a) somehow, the user is not using the deskphone properly
    b) the deskphone is faulty?

    I suppose I have to go and watch what is being done.
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  5. SY

    SY Well-Known Member
    3CX Support

    Joined:
    Jan 26, 2007
    Messages:
    1,825
    Likes Received:
    2
    Just select the quotation from the log and press "Code" button above the Editing Window...
    For example:
    Code:
    This tesxt will appear as the "CODE: SELECT ALL" posted above
     

    Attached Files:

    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  6. cfive

    cfive Member

    Joined:
    Aug 20, 2009
    Messages:
    284
    Likes Received:
    6
    Something is fishy. It's not the user, nor the deskphone, nor the extension. Something deeper I fear, in the way I have calls routed or in the way that 3CX is "handling" the calls.

    The "code" snip below is the end of call #996. High level = PSTN > Patton 4114 > Ext. 401 > Q500, and eventually picked up by ext #222. After a few seconds, it dropped - if I read this right ext #222 went 'bye'. But I also see a "user hang up" and "call terminated on user request". But I cannot get my head around it. Again - I'm not sure what ended the call. The *persons* on both ends certainly did not, it's the machinery in between!.

    In between where call #996 started and ended, 3CX made calls #997, 1000, and 1001 on behalf of this call (forward, Q, Q trying extensions, etc. - but I don't find that odd and those calls are not shown when you "filter" the log.

    I get so confused looking at the logs - so thanks immensely for helping me out!.

    Code:
    21-Nov-2012 16:11:52.861	L:996.1[Line:10012<<2047297860]: Terminating targets, reason:
    21-Nov-2012 16:11:52.861	Leg L:996.1[Line:10012<<2047297860] is terminated: Cause: BYE from PBX
    21-Nov-2012 16:11:52.861	L:996.1[Line:10012<<2047297860] Sending: OnSendReq Send Req BYE from 0.0.0.0:0 tid=c165ba5f0d31957c Call-ID=9454bda87c2f76e5:
    			BYE sip:2047297860@192.168.16.101:5060 SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-c165ba5f0d31957c-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:221@192.168.16.100:5060>
    			To: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			From: "Brooke Ward"<sip:221@192.168.16.100:5060>;tag=16775139
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 3 BYE
    			Content-Length: 0
    21-Nov-2012 16:11:52.861	[CM503008]: Call(C:996): Call is terminated
    21-Nov-2012 16:11:52.861	L:996.1[Line:10012<<2047297860]: Terminating targets, reason: SIP ;cause=200 ;text="Call terminated on user request"
    21-Nov-2012 16:11:52.860	Blocking refers for the Call(C:996)
    21-Nov-2012 16:11:52.860	Call(C:996) is terminated
    21-Nov-2012 16:11:52.859	L:996.3[Extn]: Terminating targets, reason: SIP ;cause=200 ;text="User hangup"
    21-Nov-2012 16:11:52.859	Leg L:996.3[Extn] is terminated: Cause: BYE from 192.168.16.61:5060
    21-Nov-2012 16:11:52.857	L:996.3[Extn] got Terminated Recv Req BYE from 192.168.16.61:5060 tid=579229764 Call-ID=OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.:
    			BYE sip:2047297860@192.168.16.100:5060 SIP/2.0
    			Via: SIP/2.0/UDP 192.168.16.61:5060;branch=z9hG4bK579229764;rport=5060
    			Max-Forwards: 70
    			Contact: <sip:222@192.168.16.61:5060;user=phone>
    			To: <sip:2047297860@192.168.16.100:5060;nf=e>;tag=272d121f
    			From: <sip:222@192.168.16.100>;tag=467982843;intercom=true
    			Call-ID: OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.
    			CSeq: 2 BYE
    			Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
    			Supported: replaces, path, timer
    			User-Agent: Grandstream GXP1450 1.0.4.23
    			Content-Length: 0
    21-Nov-2012 16:11:52.857	L:996.3[Extn] Sending: OnSendResp Send 200/BYE from 0.0.0.0:0 tid=579229764 Call-ID=OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 192.168.16.61:5060;branch=z9hG4bK579229764;rport=5060
    			Contact: <sip:2047297860@192.168.16.100:5060>
    			To: <sip:2047297860@192.168.16.100:5060;nf=e>;tag=272d121f
    			From: <sip:222@192.168.16.100>;tag=467982843;intercom=true
    			Call-ID: OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.
    			CSeq: 2 BYE
    			Content-Length: 0
    21-Nov-2012 16:11:49.219	L:996.3[Extn] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=066ac00f026dd30a Call-ID=OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.:
    			ACK sip:222@192.168.16.61:5060;user=phone SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-066ac00f026dd30a-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:2047297860@192.168.16.100:5060>
    			To: <sip:222@192.168.16.100>;tag=467982843;intercom=true
    			From: <sip:2047297860@192.168.16.100:5060;nf=e>;tag=272d121f
    			Call-ID: OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.
    			CSeq: 1 ACK
    			Content-Type: application/sdp
    			Content-Length: 299
    			
    			v=0
    			o=3cxPS 191679692800 147085852673 IN IP4 192.168.16.100
    			s=3cxPS Audio call
    			c=IN IP4 192.168.16.101
    			t=0 0
    			m=audio 5132 RTP/AVP 0 8 18 101
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:18 G729/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:18 annexb=no
    			a=fmtp:101 0-15
    			a=sendrecv
    21-Nov-2012 16:11:49.217	Reneg. from L:996.1[Line:10012<<2047297860], answer
    21-Nov-2012 16:11:49.217	Leg L:996.1[Line:10012<<2047297860] raised event RecvdRemoteSdp
    21-Nov-2012 16:11:49.217	Remote SDP is set for leg L:996.1[Line:10012<<2047297860]
    21-Nov-2012 16:11:49.217	NAT/ALG check:L:996.1[Line:10012<<2047297860] RESPONSE 200 on 'INVITE' - basic check passed. No information for extended checks
    21-Nov-2012 16:11:49.216	Answer SDP arrived on Leg L:996.1[Line:10012<<2047297860];
    OnAnswer Recv 200/INVITE from 192.168.16.101:5060 tid=897a7c6761462948 Call-ID=9454bda87c2f76e5:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 192.168.16.100:5060;branch=z9hG4bK-d8754z-897a7c6761462948-1---d8754z-;rport=5060;received=192.168.16.100
    			Contact: <sip:2047297860@192.168.16.101:5060>
    			To: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			From: "Brooke Ward"<sip:221@192.168.16.100:5060>;tag=16775139
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 2 INVITE
    			Content-Type: application/sdp
    			Server: Patton SN4114 JO EUI 00A0BA07922A R6.2 2012-09-11 H323 SIP FXS FXO M5T SIP Stack/4.0.30.30
    			Supported: replaces
    			Content-Length: 271
    			
    			v=0
    			o=MxSIP 0 624 IN IP4 192.168.16.101
    			s=SIP Call
    			c=IN IP4 192.168.16.101
    			t=0 0
    			m=audio 5132 RTP/AVP 0 8 18 101
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:18 G729/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:18 annexb=no
    			a=fmtp:101 0-15
    			a=sendrecv
    21-Nov-2012 16:11:49.216	L:996.1[Line:10012<<2047297860] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=be6cec77e420b919 Call-ID=9454bda87c2f76e5:
    			ACK sip:2047297860@192.168.16.101:5060 SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-be6cec77e420b919-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:221@192.168.16.100:5060>
    			To: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			From: "Brooke Ward"<sip:221@192.168.16.100:5060>;tag=16775139
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 2 ACK
    			Content-Length: 0
    21-Nov-2012 16:11:49.197	L:996.2[Queue]: Terminating targets, reason: SIP ;cause=200 ;text="User hangup"
    21-Nov-2012 16:11:49.197	Leg L:996.2[Queue] is terminated: Cause: BYE from 127.0.0.1:5488
    21-Nov-2012 16:11:49.195	L:996.2[Queue] got Terminated Recv Req BYE from 127.0.0.1:5488 tid=e9278b538a5a9c79 Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			BYE sip:2047297860@127.0.0.1:5060 SIP/2.0
    			Via: SIP/2.0/UDP 127.0.0.1:5488;branch=z9hG4bK-d8754z-e9278b538a5a9c79-1---d8754z-;rport=5488
    			Max-Forwards: 70
    			Contact: <sip:500@127.0.0.1:5488>
    			To: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			From: <sip:500@127.0.0.1>;tag=3e23f445
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 5 BYE
    			User-Agent: 3CX Queue Manager (q=500)
    			Reason: SIP;description="User Hung Up"
    			Content-Length: 0
    21-Nov-2012 16:11:49.195	L:996.2[Queue] Sending: OnSendResp Send 200/BYE from 0.0.0.0:0 tid=e9278b538a5a9c79 Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 127.0.0.1:5488;branch=z9hG4bK-d8754z-e9278b538a5a9c79-1---d8754z-;rport=5488
    			Contact: <sip:2047297860@127.0.0.1:5060>
    			To: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			From: <sip:500@127.0.0.1>;tag=3e23f445
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 5 BYE
    			Content-Length: 0
    21-Nov-2012 16:11:49.057	[CM503007]: Call(C:996): Extn:222 has joined, contact <sip:222@192.168.16.61:5060>
    21-Nov-2012 16:11:49.056	L:996.1[Line:10012<<2047297860] Sending: OnSendReq Send Req INVITE from 0.0.0.0:0 tid=642e891f91624d01 Call-ID=9454bda87c2f76e5:
    			INVITE sip:2047297860@192.168.16.101:5060 SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-642e891f91624d01-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:221@192.168.16.100:5060>
    			To: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			From: "Brooke Ward"<sip:221@192.168.16.100:5060>;tag=16775139
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 2 INVITE
    			Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    			Content-Type: application/sdp
    			Supported: replaces
    			Content-Length: 429
    			
    			v=0
    			o=3cxPS 118346481664 12113149954 IN IP4 192.168.16.100
    			s=3cxPS Audio call
    			c=IN IP4 192.168.16.61
    			t=0 0
    			m=audio 5004 RTP/AVP 0 8 9 18 97 2 4 101
    			a=rtpmap:0 PCMU/8000
    			a=ptime:20
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:9 G722/8000
    			a=rtpmap:18 G729/8000
    			a=fmtp:18 annexb=no
    			a=rtpmap:97 iLBC/8000
    			a=fmtp:97 mode=30
    			a=rtpmap:2 G726-32/8000
    			a=rtpmap:4 G723/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:101 0-15
    			a=sendrecv
    21-Nov-2012 16:11:49.053	L:996.1[Line:10012<<2047297860]: Terminating targets, reason: SIP ;cause=200 ;text="Call completed elsewhere"
    21-Nov-2012 16:11:49.052	L:996.3[Extn] has joined to L:996.1[Line:10012<<2047297860]
    21-Nov-2012 16:11:49.052	Session 170987 of leg L:996.3[Extn] is connected
    21-Nov-2012 16:11:49.052	L:996.3[Extn] got Connected.UAC Recv 200/INVITE from 192.168.16.61:5060 tid=b83a0b72840f9511 Call-ID=OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 192.168.16.100:5060;branch=z9hG4bK-d8754z-b83a0b72840f9511-1---d8754z-;rport=5060
    			Contact: <sip:222@192.168.16.61:5060;user=phone>
    			To: <sip:222@192.168.16.100>;tag=467982843;intercom=true
    			From: <sip:2047297860@192.168.16.100:5060;nf=e>;tag=272d121f
    			Call-ID: OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.
    			CSeq: 1 INVITE
    			Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
    			Content-Type: application/sdp
    			Supported: replaces, path, timer
    			User-Agent: Grandstream GXP1450 1.0.4.23
    			Content-Length: 403
    			
    			v=0
    			o=222 8000 8000 IN IP4 192.168.16.61
    			s=SIP Call
    			c=IN IP4 192.168.16.61
    			t=0 0
    			m=audio 5004 RTP/AVP 0 8 9 18 97 2 4 101
    			a=sendrecv
    			a=rtpmap:0 PCMU/8000
    			a=ptime:20
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:9 G722/8000
    			a=rtpmap:18 G729/8000
    			a=fmtp:18 annexb=no
    			a=rtpmap:97 iLBC/8000
    			a=fmtp:97 mode=30
    			a=rtpmap:2 G726-32/8000
    			a=rtpmap:4 G723/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:101 0-15
    21-Nov-2012 16:11:49.052	Remote SDP is set for leg L:996.3[Extn]
    21-Nov-2012 16:11:49.052	NAT/ALG check:L:996.3[Extn] RESPONSE 200 on 'INVITE' - basic check passed. No information for extended checks
    21-Nov-2012 16:11:49.052	Offer SDP arrived on Leg L:996.3[Extn];
    OnOffer Recv 200/INVITE from 192.168.16.61:5060 tid=b83a0b72840f9511 Call-ID=OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 192.168.16.100:5060;branch=z9hG4bK-d8754z-b83a0b72840f9511-1---d8754z-;rport=5060
    			Contact: <sip:222@192.168.16.61:5060;user=phone>
    			To: <sip:222@192.168.16.100>;tag=467982843;intercom=true
    			From: <sip:2047297860@192.168.16.100:5060;nf=e>;tag=272d121f
    			Call-ID: OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.
    			CSeq: 1 INVITE
    			Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
    			Content-Type: application/sdp
    			Supported: replaces, path, timer
    			User-Agent: Grandstream GXP1450 1.0.4.23
    			Content-Length: 403
    			
    			v=0
    			o=222 8000 8000 IN IP4 192.168.16.61
    			s=SIP Call
    			c=IN IP4 192.168.16.61
    			t=0 0
    			m=audio 5004 RTP/AVP 0 8 9 18 97 2 4 101
    			a=sendrecv
    			a=rtpmap:0 PCMU/8000
    			a=ptime:20
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:9 G722/8000
    			a=rtpmap:18 G729/8000
    			a=fmtp:18 annexb=no
    			a=rtpmap:97 iLBC/8000
    			a=fmtp:97 mode=30
    			a=rtpmap:2 G726-32/8000
    			a=rtpmap:4 G723/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:101 0-15
    21-Nov-2012 16:11:49.052	[CM503002]: Call(C:996): Alerting Extn:222 by contact <sip:222@192.168.16.61:5060>
    21-Nov-2012 16:11:49.050	UacSession 170987 has formed leg L:996.3[Extn]
    21-Nov-2012 16:11:48.236	[CM503025]: Call(C:996): Calling T:Extn:222@[Dev:sip:222@192.168.16.61:5060;user=phone] for L:996.1[Line:10012<<2047297860]
    21-Nov-2012 16:11:48.236	Route to L:996.3[Extn] sends Invite-OUT Send Req INVITE from 0.0.0.0:0 tid=cf3aa735277b4d2c Call-ID=OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.:
    			INVITE sip:222@192.168.16.61:5060;user=phone;intercom=true SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-cf3aa735277b4d2c-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:2047297860@192.168.16.100:5060>
    			To: <sip:222@192.168.16.100>;intercom=true
    			From: <sip:2047297860@192.168.16.100:5060;nf=e>;tag=272d121f
    			Call-ID: OWE1MGVhYTEwNDgwYjRmNTU3YjkwZWIzOGUxY2I3YjM.
    			CSeq: 1 INVITE
    			Alert-Info: <http://www.notused.invalidtld>;info=external
    			Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    			Call-Info: <sip:3cx.pbx>;answer-after=0
    			Supported: replaces
    			Referred-By: <sip:500@127.0.0.1>
    			Replaces: ZGMwNDU1ZjdkZmVhZGVmMWIxZTlmOTkyYmYwMWM1MTA.;to-tag=1459151241;from-tag=3913736b
    			Content-Length: 0
    21-Nov-2012 16:11:48.229	Added leg L:996.3[Extn]
    21-Nov-2012 16:11:48.179	Call(C:996): Refer is using Replaces
    21-Nov-2012 16:11:48.179	L:996.1[Line:10012<<2047297860]:  Updated OutCID: '10012' and DNName: ''
    21-Nov-2012 16:11:48.179	Call(C:996): Referred(i): src=<sip:2047297860@192.168.16.100:5060;nf=e>; dst=<sip:401@192.168.16.100:5060>
    21-Nov-2012 16:11:48.179	Call(C:996): Replaces: L:1001.3[Extn]
    21-Nov-2012 16:11:48.179	Call transfer requested on Leg L:996.2[Queue];
    Refer Recv Req REFER from 127.0.0.1:5488 tid=9f1c19689a3c5e6f Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			REFER sip:2047297860@127.0.0.1:5060 SIP/2.0
    			Via: SIP/2.0/UDP 127.0.0.1:5488;branch=z9hG4bK-d8754z-9f1c19689a3c5e6f-1---d8754z-;rport=5488
    			Max-Forwards: 70
    			Contact: <sip:500@127.0.0.1:5488>
    			To: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			From: <sip:500@127.0.0.1>;tag=3e23f445
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 3 REFER
    			User-Agent: 3CX Queue Manager (q=500)
    			Refer-To: <sip:221@127.0.0.1:5060;nofwd=1;tag3cx=28-MGDSLWTTMM?Replaces=MjIwMmFlY2Q2YTgwZDZiMWFjZWZiNmI5ZTJhNTNiYjQ.%3Bto-tag%3D2573db51%3Bfrom-tag%3Da4620c76>;tag=2573db51
    			Referred-By: <sip:500@127.0.0.1>
    			Content-Length: 0
    21-Nov-2012 16:11:08.196	L:996.2[Queue] got INFO SipInfo Recv Req INFO from 127.0.0.1:5488 tid=97642b3aa048cf58 Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			INFO sip:2047297860@127.0.0.1:5060 SIP/2.0
    			Via: SIP/2.0/UDP 127.0.0.1:5488;branch=z9hG4bK-d8754z-97642b3aa048cf58-1---d8754z-;rport=5488
    			Max-Forwards: 70
    			Contact: <sip:500@127.0.0.1:5488>
    			To: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			From: <sip:500@127.0.0.1>;tag=3e23f445
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 2 INFO
    			Content-Type: application/component-call-info
    			User-Agent: 3CX Queue Manager (q=500)
    			Content-Length: 22
    			
    			qcid=23-XNPHTOJCDE
    21-Nov-2012 16:11:08.196	L:996.2[Queue] Sending: OnSendResp Send 200/INFO from 0.0.0.0:0 tid=97642b3aa048cf58 Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 127.0.0.1:5488;branch=z9hG4bK-d8754z-97642b3aa048cf58-1---d8754z-;rport=5488
    			Contact: <sip:2047297860@127.0.0.1:5060>
    			To: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			From: <sip:500@127.0.0.1>;tag=3e23f445
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 2 INFO
    			Content-Length: 0
    21-Nov-2012 16:11:08.083	Session 170909 of leg L:996.1[Line:10012<<2047297860] is confirmed
    21-Nov-2012 16:11:08.083	L:996.1[Line:10012<<2047297860] got Confirmed Recv Req ACK from 192.168.16.101:5060 tid=e3d5e340d30e31a12 Call-ID=9454bda87c2f76e5:
    			ACK sip:10012@192.168.16.100:5060 SIP/2.0
    			Via: SIP/2.0/UDP 192.168.16.101:5060;branch=z9hG4bKe3d5e340d30e31a12
    			Max-Forwards: 70
    			To: <sip:anonymous@192.168.16.100:5060>;tag=16775139
    			From: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 17266 ACK
    			Proxy-Authorization: Digest username="10012",realm="3CXPhoneSystem",nonce="414d535c06bde27b14:6553839a0d3dfd96ad381c3fd062d1d0",uri="sip:10012@192.168.16.100:5060",response="cddeaf80ab0aa3c8083fe4bd8d5caa77",algorithm=MD5
    			User-Agent: Patton SN4114 JO EUI 00A0BA07922A R6.2 2012-09-11 H323 SIP FXS FXO M5T SIP Stack/4.0.30.30
    			Content-Length: 0
    21-Nov-2012 16:11:07.892	[CM503007]: Call(C:996): Queue:500 has joined, contact <sip:500@127.0.0.1:5488>
    21-Nov-2012 16:11:07.891	[CM503007]: Call(C:996): Line:10012<<2047297860 has joined, contact <sip:10012@192.168.16.101:5060>
    21-Nov-2012 16:11:07.891	Session 170909 of leg L:996.1[Line:10012<<2047297860] is connected
    21-Nov-2012 16:11:07.891	L:996.1[Line:10012<<2047297860] got Connected.UAS Send 200/INVITE from 0.0.0.0:0 tid=387b2c4269af364ed Call-ID=9454bda87c2f76e5:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 192.168.16.101:5060;branch=z9hG4bK387b2c4269af364ed
    			Contact: <sip:10012@192.168.16.100:5060>
    			To: <sip:anonymous@192.168.16.100:5060>;tag=16775139
    			From: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 17266 INVITE
    			Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    			Content-Type: application/sdp
    			Supported: replaces
    			User-Agent: 3CXPhoneSystem 11.0.27588.780 (27565)
    			Content-Length: 239
    			
    			v=0
    			o=3cxPS 118346481664 12113149953 IN IP4 192.168.16.100
    			s=3cxPS Audio call
    			c=IN IP4 192.168.16.100
    			t=0 0
    			m=audio 7062 RTP/AVP 8 0 101
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:101 0-16
    21-Nov-2012 16:11:07.891	L:996.1[Line:10012<<2047297860] Sending: OnSendResp Send 200/INVITE from 0.0.0.0:0 tid=387b2c4269af364ed Call-ID=9454bda87c2f76e5:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 192.168.16.101:5060;branch=z9hG4bK387b2c4269af364ed
    			Contact: <sip:10012@192.168.16.100:5060>
    			To: <sip:anonymous@192.168.16.100:5060>;tag=16775139
    			From: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 17266 INVITE
    			Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    			Content-Type: application/sdp
    			Supported: replaces
    			Content-Length: 239
    			
    			v=0
    			o=3cxPS 118346481664 12113149953 IN IP4 192.168.16.100
    			s=3cxPS Audio call
    			c=IN IP4 192.168.16.100
    			t=0 0
    			m=audio 7062 RTP/AVP 8 0 101
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:101 0-16
    21-Nov-2012 16:11:07.888	L:996.1[Line:10012<<2047297860]: Terminating targets, reason: SIP ;cause=200 ;text="Call completed elsewhere"
    21-Nov-2012 16:11:07.888	L:996.2[Queue] has joined to L:996.1[Line:10012<<2047297860]
    21-Nov-2012 16:11:07.888	Session 170912 of leg L:996.2[Queue] is connected
    21-Nov-2012 16:11:07.888	L:996.2[Queue] got Connected.UAC Recv 200/INVITE from 127.0.0.1:5488 tid=f635a81fb8618c78 Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-d8754z-f635a81fb8618c78-1---d8754z-;rport=5060
    			Contact: <sip:500@127.0.0.1:5488>
    			To: <sip:500@127.0.0.1>;tag=3e23f445
    			From: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 1 INVITE
    			Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    			Content-Type: application/sdp
    			Supported: replaces
    			User-Agent: 3CX Queue Manager (q=500)
    			Content-Length: 226
    			
    			v=0
    			o=3cxVCE 62947155 286675590 IN IP4 127.0.0.1
    			s=3cxVCE Audio Call
    			c=IN IP4 127.0.0.1
    			t=0 0
    			m=audio 32044 RTP/AVP 8 0 101
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:101 0-16
    21-Nov-2012 16:11:07.887	Remote SDP is set for leg L:996.2[Queue]
    21-Nov-2012 16:11:07.887	NAT/ALG check:L:996.2[Queue] RESPONSE 200 on 'INVITE' - basic check passed. No information for extended checks
    21-Nov-2012 16:11:07.887	Answer SDP arrived on Leg L:996.2[Queue];
    OnAnswer Recv 200/INVITE from 127.0.0.1:5488 tid=f635a81fb8618c78 Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			SIP/2.0 200 OK
    			Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-d8754z-f635a81fb8618c78-1---d8754z-;rport=5060
    			Contact: <sip:500@127.0.0.1:5488>
    			To: <sip:500@127.0.0.1>;tag=3e23f445
    			From: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 1 INVITE
    			Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    			Content-Type: application/sdp
    			Supported: replaces
    			User-Agent: 3CX Queue Manager (q=500)
    			Content-Length: 226
    			
    			v=0
    			o=3cxVCE 62947155 286675590 IN IP4 127.0.0.1
    			s=3cxVCE Audio Call
    			c=IN IP4 127.0.0.1
    			t=0 0
    			m=audio 32044 RTP/AVP 8 0 101
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:101 0-16
    21-Nov-2012 16:11:07.887	L:996.2[Queue] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=413b802f2322a81c Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			ACK sip:500@127.0.0.1:5488 SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-413b802f2322a81c-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:2047297860@127.0.0.1:5060>
    			To: <sip:500@127.0.0.1>;tag=3e23f445
    			From: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 1 ACK
    			Content-Length: 0
    21-Nov-2012 16:11:07.886	L:996.1[Line:10012<<2047297860] Sending: OnSendResp Send 180/INVITE from 0.0.0.0:0 tid=387b2c4269af364ed Call-ID=9454bda87c2f76e5:
    			SIP/2.0 180 Ringing
    			Via: SIP/2.0/UDP 192.168.16.101:5060;branch=z9hG4bK387b2c4269af364ed
    			Contact: <sip:10012@192.168.16.100:5060>
    			To: <sip:anonymous@192.168.16.100:5060>;tag=16775139
    			From: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 17266 INVITE
    			Content-Length: 0
    21-Nov-2012 16:11:07.886	Provisional response arrived for session 170912 of Leg L:996.2[Queue]
    21-Nov-2012 16:11:07.886	L:996.2[Queue] got Provisional Recv 180/INVITE from 127.0.0.1:5488 tid=f635a81fb8618c78 Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			SIP/2.0 180 Ringing
    			Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-d8754z-f635a81fb8618c78-1---d8754z-;rport=5060
    			Contact: <sip:500@127.0.0.1:5488>
    			To: <sip:500@127.0.0.1>;tag=3e23f445
    			From: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			CSeq: 1 INVITE
    			User-Agent: 3CX Queue Manager (q=500)
    			Content-Length: 0
    21-Nov-2012 16:11:07.886	[CM503002]: Call(C:996): Alerting Queue:500 by contact <sip:500@127.0.0.1:5488>
    21-Nov-2012 16:11:07.884	UacSession 170912 has formed leg L:996.2[Queue]
    21-Nov-2012 16:11:07.756	[CM503025]: Call(C:996): Calling T:Queue:500@[Dev:sip:500@127.0.0.1:5488] for L:996.1[Line:10012<<2047297860]
    21-Nov-2012 16:11:07.756	Route to L:996.2[Queue] sends Invite-OUT Send Req INVITE from 0.0.0.0:0 tid=6d7ae15f763f857c Call-ID=ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.:
    			INVITE sip:500@127.0.0.1:5488 SIP/2.0
    			Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-6d7ae15f763f857c-1---d8754z-;rport
    			Max-Forwards: 70
    			Contact: <sip:2047297860@127.0.0.1:5060>
    			To: <sip:500@127.0.0.1>
    			From: <sip:2047297860@127.0.0.1:5060;nf=e>;tag=600f296d
    			Call-ID: ZDQ4NTgyMDc2N2QzYjgwNTY4M2RlZjY5MDA5OTQ0Zjc.
    			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: 289
    			
    			v=0
    			o=3cxPS 347439366144 354636791809 IN IP4 127.0.0.1
    			s=3cxPS Audio call
    			c=IN IP4 127.0.0.1
    			t=0 0
    			m=audio 7064 RTP/AVP 8 0 18 101
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:18 G729/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:18 annexb=no
    			a=fmtp:101 0-16
    			a=sendrecv
    21-Nov-2012 16:11:07.748	Added leg L:996.2[Queue]
    21-Nov-2012 16:11:07.715	[Flow] Call(C:996): making call from L:996.1[Line:10012<<2047297860] to T:Queue:500@[Dev:sip:500@127.0.0.1:5488]
    21-Nov-2012 16:11:07.715	[CM503005]: Call(C:996): Forwarding: T:Queue:500@[Dev:sip:500@127.0.0.1:5488]
    21-Nov-2012 16:11:07.714	[Flow] L:996.1[Line:10012<<2047297860]: Build targets for Queue:500
    21-Nov-2012 16:11:07.714	L:996.1[Line:10012<<2047297860] forwards call from Extn:401 to Queue:500 based on rule Fwd[Available/NotReg]
    21-Nov-2012 16:11:07.714	L:996.1[Line:10012<<2047297860] failed to reach Extn:401, reason Not Registered
    21-Nov-2012 16:11:07.714	Leg L:996.1[Line:10012<<2047297860]: Refreshing OutCID from endpoint Extn:401 = []
    21-Nov-2012 16:11:07.714	[CM503016]: Call(C:996): Attempt to reach <sip:401@192.168.16.100:5060> from Line:10012<<2047297860 has failed. Reason: Not Registered
    21-Nov-2012 16:11:07.714	[CM503017]: Call(C:996): Target is not registered: Extn:401
    21-Nov-2012 16:11:07.713	[Flow] Call(C:996): has built target endpoint: Extn:401 for call from L:996.1[Line:10012<<2047297860]
    21-Nov-2012 16:11:07.713	[CM503010]: Call(C:996): Making route(s) from Line:10012<<2047297860 to <sip:401@192.168.16.100:5060>
    21-Nov-2012 16:11:07.713	Remote SDP is set for leg L:996.1[Line:10012<<2047297860]
    21-Nov-2012 16:11:07.711	[CM500002]: Call(C:996): Info on incoming INVITE from Line:10012<<2047297860:
    			Invite-IN Recv Req INVITE from 192.168.16.101:5060 tid=387b2c4269af364ed Call-ID=9454bda87c2f76e5:
    			INVITE sip:10012@192.168.16.100:5060 SIP/2.0
    			Via: SIP/2.0/UDP 192.168.16.101:5060;branch=z9hG4bK387b2c4269af364ed
    			Max-Forwards: 70
    			Contact: <sip:2047297860@192.168.16.101:5060>
    			To: <sip:anonymous@192.168.16.100:5060>
    			From: <sip:2047297860@192.168.16.101:5060>;tag=61287c5f20
    			Call-ID: 9454bda87c2f76e5
    			CSeq: 17266 INVITE
    			Content-Type: application/sdp
    			Proxy-Authorization: Digest username="10012",realm="3CXPhoneSystem",nonce="414d535c06bde27b14:6553839a0d3dfd96ad381c3fd062d1d0",uri="sip:10012@192.168.16.100:5060",response="cddeaf80ab0aa3c8083fe4bd8d5caa77",algorithm=MD5
    			Supported: replaces
    			User-Agent: Patton SN4114 JO EUI 00A0BA07922A R6.2 2012-09-11 H323 SIP FXS FXO M5T SIP Stack/4.0.30.30
    			Content-Length: 271
    			
    			v=0
    			o=MxSIP 0 619 IN IP4 192.168.16.101
    			s=SIP Call
    			c=IN IP4 192.168.16.101
    			t=0 0
    			m=audio 5132 RTP/AVP 8 0 18 101
    			a=rtpmap:8 PCMA/8000
    			a=rtpmap:0 PCMU/8000
    			a=rtpmap:18 G729/8000
    			a=rtpmap:101 telephone-event/8000
    			a=fmtp:18 annexb=no
    			a=fmtp:101 0-16
    			a=sendrecv
    
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  7. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    10,757
    Likes Received:
    286
    Did you try replacing the set?

    Replacing the set with one of a a different make, using very simple (basic) extension settings.
    That works, then use the original set, but again use only basic setting.

    I assume that you have not had any problems of this sort on any other sets, and that they are the same make/model with similar features.

    It may very well be an interaction with the datafill of the set, or an option conflict, on the set or the 3CX options for that extension. If you use a process of elimination, you should be able to determine the culprit.

    Did this just begin recently, with the set behaving properly up to a certain point? Were there any changes to your network, or set-up in general?
     
  8. cfive

    cfive Member

    Joined:
    Aug 20, 2009
    Messages:
    284
    Likes Received:
    6
    Hi leejor,

    The main receptionist is ext 221, I swapped out her set. The second receptionist is 222, and it appears she is seeing the same thing. They do however both receive calls similarily, from the same queue, and the sets are the same GXP 1450 10.4.23. I have over 100 of these GXP 1450's deployed over several 3CX PBXs with the same firmware, and am not seeing this anywhere else.

    I'll give ext 221 a day with the swapped out set and see what happens. But I do suspect something related to the queue (prioritized hunt) - resulting in numerous calls being set up and taken down as the Queue manager cycles through the extensions - and then when one answers, the wrong call being terminated. Just guessing though.

    In that last code snip, do we know who ended the call?
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  9. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    10,757
    Likes Received:
    286
    Yes...192.168.16.61:5060
     
  10. clever1

    Joined:
    Aug 27, 2010
    Messages:
    3
    Likes Received:
    0
    Hi,

    I have the same phenomenon.
    Have tested with different providers on the same V11 install: Call is dropped after 8 minutes
    Have tested with different phones (Siemens IP-Dect, 3CX Softphone): Call is dropped after 8 minutes.

    The "function" seems to have appearded since the last SP install

    To me all indicators are that some code in the 3CX drops the call after 8 minutes. The 8 minute timer is a real constant in the equasion at all possible variations of a call (provider/client/originator/receiver etc)
     
  11. markshehan

    markshehan New Member

    Joined:
    Nov 14, 2012
    Messages:
    141
    Likes Received:
    0
    run a wireshark (http://www.wireshark.org to download it free) on the pbx machine. Then either post it here or email it to me and we can see. If you are seeing it constantly after 8 minutes then we need to see what is being sent/received by the pbx. There are no timers that go off after 8 minutes unless someone has lowered the min session expires time and it is not reinviting. The wireshark will show all that information too. It will have to be a wireshark and not 3cx logs to see this one.
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  12. clever1

    Joined:
    Aug 27, 2010
    Messages:
    3
    Likes Received:
    0
    Hello Mark,
    Thanks for the tips.
    I'll run a wireshark asap.
    Still I believe that this is an internal PBX thing since it even happens during internal calls between 3CX softphones as well as during externl calls. All supporting re-invites.
    Where would the min session expiry be settable (so that i can check to be sure?)
    Thanks
    Joep Maas
    Clever Clips
     
  13. markshehan

    markshehan New Member

    Joined:
    Nov 14, 2012
    Messages:
    141
    Likes Received:
    0
    Your provider usually sets that, not you. The default is 1800 with most providers. But we will see it in the sip messages.

    There is a little known setting in 3cx in the advanced custom parameters (MAXCALLDURATION) which should be 10800 (3 hours). After this period calls are cut off automatically by 3cx. Make sure that hasnt been changed for some reason.

    Otherwise the wireshark is going to give us some indication.
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  14. clever1

    Joined:
    Aug 27, 2010
    Messages:
    3
    Likes Received:
    0
    Hello Mark,
    I've checked the maxcallduration setting
    for some odd reason it was set to 1 :oops:
    No idea how that came about, but I'll monitor this for some tine to come to see if these bits are maybe on a "bad sector"
    Thanks for the help so far!
    Joep
     
Thread Status:
Not open for further replies.