SOLVED SIP Outbound CID displaying 888888888888 but trace shows true CID

Status
Not open for further replies.

sirjeff

New Member
Jun 18, 2018
5
0
1
49
Hi

We are using FusionPBX and whereas local calls made show the correct CID but the outgoing CID displayed is 888888888888. Suprisingly, the trace on my side and Service Providers shows the correct CID.

Could there be a specific configurable be missing something on my side or SPs that causes this anomaly?


Would appreciate any suggestion

Jeff
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,059
576
113
Where is the outgoing CID displayed as that? I do not understand the problem.
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,059
576
113
Please paste a full siptrace of the call, either sngrep or by running: sofia global siptrace on

in freeswitch cli
 

sirjeff

New Member
Jun 18, 2018
5
0
1
49
Please paste a full siptrace of the call, either sngrep or by running: sofia global siptrace on

in freeswitch cli

[This app Best viewed at 160x60 or more..]
+OK log level [7]
freeswitch@freeswitch> sofia global siptrace on
+OK Global siptrace on
recv 733 bytes from udp/[169.255.80.67]:5061 at 23:56:00.398456:
------------------------------------------------------------------------
BYE sip:0026773000666@169.255.80.71:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK27694fd1;rport
Max-Forwards: 70
From: <sip:26779117000@169.255.80.67:5061>;tag=as3f1c3152
To: <sip:0026773000666@169.255.80.71>;tag=3Zv5DKU4c5HBN
Call-ID: 560b80aa12ffad9f366bba3167554d2d@169.255.80.67:5061
CSeq: 104 BYE
User-Agent: FPBX-12.0.76.4(13.5.0)
Proxy-Authorization: Digest username="C2-101", realm="169.255.80.71", algorithm=MD5, uri="sip:0026773000666@169.255.80.71:5060", nonce="89649233-f7d1-497c-98c2-ea86db2cd960", response="3e71c275685d041134c2c37c9759d04f", qop=auth, cnonce="25b8852a", nc=00000002
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:00.406535 [NOTICE] sofia.c:988 Hangup sofia/internal/26779117000@169.255.80.67:5061 [CS_EXECUTE] [NORMAL_CLEARING]
send 469 bytes to udp/[169.255.80.67]:5061 at 23:56:00.407512:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK27694fd1;rport=5061
From: <sip:26779117000@169.255.80.67:5061>;tag=as3f1c3152
To: <sip:0026773000666@169.255.80.71>;tag=3Zv5DKU4c5HBN
Call-ID: 560b80aa12ffad9f366bba3167554d2d@169.255.80.67:5061
CSeq: 104 BYE
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:00.406535 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/internal/26779117000@169.255.80.67:5061]
2018-06-28 23:56:00.406535 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/26779117000@169.255.80.67:5061 ending bridge by request from write function
2018-06-28 23:56:00.406535 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/external/73000666]
2018-06-28 23:56:00.406535 [NOTICE] switch_ivr_bridge.c:889 Hangup sofia/external/73000666 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:612 (sofia/external/73000666) State EXCHANGE_MEDIA going to sleep
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:543 (sofia/external/73000666) Running State Change CS_HANGUP
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:809 (sofia/external/73000666) Callstate Change ACTIVE -> HANGUP
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:811 (sofia/external/73000666) State HANGUP
2018-06-28 23:56:00.406535 [DEBUG] mod_sofia.c:431 sofia/external/73000666 Overriding SIP cause 480 with 200 from the other leg
2018-06-28 23:56:00.406535 [DEBUG] mod_sofia.c:437 Channel sofia/external/73000666 hanging up, cause: NORMAL_CLEARING
2018-06-28 23:56:00.406535 [DEBUG] mod_sofia.c:490 Sending BYE to sofia/external/73000666
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:60 sofia/external/73000666 Standard HANGUP, cause: NORMAL_CLEARING
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:811 (sofia/external/73000666) State HANGUP going to sleep
send 678 bytes to udp/[172.16.19.13]:5060 at 23:56:00.416826:
------------------------------------------------------------------------
2018-06-28 23:56:00.406535 [DEBUG] switch_ivr_bridge.c:1697 sofia/external/73000666 skip receive message [UNBRIDGE] (channel is hungup already)
BYE sip:73000666@172.16.19.13:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 169.255.80.71:5080;rport;branch=z9hG4bKS5c3avNNcZrpm
Route: <sip:172.16.19.13:5060;transport=udp;lr>
2018-06-28 23:56:00.406535 [DEBUG] switch_ivr_bridge.c:1700 sofia/internal/26779117000@169.255.80.67:5061 skip receive message [UNBRIDGE] (channel is hungup already)
Max-Forwards: 70
From: "26779117000" <sip:FreeSWITCH@172.16.19.13>;tag=D47BSBNHXmFDF
To: <sip:73000666@172.16.19.13>;tag=sbc0804ie7k71n2-CC-24
Call-ID: 80298493-f5c0-1236-f78b-005056aaf96b
CSeq: 124775334 BYE
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Reason: Q.850;cause=16;text="NORMAL_CLEARING"
Content-Length: 0
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16

