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