------------------------------------------------------------------------
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:578 (sofia/external/73000666) State Change CS_HANGUP -> CS_REPORTING
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:543 (sofia/external/73000666) Running State Change CS_REPORTING
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:897 (sofia/external/73000666) State REPORTING
2018-06-28 23:56:00.406535 [ERR] mod_xml_radius.c:933 Result of true match: 172.16.19.13 == ^169\.255\.80\.67
2018-06-28 23:56:00.406535 [DEBUG] switch_core_session.c:2797 sofia/internal/26779117000@169.255.80.67:5061 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/26779117000@169.255.80.67:5061) State EXECUTE going to sleep
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_HANGUP
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/26779117000@169.255.80.67:5061) Callstate Change ACTIVE -> HANGUP
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/26779117000@169.255.80.67:5061) State HANGUP
2018-06-28 23:56:00.406535 [DEBUG] mod_sofia.c:437 Channel sofia/internal/26779117000@169.255.80.67:5061 hanging up, cause: NORMAL_CLEARING
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:60 sofia/internal/26779117000@169.255.80.67:5061 Standard HANGUP, cause: NORMAL_CLEARING
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/26779117000@169.255.80.67:5061) State HANGUP going to sleep
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/26779117000@169.255.80.67:5061) State Change CS_HANGUP -> CS_REPORTING
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_REPORTING
2018-06-28 23:56:00.406535 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/26779117000@169.255.80.67:5061) State REPORTING
2018-06-28 23:56:00.406535 [ERR] mod_xml_radius.c:933 Result of true match: 169.255.80.71 == ^169\.255\.80\.67
recv 308 bytes from udp/[172.16.19.13]:5060 at 23:56:00.473489:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 169.255.80.71:5080;branch=z9hG4bKS5c3avNNcZrpm;rport=5080
Call-ID: 80298493-f5c0-1236-f78b-005056aaf96b
From: "26779117000"<sip:FreeSWITCH@172.16.19.13>;tag=D47BSBNHXmFDF
To: <sip:73000666@172.16.19.13>;tag=sbc0804ie7k71n2-CC-24
CSeq: 124775334 BYE
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:00.886496 [INFO] mod_xml_radius.c:1044 mod_xml_radius: Accounting Stop success
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:174 sofia/internal/26779117000@169.255.80.67:5061 Standard REPORTING, cause: NORMAL_CLEARING
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/26779117000@169.255.80.67:5061) State REPORTING going to sleep
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/26779117000@169.255.80.67:5061) State Change CS_REPORTING -> CS_DESTROY
2018-06-28 23:56:00.886496 [DEBUG] switch_core_session.c:1647 Session 39125 (sofia/internal/26779117000@169.255.80.67:5061) Locked, Waiting on external entities
2018-06-28 23:56:00.886496 [NOTICE] switch_core_session.c:1665 Session 39125 (sofia/internal/26779117000@169.255.80.67:5061) Ended
2018-06-28 23:56:00.886496 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/26779117000@169.255.80.67:5061 [CS_DESTROY]
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_DESTROY
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/26779117000@169.255.80.67:5061) State DESTROY
2018-06-28 23:56:00.886496 [DEBUG] mod_sofia.c:342 sofia/internal/26779117000@169.255.80.67:5061 SOFIA DESTROY
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:181 sofia/internal/26779117000@169.255.80.67:5061 Standard DESTROY
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/26779117000@169.255.80.67:5061) State DESTROY going to sleep
2018-06-28 23:56:00.886496 [INFO] mod_xml_radius.c:1044 mod_xml_radius: Accounting Stop success
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:174 sofia/external/73000666 Standard REPORTING, cause: NORMAL_CLEARING
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:897 (sofia/external/73000666) State REPORTING going to sleep
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:569 (sofia/external/73000666) State Change CS_REPORTING -> CS_DESTROY
2018-06-28 23:56:00.886496 [DEBUG] switch_core_session.c:1647 Session 39126 (sofia/external/73000666) Locked, Waiting on external entities
2018-06-28 23:56:00.886496 [NOTICE] switch_core_session.c:1665 Session 39126 (sofia/external/73000666) Ended
2018-06-28 23:56:00.886496 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/73000666 [CS_DESTROY]
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:700 (sofia/external/73000666) Running State Change CS_DESTROY
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:710 (sofia/external/73000666) State DESTROY
2018-06-28 23:56:00.886496 [DEBUG] mod_sofia.c:342 sofia/external/73000666 SOFIA DESTROY
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:181 sofia/external/73000666 Standard DESTROY
2018-06-28 23:56:00.886496 [DEBUG] switch_core_state_machine.c:710 (sofia/external/73000666) State DESTROY going to sleep
recv 311 bytes from udp/[172.16.19.13]:5060 at 23:56:01.254255:
------------------------------------------------------------------------
OPTIONS sip:169.255.80.71:5080 SIP/2.0
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bKiiv1nkg12v1zvj2l2ejk5ein1T09098
Call-ID: isbclinzfi22lv5njke1ingfhzlvvd11ekdz@SoftX3000
From: <sip:172.16.19.13:5060>;tag=sbc0807lilhjgnf
To: <sip:169.255.80.71>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0

------------------------------------------------------------------------
send 540 bytes to udp/[172.16.19.13]:5060 at 23:56:01.254554:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bKiiv1nkg12v1zvj2l2ejk5ein1T09098
From: <sip:172.16.19.13:5060>;tag=sbc0807lilhjgnf
To: <sip:169.255.80.71>;tag=pNBB9ycH34QNQ
Call-ID: isbclinzfi22lv5njke1ingfhzlvvd11ekdz@SoftX3000
CSeq: 1 OPTIONS
Contact: <sip:169.255.80.71:5080>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Length: 0

------------------------------------------------------------------------
recv 902 bytes from udp/[169.255.80.67]:5061 at 23:56:06.922936:
------------------------------------------------------------------------
INVITE sip:0026773000666@169.255.80.71 SIP/2.0
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK6ab17a75;rport
Max-Forwards: 70
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>
Contact: <sip:26779117000@169.255.80.67:5061>
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 102 INVITE
User-Agent: FPBX-12.0.76.4(13.5.0)
Date: Thu, 28 Jun 2018 21:58:05 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 298

v=0
o=root 487791624 487791624 IN IP4 169.255.80.67
s=Asterisk PBX 13.5.0
c=IN IP4 169.255.80.67
t=0 0
m=audio 14188 RTP/AVP 8 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
------------------------------------------------------------------------
send 314 bytes to udp/[169.255.80.67]:5061 at 23:56:06.923118:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK6ab17a75;rport=5061
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 102 INVITE
User-Agent: FreeSWITCH
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:06.906528 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/26779117000@169.255.80.67:5061 [d8df1b89-0eb8-463c-aa96-fe0a381f635b]
2018-06-28 23:56:06.906528 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_NEW
2018-06-28 23:56:06.906528 [DEBUG] sofia.c:9456 sofia/internal/26779117000@169.255.80.67:5061 receiving invite from 169.255.80.67:5061 version: 1.6.9 64bit
2018-06-28 23:56:06.906528 [DEBUG] sofia.c:9623 IP 169.255.80.67 Rejected by acl "domains". Falling back to Digest auth.
2018-06-28 23:56:06.906528 [WARNING] sofia_reg.c:1787 SIP auth challenge (INVITE) on sofia profile 'internal' for [0026773000666@169.255.80.71] from ip 169.255.80.67
2018-06-28 23:56:06.906528 [DEBUG] switch_core_state_machine.c:562 (sofia/internal/26779117000@169.255.80.67:5061) State NEW
send 818 bytes to udp/[169.255.80.67]:5061 at 23:56:06.923814:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK6ab17a75;rport=5061
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>;tag=7318mZyj18apK
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 102 INVITE
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Proxy-Authenticate: Digest realm="169.255.80.71", nonce="90db1749-ac82-4f1c-a3fd-bb25bb294240", algorithm=MD5, qop="auth"
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:06.906528 [DEBUG] sofia.c:2248 detaching session d8df1b89-0eb8-463c-aa96-fe0a381f635b
recv 426 bytes from udp/[169.255.80.67]:5061 at 23:56:06.923974:
------------------------------------------------------------------------
ACK sip:0026773000666@169.255.80.71 SIP/2.0
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK6ab17a75;rport
Max-Forwards: 70
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>;tag=7318mZyj18apK
Contact: <sip:26779117000@169.255.80.67:5061>
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 102 ACK
User-Agent: FPBX-12.0.76.4(13.5.0)
Content-Length: 0

------------------------------------------------------------------------
recv 1159 bytes from udp/[169.255.80.67]:5061 at 23:56:06.924143:
------------------------------------------------------------------------
INVITE sip:0026773000666@169.255.80.71 SIP/2.0
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK171ee467;rport
Max-Forwards: 70
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>
Contact: <sip:26779117000@169.255.80.67:5061>
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 103 INVITE
User-Agent: FPBX-12.0.76.4(13.5.0)
Proxy-Authorization: Digest username="C2-101", realm="169.255.80.71", algorithm=MD5, uri="sip:0026773000666@169.255.80.71", nonce="90db1749-ac82-4f1c-a3fd-bb25bb294240", response="4a61cd6e7aefdd9d423b9f5d43f86b61", qop=auth, cnonce="276cb94f", nc=00000001
Date: Thu, 28 Jun 2018 21:58:05 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 298

v=0
o=root 487791624 487791625 IN IP4 169.255.80.67
s=Asterisk PBX 13.5.0
c=IN IP4 169.255.80.67
t=0 0
m=audio 14188 RTP/AVP 8 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
------------------------------------------------------------------------
send 314 bytes to udp/[169.255.80.67]:5061 at 23:56:06.924258:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK171ee467;rport=5061
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 103 INVITE
User-Agent: FreeSWITCH
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:06.906528 [DEBUG] sofia.c:2356 Re-attaching to session d8df1b89-0eb8-463c-aa96-fe0a381f635b
2018-06-28 23:56:06.906528 [DEBUG] sofia.c:9456 sofia/internal/26779117000@169.255.80.67:5061 receiving invite from 169.255.80.67:5061 version: 1.6.9 64bit
2018-06-28 23:56:06.906528 [DEBUG] sofia.c:9623 IP 169.255.80.67 Rejected by acl "domains". Falling back to Digest auth.
2018-06-28 23:56:06.946486 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f1be8159640 Connected.
2018-06-28 23:56:06.946486 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f1be8159640 released.
2018-06-28 23:56:07.446505 [DEBUG] sofia.c:6933 Channel sofia/internal/26779117000@169.255.80.67:5061 entering state [received][100]
2018-06-28 23:56:07.446505 [DEBUG] sofia.c:6943 Remote SDP:
v=0
o=root 487791624 487791625 IN IP4 169.255.80.67
s=Asterisk PBX 13.5.0
c=IN IP4 169.255.80.67
t=0 0
m=audio 14188 RTP/AVP 8 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150

2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4281 Set telephone-event payload to 101@8000
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:3091 Set Codec sofia/internal/26779117000@169.255.80.67:5061 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2018-06-28 23:56:07.446505 [DEBUG] switch_core_codec.c:111 sofia/internal/26779117000@169.255.80.67:5061 Original read codec set to PCMA:8
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4634 Set telephone-event payload to 101@8000
2018-06-28 23:56:07.446505 [DEBUG] switch_core_media.c:4693 sofia/internal/26779117000@169.255.80.67:5061 Set 2833 dtmf send payload to 101 recv payload to 101
2018-06-28 23:56:07.446505 [DEBUG] sofia.c:7332 (sofia/internal/26779117000@169.255.80.67:5061) State Change CS_NEW -> CS_INIT
2018-06-28 23:56:07.446505 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_INIT
2018-06-28 23:56:07.446505 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/26779117000@169.255.80.67:5061) State INIT
2018-06-28 23:56:07.446505 [DEBUG] mod_sofia.c:89 sofia/internal/26779117000@169.255.80.67:5061 SOFIA INIT
2018-06-28 23:56:07.446505 [DEBUG] switch_core_state_machine.c:40 sofia/internal/26779117000@169.255.80.67:5061 Standard INIT
2018-06-28 23:56:07.446505 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/26779117000@169.255.80.67:5061) State Change CS_INIT -> CS_ROUTING
2018-06-28 23:56:07.446505 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/26779117000@169.255.80.67:5061) State INIT going to sleep
2018-06-28 23:56:07.446505 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_ROUTING
2018-06-28 23:56:07.446505 [DEBUG] switch_channel.c:2249 (sofia/internal/26779117000@169.255.80.67:5061) Callstate Change DOWN -> RINGING
2018-06-28 23:56:07.446505 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/26779117000@169.255.80.67:5061) State ROUTING
2018-06-28 23:56:07.446505 [DEBUG] mod_sofia.c:142 sofia/internal/26779117000@169.255.80.67:5061 SOFIA ROUTING
2018-06-28 23:56:07.446505 [ERR] mod_xml_radius.c:933 Result of true match: 169.255.80.71 == ^169\.255\.80\.67
2018-06-28 23:56:07.906528 [INFO] mod_xml_radius.c:986 mod_xml_radius: Accounting Start success
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:236 sofia/internal/26779117000@169.255.80.67:5061 Standard ROUTING
2018-06-28 23:56:07.906528 [INFO] mod_dialplan_xml.c:637 Processing 26779117000 <26779117000>->0026773000666 in context public
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 parsing [public->BTC-Interconnect-Test] continue=false
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Regex (PASS) [BTC-Interconnect-Test] destination_number(0026773000666) =~ /^00267(73000666)$/ break=on-false
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(call_direction=inbound)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(domain_uuid=f6a138b0-93b8-4741-a1c2-56a4e0119b43)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(domain_name=169.255.80.71)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(domain=169.255.80.71)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(call_direction=outbound)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(inherit_codec=true)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(ignore_display_updates=true)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(callee_id_number=73000666)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action set(continue_on_fail=true)
Dialplan: sofia/internal/26779117000@169.255.80.67:5061 Action bridge(sofia/gateway/52bfaaeb-754c-42ab-a7c7-be9db6a8cf89/73000666)
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/26779117000@169.255.80.67:5061) State Change CS_ROUTING -> CS_EXECUTE
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/26779117000@169.255.80.67:5061) State ROUTING going to sleep
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_EXECUTE
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/26779117000@169.255.80.67:5061) State EXECUTE
2018-06-28 23:56:07.906528 [DEBUG] mod_sofia.c:197 sofia/internal/26779117000@169.255.80.67:5061 SOFIA EXECUTE
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:328 sofia/internal/26779117000@169.255.80.67:5061 Standard EXECUTE
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(call_direction=inbound)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [call_direction]=[inbound]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(domain_uuid=f6a138b0-93b8-4741-a1c2-56a4e0119b43)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [domain_uuid]=[f6a138b0-93b8-4741-a1c2-56a4e0119b43]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(domain_name=169.255.80.71)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [domain_name]=[169.255.80.71]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(domain=169.255.80.71)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [domain]=[169.255.80.71]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(sip_h_X-accountcode=)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [sip_h_X-accountcode]=[UNDEF]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(call_direction=outbound)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [call_direction]=[outbound]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(hangup_after_bridge=true)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [hangup_after_bridge]=[true]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(effective_caller_id_number=)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [effective_caller_id_number]=[UNDEF]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(inherit_codec=true)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [inherit_codec]=[true]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(ignore_display_updates=true)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [ignore_display_updates]=[true]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(callee_id_number=73000666)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [callee_id_number]=[73000666]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 set(continue_on_fail=true)
2018-06-28 23:56:07.906528 [DEBUG] mod_dptools.c:1519 SET sofia/internal/26779117000@169.255.80.67:5061 [continue_on_fail]=[true]
EXECUTE sofia/internal/26779117000@169.255.80.67:5061 bridge(sofia/gateway/52bfaaeb-754c-42ab-a7c7-be9db6a8cf89/73000666)
2018-06-28 23:56:07.906528 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
2018-06-28 23:56:07.906528 [NOTICE] switch_channel.c:1104 New Channel sofia/external/73000666 [f76a6130-b224-46e3-818d-9f81c2871b6c]
2018-06-28 23:56:07.906528 [DEBUG] mod_sofia.c:4757 (sofia/external/73000666) State Change CS_NEW -> CS_INIT
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:543 (sofia/external/73000666) Running State Change CS_INIT
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:586 (sofia/external/73000666) State INIT
2018-06-28 23:56:07.906528 [DEBUG] mod_sofia.c:89 sofia/external/73000666 SOFIA INIT
2018-06-28 23:56:07.906528 [DEBUG] sofia_glue.c:1257 sofia/external/73000666 sending invite version: 1.6.9 64bit
Local SDP:
v=0
o=FreeSWITCH 1530192151 1530192152 IN IP4 169.255.80.71
s=FreeSWITCH
c=IN IP4 169.255.80.71
t=0 0
m=audio 30816 RTP/AVP 8 3 18 101 13
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv

2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:40 sofia/external/73000666 Standard INIT
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:48 (sofia/external/73000666) State Change CS_INIT -> CS_ROUTING
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:586 (sofia/external/73000666) State INIT going to sleep
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:543 (sofia/external/73000666) Running State Change CS_ROUTING
send 1145 bytes to udp/[172.16.19.13]:5060 at 23:56:07.941622:
------------------------------------------------------------------------
INVITE sip:73000666@172.16.19.13 SIP/2.0
Via: SIP/2.0/UDP 169.255.80.71:5080;rport;branch=z9hG4bKte6UcQ6r97e9F
Max-Forwards: 69
From: "26779117000" <sip:FreeSWITCH@172.16.19.13>;tag=Qy43atXm0De8j
To: <sip:73000666@172.16.19.13>
Call-ID: e72fa162-f5c0-1236-f78b-005056aaf96b
CSeq: 124775419 INVITE
Contact: <sip:gw+52bfaaeb-754c-42ab-a7c7-be9db6a8cf89@169.255.80.71:5080;transport=udp;gw=52bfaaeb-754c-42ab-a7c7-be9db6a8cf89>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 295
X-FS-Support: update_display,send_info
Remote-Party-ID: "26779117000" <sip:26779117000@172.16.19.13>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1530192151 1530192152 IN IP4 169.255.80.71
s=FreeSWITCH
c=IN IP4 169.255.80.71
t=0 0
m=audio 30816 RTP/AVP 8 3 18 101 13
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
------------------------------------------------------------------------
2018-06-28 23:56:07.906528 [DEBUG] switch_core_state_machine.c:602 (sofia/external/73000666) State ROUTING
2018-06-28 23:56:07.906528 [DEBUG] mod_sofia.c:142 sofia/external/73000666 SOFIA ROUTING
2018-06-28 23:56:07.906528 [DEBUG] switch_ivr_originate.c:67 (sofia/external/73000666) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2018-06-28 23:56:07.906528 [ERR] mod_xml_radius.c:933 Result of true match: 172.16.19.13 == ^169\.255\.80\.67
recv 289 bytes from udp/[172.16.19.13]:5060 at 23:56:07.953327:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 169.255.80.71:5080;branch=z9hG4bKte6UcQ6r97e9F;rport=5080
Call-ID: e72fa162-f5c0-1236-f78b-005056aaf96b
From: "26779117000"<sip:FreeSWITCH@172.16.19.13>;tag=Qy43atXm0De8j
To: <sip:73000666@172.16.19.13>
CSeq: 124775419 INVITE
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:08.406546 [INFO] mod_xml_radius.c:986 mod_xml_radius: Accounting Start success
2018-06-28 23:56:08.406546 [DEBUG] switch_core_state_machine.c:602 (sofia/external/73000666) State ROUTING going to sleep
2018-06-28 23:56:08.406546 [DEBUG] switch_core_state_machine.c:543 (sofia/external/73000666) Running State Change CS_CONSUME_MEDIA
2018-06-28 23:56:08.406546 [DEBUG] sofia.c:6933 Channel sofia/external/73000666 entering state [calling][0]
2018-06-28 23:56:08.406546 [DEBUG] switch_core_state_machine.c:621 (sofia/external/73000666) State CONSUME_MEDIA
2018-06-28 23:56:08.406546 [DEBUG] switch_core_state_machine.c:621 (sofia/external/73000666) State CONSUME_MEDIA going to sleep
recv 700 bytes from udp/[172.16.19.13]:5060 at 23:56:12.285134:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 169.255.80.71:5080;branch=z9hG4bKte6UcQ6r97e9F;rport=5080
Record-Route: <sip:172.16.19.13:5060;transport=udp;lr>
Call-ID: e72fa162-f5c0-1236-f78b-005056aaf96b
From: "26779117000"<sip:FreeSWITCH@172.16.19.13>;tag=Qy43atXm0De8j
To: <sip:73000666@172.16.19.13>;tag=sbc0807dnzfdjg1-CC-27
CSeq: 124775419 INVITE
Contact: <sip:73000666@172.16.19.13:5060;user=phone>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Content-Length: 145
Content-Type: application/sdp

v=0
o=- 248552 248552 IN IP4 172.16.19.14
s=SBC call
c=IN IP4 172.16.19.14
t=0 0
m=audio 12674 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
------------------------------------------------------------------------
2018-06-28 23:56:12.266503 [DEBUG] sofia.c:6933 Channel sofia/external/73000666 entering state [proceeding][180]
2018-06-28 23:56:12.266503 [DEBUG] sofia.c:6943 Remote SDP:
v=0
o=- 248552 248552 IN IP4 172.16.19.14
s=SBC call
c=IN IP4 172.16.19.14
t=0 0
m=audio 12674 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20

2018-06-28 23:56:12.266503 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-06-28 23:56:12.266503 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2018-06-28 23:56:12.266503 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-06-28 23:56:12.266503 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
2018-06-28 23:56:12.266503 [DEBUG] switch_core_media.c:3091 Set Codec sofia/external/73000666 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2018-06-28 23:56:12.266503 [DEBUG] switch_core_codec.c:111 sofia/external/73000666 Original read codec set to PCMA:8
2018-06-28 23:56:12.266503 [DEBUG] switch_core_media.c:4700 No 2833 in SDP. Disable 2833 dtmf and switch to INFO
2018-06-28 23:56:12.266503 [DEBUG] switch_core_media.c:6496 AUDIO RTP [sofia/external/73000666] 169.255.80.71 port 30816 -> 172.16.19.14 port 12674 codec: 8 ms: 20
2018-06-28 23:56:12.266503 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
2018-06-28 23:56:12.286491 [DEBUG] switch_core_media.c:6825 sofia/external/73000666 Set rtp dtmf delay to 40
2018-06-28 23:56:12.286491 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/73000666!
2018-06-28 23:56:12.286491 [DEBUG] switch_channel.c:3471 (sofia/external/73000666) Callstate Change DOWN -> EARLY
2018-06-28 23:56:12.286491 [DEBUG] switch_ivr_originate.c:410 Setting codec string on sofia/internal/26779117000@169.255.80.67:5061 to PCMA@8000h@20i
2018-06-28 23:56:12.286491 [INFO] switch_ivr_originate.c:3556 Sending early media
2018-06-28 23:56:12.286491 [DEBUG] switch_core_media.c:6496 AUDIO RTP [sofia/internal/26779117000@169.255.80.67:5061] 169.255.80.71 port 31488 -> 169.255.80.67 port 14188 codec: 8 ms: 20
2018-06-28 23:56:12.286491 [DEBUG] switch_rtp.c:3832 Starting timer [soft] 160 bytes per 20ms
2018-06-28 23:56:12.286491 [DEBUG] switch_core_media.c:6795 sofia/internal/26779117000@169.255.80.67:5061 Set 2833 dtmf send payload to 101
2018-06-28 23:56:12.286491 [DEBUG] switch_core_media.c:6802 sofia/internal/26779117000@169.255.80.67:5061 Set 2833 dtmf receive payload to 101
2018-06-28 23:56:12.286491 [DEBUG] switch_core_media.c:6825 sofia/internal/26779117000@169.255.80.67:5061 Set rtp dtmf delay to 40
2018-06-28 23:56:12.286491 [DEBUG] mod_sofia.c:2311 Ring SDP:
v=0
o=FreeSWITCH 1530191484 1530191485 IN IP4 169.255.80.71
s=FreeSWITCH
c=IN IP4 169.255.80.71
t=0 0
m=audio 31488 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

2018-06-28 23:56:12.286491 [NOTICE] mod_sofia.c:2314 Pre-Answer sofia/internal/26779117000@169.255.80.67:5061!
2018-06-28 23:56:12.286491 [DEBUG] switch_channel.c:3471 (sofia/internal/26779117000@169.255.80.67:5061) Callstate Change RINGING -> EARLY
2018-06-28 23:56:12.286491 [DEBUG] switch_ivr_originate.c:3607 Originate Resulted in Success: [sofia/external/73000666]
send 1129 bytes to udp/[169.255.80.67]:5061 at 23:56:12.294043:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK171ee467;rport=5061
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>;tag=8cU1ptFpyH18e
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 103 INVITE
Contact: <sip:0026773000666@169.255.80.71:5060;transport=udp>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 222
Remote-Party-ID: "Outbound Call" <sip:73000666@169.255.80.71>;party=calling;privacy=off;screen=no

v=0
o=FreeSWITCH 1530191484 1530191485 IN IP4 169.255.80.71
s=FreeSWITCH
c=IN IP4 169.255.80.71
t=0 0
m=audio 31488 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2018-06-28 23:56:12.286491 [DEBUG] sofia.c:6933 Channel sofia/internal/26779117000@169.255.80.67:5061 entering state [early][183]
2018-06-28 23:56:12.286491 [DEBUG] switch_ivr_bridge.c:1599 (sofia/external/73000666) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2018-06-28 23:56:12.286491 [DEBUG] switch_core_state_machine.c:543 (sofia/external/73000666) Running State Change CS_EXCHANGE_MEDIA
2018-06-28 23:56:12.286491 [DEBUG] switch_core_state_machine.c:612 (sofia/external/73000666) State EXCHANGE_MEDIA
2018-06-28 23:56:12.286491 [DEBUG] mod_sofia.c:630 SOFIA EXCHANGE_MEDIA
2018-06-28 23:56:12.726643 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
2018-06-28 23:56:12.726643 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
recv 599 bytes from udp/[172.16.19.13]:5060 at 23:56:14.635319:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 169.255.80.71:5080;branch=z9hG4bKte6UcQ6r97e9F;rport=5080
Record-Route: <sip:172.16.19.13:5060;transport=udp;lr>
Call-ID: e72fa162-f5c0-1236-f78b-005056aaf96b
From: "26779117000"<sip:FreeSWITCH@172.16.19.13>;tag=Qy43atXm0De8j
To: <sip:73000666@172.16.19.13>;tag=sbc0807dnzfdjg1-CC-27
CSeq: 124775419 INVITE
Contact: <sip:73000666@172.16.19.13:5060;user=phone>
Content-Length: 145
Content-Type: application/sdp

v=0
o=- 248552 248553 IN IP4 172.16.19.14
s=SBC call
c=IN IP4 172.16.19.14
t=0 0
m=audio 12674 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
------------------------------------------------------------------------
2018-06-28 23:56:14.646508 [DEBUG] sofia.c:6933 Channel sofia/external/73000666 entering state [completing][200]
2018-06-28 23:56:14.646508 [DEBUG] sofia.c:6943 Remote SDP:
v=0
o=- 248552 248553 IN IP4 172.16.19.14
s=SBC call
c=IN IP4 172.16.19.14
t=0 0
m=audio 12674 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20

send 539 bytes to udp/[172.16.19.13]:5060 at 23:56:14.647018:
------------------------------------------------------------------------
ACK sip:73000666@172.16.19.13:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 169.255.80.71:5080;rport;branch=z9hG4bKUQZmejQv6g5UB
Route: <sip:172.16.19.13:5060;transport=udp;lr>
Max-Forwards: 70
From: "26779117000" <sip:FreeSWITCH@172.16.19.13>;tag=Qy43atXm0De8j
To: <sip:73000666@172.16.19.13>;tag=sbc0807dnzfdjg1-CC-27
Call-ID: e72fa162-f5c0-1236-f78b-005056aaf96b
CSeq: 124775419 ACK
Contact: <sip:gw+52bfaaeb-754c-42ab-a7c7-be9db6a8cf89@169.255.80.71:5080;transport=udp;gw=52bfaaeb-754c-42ab-a7c7-be9db6a8cf89>
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:14.646508 [DEBUG] sofia.c:6933 Channel sofia/external/73000666 entering state [ready][200]
2018-06-28 23:56:14.646508 [NOTICE] sofia.c:7847 Channel [sofia/external/73000666] has been answered
2018-06-28 23:56:14.666488 [DEBUG] switch_channel.c:3770 (sofia/external/73000666) Callstate Change EARLY -> ACTIVE
2018-06-28 23:56:14.666488 [DEBUG] mod_sofia.c:821 Local SDP sofia/internal/26779117000@169.255.80.67:5061:
v=0
o=FreeSWITCH 1530191484 1530191486 IN IP4 169.255.80.71
s=FreeSWITCH
c=IN IP4 169.255.80.71
t=0 0
m=audio 31488 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

send 1090 bytes to udp/[169.255.80.67]:5061 at 23:56:14.672586:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK171ee467;rport=5061
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>;tag=8cU1ptFpyH18e
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 103 INVITE
Contact: <sip:0026773000666@169.255.80.71:5060;transport=udp>
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 222
Remote-Party-ID: "Outbound Call" <sip:73000666@169.255.80.71>;party=calling;privacy=off;screen=no

v=0
o=FreeSWITCH 1530191484 1530191485 IN IP4 169.255.80.71
s=FreeSWITCH
c=IN IP4 169.255.80.71
t=0 0
m=audio 31488 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
2018-06-28 23:56:14.666488 [NOTICE] switch_ivr_bridge.c:623 Channel [sofia/internal/26779117000@169.255.80.67:5061] has been answered
recv 445 bytes from udp/[169.255.80.67]:5061 at 23:56:14.673000:
------------------------------------------------------------------------
ACK sip:0026773000666@169.255.80.71:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK4bb84eff;rport
Max-Forwards: 70
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>;tag=8cU1ptFpyH18e
Contact: <sip:26779117000@169.255.80.67:5061>
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 103 ACK
User-Agent: FPBX-12.0.76.4(13.5.0)
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:14.666488 [DEBUG] switch_channel.c:3770 (sofia/internal/26779117000@169.255.80.67:5061) Callstate Change EARLY -> ACTIVE
2018-06-28 23:56:14.666488 [DEBUG] sofia.c:6933 Channel sofia/internal/26779117000@169.255.80.67:5061 entering state [completed][200]
2018-06-28 23:56:14.666488 [DEBUG] sofia.c:6933 Channel sofia/internal/26779117000@169.255.80.67:5061 entering state [ready][200]
2018-06-28 23:56:14.706497 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
2018-06-28 23:56:14.706497 [DEBUG] switch_rtp.c:6711 Correct audio ip/port confirmed.
recv 733 bytes from udp/[169.255.80.67]:5061 at 23:56:20.638549:
------------------------------------------------------------------------
BYE sip:0026773000666@169.255.80.71:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK1e5ef3fc;rport
Max-Forwards: 70
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>;tag=8cU1ptFpyH18e
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 104 BYE
User-Agent: FPBX-12.0.76.4(13.5.0)
Proxy-Authorization: Digest username="C2-101", realm="169.255.80.71", algorithm=MD5, uri="sip:0026773000666@169.255.80.71:5060", nonce="90db1749-ac82-4f1c-a3fd-bb25bb294240", response="cd49ce290e618d045b2982a1e14e5381", qop=auth, cnonce="172c02aa", nc=00000002
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:20.646493 [NOTICE] sofia.c:988 Hangup sofia/internal/26779117000@169.255.80.67:5061 [CS_EXECUTE] [NORMAL_CLEARING]
send 469 bytes to udp/[169.255.80.67]:5061 at 23:56:20.653241:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 169.255.80.67:5061;branch=z9hG4bK1e5ef3fc;rport=5061
From: <sip:26779117000@169.255.80.67:5061>;tag=as0e3094b8
To: <sip:0026773000666@169.255.80.71>;tag=8cU1ptFpyH18e
Call-ID: 1a60eed9535a97477f08e89f033323c2@169.255.80.67:5061
CSeq: 104 BYE
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:20.646493 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/internal/26779117000@169.255.80.67:5061]
2018-06-28 23:56:20.646493 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/26779117000@169.255.80.67:5061 ending bridge by request from write function
2018-06-28 23:56:20.666494 [DEBUG] switch_ivr_bridge.c:785 BRIDGE THREAD DONE [sofia/external/73000666]
2018-06-28 23:56:20.666494 [NOTICE] switch_ivr_bridge.c:889 Hangup sofia/external/73000666 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:612 (sofia/external/73000666) State EXCHANGE_MEDIA going to sleep
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:543 (sofia/external/73000666) Running State Change CS_HANGUP
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:809 (sofia/external/73000666) Callstate Change ACTIVE -> HANGUP
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:811 (sofia/external/73000666) State HANGUP
2018-06-28 23:56:20.666494 [DEBUG] mod_sofia.c:431 sofia/external/73000666 Overriding SIP cause 480 with 200 from the other leg
2018-06-28 23:56:20.666494 [DEBUG] mod_sofia.c:437 Channel sofia/external/73000666 hanging up, cause: NORMAL_CLEARING
2018-06-28 23:56:20.666494 [DEBUG] mod_sofia.c:490 Sending BYE to sofia/external/73000666
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:60 sofia/external/73000666 Standard HANGUP, cause: NORMAL_CLEARING
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:811 (sofia/external/73000666) State HANGUP going to sleep
send 678 bytes to udp/[172.16.19.13]:5060 at 23:56:20.667458:
------------------------------------------------------------------------
BYE sip:73000666@172.16.19.13:5060;user=phone SIP/2.0
Via: SIP/2.0/UDP 169.255.80.71:5080;rport;branch=z9hG4bKv0rDgD8Z3SUeQ
Route: <sip:172.16.19.13:5060;transport=udp;lr>
Max-Forwards: 70
From: "26779117000" <sip:FreeSWITCH@172.16.19.13>;tag=Qy43atXm0De8j
To: <sip:73000666@172.16.19.13>;tag=sbc0807dnzfdjg1-CC-27
Call-ID: e72fa162-f5c0-1236-f78b-005056aaf96b
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:578 (sofia/external/73000666) State Change CS_HANGUP -> CS_REPORTING
CSeq: 124775420 BYE
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Reason: Q.850;cause=16;text="NORMAL_CLEARING"
Content-Length: 0
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16

------------------------------------------------------------------------
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:543 (sofia/external/73000666) Running State Change CS_REPORTING
2018-06-28 23:56:20.666494 [DEBUG] switch_ivr_bridge.c:1697 sofia/external/73000666 skip receive message [UNBRIDGE] (channel is hungup already)
2018-06-28 23:56:20.666494 [DEBUG] switch_ivr_bridge.c:1700 sofia/internal/26779117000@169.255.80.67:5061 skip receive message [UNBRIDGE] (channel is hungup already)
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:897 (sofia/external/73000666) State REPORTING
2018-06-28 23:56:20.666494 [ERR] mod_xml_radius.c:933 Result of true match: 172.16.19.13 == ^169\.255\.80\.67
2018-06-28 23:56:20.666494 [DEBUG] switch_core_session.c:2797 sofia/internal/26779117000@169.255.80.67:5061 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/26779117000@169.255.80.67:5061) State EXECUTE going to sleep
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_HANGUP
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/26779117000@169.255.80.67:5061) Callstate Change ACTIVE -> HANGUP
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/26779117000@169.255.80.67:5061) State HANGUP
2018-06-28 23:56:20.666494 [DEBUG] mod_sofia.c:437 Channel sofia/internal/26779117000@169.255.80.67:5061 hanging up, cause: NORMAL_CLEARING
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:60 sofia/internal/26779117000@169.255.80.67:5061 Standard HANGUP, cause: NORMAL_CLEARING
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/26779117000@169.255.80.67:5061) State HANGUP going to sleep
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/26779117000@169.255.80.67:5061) State Change CS_HANGUP -> CS_REPORTING
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_REPORTING
2018-06-28 23:56:20.666494 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/26779117000@169.255.80.67:5061) State REPORTING
2018-06-28 23:56:20.666494 [ERR] mod_xml_radius.c:933 Result of true match: 169.255.80.71 == ^169\.255\.80\.67
recv 308 bytes from udp/[172.16.19.13]:5060 at 23:56:20.723393:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 169.255.80.71:5080;branch=z9hG4bKv0rDgD8Z3SUeQ;rport=5080
Call-ID: e72fa162-f5c0-1236-f78b-005056aaf96b
From: "26779117000"<sip:FreeSWITCH@172.16.19.13>;tag=Qy43atXm0De8j
To: <sip:73000666@172.16.19.13>;tag=sbc0807dnzfdjg1-CC-27
CSeq: 124775420 BYE
Content-Length: 0

------------------------------------------------------------------------
2018-06-28 23:56:21.126499 [INFO] mod_xml_radius.c:1044 mod_xml_radius: Accounting Stop success
2018-06-28 23:56:21.126499 [DEBUG] switch_core_state_machine.c:174 sofia/external/73000666 Standard REPORTING, cause: NORMAL_CLEARING
2018-06-28 23:56:21.126499 [DEBUG] switch_core_state_machine.c:897 (sofia/external/73000666) State REPORTING going to sleep
2018-06-28 23:56:21.126499 [DEBUG] switch_core_state_machine.c:569 (sofia/external/73000666) State Change CS_REPORTING -> CS_DESTROY
2018-06-28 23:56:21.126499 [DEBUG] switch_core_session.c:1647 Session 39128 (sofia/external/73000666) Locked, Waiting on external entities
2018-06-28 23:56:21.126499 [NOTICE] switch_core_session.c:1665 Session 39128 (sofia/external/73000666) Ended
2018-06-28 23:56:21.126499 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/73000666 [CS_DESTROY]
2018-06-28 23:56:21.126499 [DEBUG] switch_core_state_machine.c:700 (sofia/external/73000666) Running State Change CS_DESTROY
2018-06-28 23:56:21.126499 [DEBUG] switch_core_state_machine.c:710 (sofia/external/73000666) State DESTROY
2018-06-28 23:56:21.126499 [DEBUG] mod_sofia.c:342 sofia/external/73000666 SOFIA DESTROY
2018-06-28 23:56:21.126499 [DEBUG] switch_core_state_machine.c:181 sofia/external/73000666 Standard DESTROY
2018-06-28 23:56:21.126499 [DEBUG] switch_core_state_machine.c:710 (sofia/external/73000666) State DESTROY going to sleep
2018-06-28 23:56:21.146491 [INFO] mod_xml_radius.c:1044 mod_xml_radius: Accounting Stop success
2018-06-28 23:56:21.146491 [DEBUG] switch_core_state_machine.c:174 sofia/internal/26779117000@169.255.80.67:5061 Standard REPORTING, cause: NORMAL_CLEARING
2018-06-28 23:56:21.146491 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/26779117000@169.255.80.67:5061) State REPORTING going to sleep
2018-06-28 23:56:21.146491 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/26779117000@169.255.80.67:5061) State Change CS_REPORTING -> CS_DESTROY
2018-06-28 23:56:21.146491 [DEBUG] switch_core_session.c:1647 Session 39127 (sofia/internal/26779117000@169.255.80.67:5061) Locked, Waiting on external entities
2018-06-28 23:56:21.146491 [NOTICE] switch_core_session.c:1665 Session 39127 (sofia/internal/26779117000@169.255.80.67:5061) Ended
2018-06-28 23:56:21.146491 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/26779117000@169.255.80.67:5061 [CS_DESTROY]
2018-06-28 23:56:21.146491 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/26779117000@169.255.80.67:5061) Running State Change CS_DESTROY
2018-06-28 23:56:21.146491 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/26779117000@169.255.80.67:5061) State DESTROY
2018-06-28 23:56:21.146491 [DEBUG] mod_sofia.c:342 sofia/internal/26779117000@169.255.80.67:5061 SOFIA DESTROY
2018-06-28 23:56:21.146491 [DEBUG] switch_core_state_machine.c:181 sofia/internal/26779117000@169.255.80.67:5061 Standard DESTROY
2018-06-28 23:56:21.146491 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/26779117000@169.255.80.67:5061) State DESTROY going to sleep
recv 311 bytes from udp/[172.16.19.13]:5060 at 23:56:21.313950:
------------------------------------------------------------------------
OPTIONS sip:169.255.80.71:5080 SIP/2.0
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bKjd5kvik5kilgnhfekkzd2zhdfT39623
Call-ID: isbcznvz22lljgv5zdhdevf2glgiz5722i52@SoftX3000
From: <sip:172.16.19.13:5060>;tag=sbc0805zeg5kjnd
To: <sip:169.255.80.71>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0

------------------------------------------------------------------------
send 540 bytes to udp/[172.16.19.13]:5060 at 23:56:21.314181:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bKjd5kvik5kilgnhfekkzd2zhdfT39623
From: <sip:172.16.19.13:5060>;tag=sbc0805zeg5kjnd
To: <sip:169.255.80.71>;tag=r7XvcNerXp4te
Call-ID: isbcznvz22lljgv5zdhdevf2glgiz5722i52@SoftX3000
CSeq: 1 OPTIONS
Contact: <sip:169.255.80.71:5080>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Length: 0

------------------------------------------------------------------------
recv 548 bytes from udp/[169.255.80.87]:5060 at 23:56:22.075883:
------------------------------------------------------------------------
OPTIONS sip:169.255.80.71 SIP/2.0
Via: SIP/2.0/UDP 169.255.80.87:5060;branch=z9hG4bK5b23639b;rport
Max-Forwards: 70
From: "asterisk" <sip:asterisk@169.255.80.87>;tag=as385b31a5
To: <sip:169.255.80.71>
Contact: <sip:asterisk@169.255.80.87:5060>
Call-ID: 79c8d4f76ac2304f139658fe49d74b6d@169.255.80.87:5060
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 11.25.1-vici
Date: Thu, 28 Jun 2018 21:56:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

------------------------------------------------------------------------
send 688 bytes to udp/[169.255.80.87]:5060 at 23:56:22.076225:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 169.255.80.87:5060;branch=z9hG4bK5b23639b;rport=5060
From: "asterisk" <sip:asterisk@169.255.80.87>;tag=as385b31a5
To: <sip:169.255.80.71>;tag=9NmtrN0SUtQUa
Call-ID: 79c8d4f76ac2304f139658fe49d74b6d@169.255.80.87:5060
CSeq: 102 OPTIONS
Contact: <sip:169.255.80.71>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
Content-Length: 0

------------------------------------------------------------------------
recv 311 bytes from udp/[172.16.19.13]:5060 at 23:56:41.374402:
------------------------------------------------------------------------
OPTIONS sip:169.255.80.71:5080 SIP/2.0
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bK21hhdn52k7jhl1zjdiivfgizhT39627
Call-ID: isbcdjdhj2hlg2k5vvnzgkn2hn5j7zhnjk55@SoftX3000
From: <sip:172.16.19.13:5060>;tag=sbc0805izilgkh5
To: <sip:169.255.80.71>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0

------------------------------------------------------------------------
send 540 bytes to udp/[172.16.19.13]:5060 at 23:56:41.374666:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bK21hhdn52k7jhl1zjdiivfgizhT39627
From: <sip:172.16.19.13:5060>;tag=sbc0805izilgkh5
To: <sip:169.255.80.71>;tag=SgQNegZUtZtDa
Call-ID: isbcdjdhj2hlg2k5vvnzgkn2hn5j7zhnjk55@SoftX3000
CSeq: 1 OPTIONS
Contact: <sip:169.255.80.71:5080>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Length: 0

------------------------------------------------------------------------
recv 311 bytes from udp/[172.16.19.13]:5060 at 23:57:01.434215:
------------------------------------------------------------------------
OPTIONS sip:169.255.80.71:5080 SIP/2.0
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bKv7ez2jilfhvlnf5d2izv7enjeT09107
Call-ID: isbcj5v7nvekjfkzeggdghzneffkk2ke1ihl@SoftX3000
From: <sip:172.16.19.13:5060>;tag=sbc0807jz7dv2vl
To: <sip:169.255.80.71>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0

------------------------------------------------------------------------
send 540 bytes to udp/[172.16.19.13]:5060 at 23:57:01.434403:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bKv7ez2jilfhvlnf5d2izv7enjeT09107
From: <sip:172.16.19.13:5060>;tag=sbc0807jz7dv2vl
To: <sip:169.255.80.71>;tag=tSgegBgZQ8g0N
Call-ID: isbcj5v7nvekjfkzeggdghzneffkk2ke1ihl@SoftX3000
CSeq: 1 OPTIONS
Contact: <sip:169.255.80.71:5080>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Length: 0

------------------------------------------------------------------------
recv 311 bytes from udp/[172.16.19.13]:5060 at 23:57:21.494491:
------------------------------------------------------------------------
OPTIONS sip:169.255.80.71:5080 SIP/2.0
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bKijhg1k5d2je1zlhfkz7f7dn1dT09108
Call-ID: isbcfzzgllf7gd1hl2kh2ngek21gzhkjkk52@SoftX3000
From: <sip:172.16.19.13:5060>;tag=sbc0807lk151k2h
To: <sip:169.255.80.71>
CSeq: 1 OPTIONS
Max-Forwards: 70
Content-Length: 0

------------------------------------------------------------------------
send 540 bytes to udp/[172.16.19.13]:5060 at 23:57:21.494694:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.19.13:5060;branch=z9hG4bKijhg1k5d2je1zlhfkz7f7dn1dT09108
From: <sip:172.16.19.13:5060>;tag=sbc0807lk151k2h
To: <sip:169.255.80.71>;tag=U296H602mH7jH
Call-ID: isbcfzzgllf7gd1hl2kh2ngek21gzhkjkk52@SoftX3000
CSeq: 1 OPTIONS
Contact: <sip:169.255.80.71:5080>
User-Agent: FreeSWITCH
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Length: 0

------------------------------------------------------------------------
recv 548 bytes from udp/[169.255.80.87]:5060 at 23:57:22.076910:
------------------------------------------------------------------------
OPTIONS sip:169.255.80.71 SIP/2.0
Via: SIP/2.0/UDP 169.255.80.87:5060;branch=z9hG4bK0443b2c8;rport
Max-Forwards: 70
From: "asterisk" <sip:asterisk@169.255.80.87>;tag=as71eb78bc
To: <sip:169.255.80.71>
Contact: <sip:asterisk@169.255.80.87:5060>
Call-ID: 06e7ca3c15a3fe81078c55aa5d1ff71c@169.255.80.87:5060
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 11.25.1-vici
Date: Thu, 28 Jun 2018 21:57:22 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

------------------------------------------------------------------------
send 688 bytes to udp/[169.255.80.87]:5060 at 23:57:22.077289:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 169.255.80.87:5060;branch=z9hG4bK0443b2c8;rport=5060
From: "asterisk" <sip:asterisk@169.255.80.87>;tag=as71eb78bc
To: <sip:169.255.80.71>;tag=aZDKtgHXr3Dep
Call-ID: 06e7ca3c15a3fe81078c55aa5d1ff71c@169.255.80.87:5060
CSeq: 102 OPTIONS
Contact: <sip:169.255.80.71>
User-Agent: FreeSWITCH
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,059
576
113
They may want caller ID in from, in which case set this in the gateway advanced settings.

Do not forget to stop and start the gateway after modification.
 
Status
Not open for further replies.