span style="color: green; font-weight: bold;"> 2019-06-14 20:10:44.679541 [INFO] switch_core.c:2487
FreeSWITCH Version 1.8.6-7-93b4c92e75~64bit (-7-93b4c92e75 64bit)
FreeSWITCH Started
Max Sessions [1000]
Session Rate [30]
SQL [Enabled]
freeswitch@deb209> sofia global siptrace on
+OK Global siptrace on
freeswitch@deb209> recv 794 bytes from udp/[172.16.16.99]:5060 at 20:11:00.838080:
------------------------------------------------------------------------
INVITE sip:number_to_call@deb209.lan SIP/2.0
Via: SIP/2.0/UDP 172.16.16.99;rport;branch=z9hG4bKzsxbgvng
Max-Forwards: 70
To: <sip:number_to_call@deb209.lan>
From: "test" <sip:1001@deb209.lan>;tag=roasw
Call-ID: auboqghblvjrqxh@PC-Z97
CSeq: 910 INVITE
Contact: <sip:1001@172.16.16.99>
Content-Type: application/sdp
Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,PRACK,REFER,NOTIFY,SUBSCRIBE,INFO,MESSAGE
Supported: replaces,norefersub,100rel
User-Agent: Twinkle/1.10.1
Content-Length: 309
v=0
o=twinkle 240836978 1585164347 IN IP4 172.16.16.99
s=-
c=IN IP4 172.16.16.99
t=0 0
m=audio 22110 RTP/AVP 8 0 97 98 3 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:97 speex/8000
a=rtpmap:98 speex/16000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
------------------------------------------------------------------------
2019-06-14 20:11:00.826987 [NOTICE] switch_channel.c:1114 New Channel sofia/internal/1001@deb209.lan [24f3394f-c530-42a6-91c7-49c4cc6306e6]
send 796 bytes to udp/[172.16.16.99]:5060 at 20:11:00.839124:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 172.16.16.99;rport=5060;branch=z9hG4bKzsxbgvng
From: "test" <sip:1001@deb209.lan>;tag=roasw
To: <sip:number_to_call@deb209.lan>;tag=jQr01rgX7p9ep
Call-ID: auboqghblvjrqxh@PC-Z97
CSeq: 910 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.6-7-93b4c92e75~64bit
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="deb209.lan", nonce="06d647d3-498f-4787-8586-7cc4c724903a", algorithm=MD5, qop="auth"
Content-Length: 0
------------------------------------------------------------------------
recv 311 bytes from udp/[172.16.16.99]:5060 at 20:11:00.839511:
------------------------------------------------------------------------
ACK sip:number_to_call@deb209.lan SIP/2.0
Via: SIP/2.0/UDP 172.16.16.99;rport;branch=z9hG4bKzsxbgvng
Max-Forwards: 70
To: <sip:number_to_call@deb209.lan>;tag=jQr01rgX7p9ep
From: "test" <sip:1001@deb209.lan>;tag=roasw
Call-ID: auboqghblvjrqxh@PC-Z97
CSeq: 910 ACK
User-Agent: Twinkle/1.10.1
Content-Length: 0
------------------------------------------------------------------------
recv 1034 bytes from udp/[172.16.16.99]:5060 at 20:11:00.839692:
------------------------------------------------------------------------
INVITE sip:number_to_call@deb209.lan SIP/2.0
Via: SIP/2.0/UDP 172.16.16.99;rport;branch=z9hG4bKcaittfdm
Max-Forwards: 70
Proxy-Authorization: Digest username="1001",realm="deb209.lan",nonce="06d647d3-498f-4787-8586-7cc4c724903a",uri="sip:number_to_call@deb209.lan",response="07eee45a79b2c4e303187fcfac03977d",algorithm=MD5,cnonce="65cd38f7f7",qop=auth,nc=00000001
To: <sip:number_to_call@deb209.lan>
From: "test" <sip:1001@deb209.lan>;tag=roasw
Call-ID: auboqghblvjrqxh@PC-Z97
CSeq: 911 INVITE
Contact: <sip:1001@172.16.16.99>
Content-Type: application/sdp
Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,PRACK,REFER,NOTIFY,SUBSCRIBE,INFO,MESSAGE
Supported: replaces,norefersub,100rel
User-Agent: Twinkle/1.10.1
Content-Length: 309
v=0
o=twinkle 240836978 1585164347 IN IP4 172.16.16.99
s=-
c=IN IP4 172.16.16.99
t=0 0
m=audio 22110 RTP/AVP 8 0 97 98 3 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:97 speex/8000
a=rtpmap:98 speex/16000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
------------------------------------------------------------------------
2019-06-14 20:11:00.860573 [INFO] mod_dialplan_xml.c:637 Processing test <1001>->number_to_call in context default
send 295 bytes to udp/[172.16.16.99]:5060 at 20:11:00.862289:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.16.16.99;rport=5060;branch=z9hG4bKcaittfdm
From: "test" <sip:1001@deb209.lan>;tag=roasw
To: <sip:number_to_call@deb209.lan>
Call-ID: auboqghblvjrqxh@PC-Z97
CSeq: 911 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.6-7-93b4c92e75~64bit
Content-Length: 0
------------------------------------------------------------------------
2019-06-14 20:11:00.860573 [NOTICE] switch_channel.c:1114 New Channel sofia/external/number_to_call [65ba406e-0e6a-4d57-b5dd-2f53b34873b0]
send 1161 bytes to udp/[my_proxy_ip]:5060 at 20:11:00.866132:
------------------------------------------------------------------------
INVITE sip:number_to_call@123.ims.my_provider.net:5060 SIP/2.0
Via: SIP/2.0/UDP my_public_ip:5080;rport;branch=z9hG4bK9X12v734KmrZe
Max-Forwards: 69
From: "123456789" <sip:123456789@ims.my_provider.net>;tag=8g41KmQBgB0pm
To: <sip:number_to_call@123.ims.my_provider.net:5060>
Call-ID: 9b55b86c-0972-1238-0aa8-080027755653
CSeq: 5714138 INVITE
Contact: <sip:123456789@my_public_ip:5080;transport=udp;gw=outbound-my_provider>
User-Agent: ZYXEL VMG8924-B10D V5.13(ABLH.0)b6
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
Supported: precondition, 100rel, timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 248
X-FS-Support: update_display,send_info
Remote-Party-ID: "123456789" <sip:123456789@ims.my_provider.net>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1560495772 1560495773 IN IP4 my_public_ip
s=FreeSWITCH
c=IN IP4 my_public_ip
t=0 0
m=audio 40088 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
recv 332 bytes from udp/[my_proxy_ip]:5060 at 20:11:00.880195:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP my_public_ip:5080;rport=5080;branch=z9hG4bK9X12v734KmrZe
To: <sip:number_to_call@123.ims.my_provider.net>
From: "123456789"<sip:123456789@ims.my_provider.net>;tag=8g41KmQBgB0pm
Call-ID: 9b55b86c-0972-1238-0aa8-080027755653
CSeq: 5714138 INVITE
User-Agent: ZTE-SBC
Content-Length: 0
------------------------------------------------------------------------
recv 805 bytes from udp/[my_proxy_ip]:5060 at 20:11:01.076014:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP my_public_ip:5080;rport=5080;branch=z9hG4bK9X12v734KmrZe
To: <sip:number_to_call@123.ims.my_provider.net>;tag=ztesipsuDgPzVN*2-4-20481*giag.2
From: "?"<sip:123456789@ims.my_provider.net>;tag=8g41KmQBgB0pm
Call-ID: 9b55b86c-0972-1238-0aa8-080027755653
CSeq: 5714138 INVITE
Contact: <sip:my_proxy_ip:5060;zte-did=2-4-20481-2565-12>
RSeq: 1710764156
Supported: 100rel
P-Early-Media: sendonly
Require: 100rel
Accept: application/sdp,
multipart/mixed
Reason: SIP;cause=404;text="Not Found"
Content-Type: application/sdp
Content-Length: 174
Content-Disposition: session
v=0
o=ZTE 75885206 1020429859 IN IP4 94.32.130.114
s=phone-call
c=IN IP4 94.32.130.114
t=0 0
m=audio 32790 RTP/AVP 8 101
a=rtpmap:101 telephone-event/8000
a=sendrecv
------------------------------------------------------------------------
send 728 bytes to udp/[my_proxy_ip]:5060 at 20:11:01.076200:
------------------------------------------------------------------------
PRACK sip:my_proxy_ip:5060;zte-did=2-4-20481-2565-12 SIP/2.0
Via: SIP/2.0/UDP my_public_ip:5080;rport;branch=z9hG4bKa7tUy2m8gXeja
Max-Forwards: 70
From: "123456789" <sip:123456789@ims.my_provider.net>;tag=8g41KmQBgB0pm
To: <sip:number_to_call@123.ims.my_provider.net:5060>;tag=ztesipsuDgPzVN*2-4-20481*giag.2
Call-ID: 9b55b86c-0972-1238-0aa8-080027755653
CSeq: 5714139 PRACK
Contact: <sip:123456789@my_public_ip:5080;transport=udp;gw=outbound-my_provider>
RAck: 1710764156 5714138 INVITE
User-Agent: ZYXEL VMG8924-B10D V5.13(ABLH.0)b6
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
Supported: precondition, 100rel, timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
2019-06-14 20:11:01.059362 [NOTICE] sofia.c:7336 Pre-Answer sofia/external/number_to_call!
2019-06-14 20:11:01.059362 [INFO] switch_ivr_originate.c:3752 Sending early media
2019-06-14 20:11:01.079472 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@deb209.lan!
send 1132 bytes to udp/[172.16.16.99]:5060 at 20:11:01.083881:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 172.16.16.99;rport=5060;branch=z9hG4bKcaittfdm
From: "test" <sip:1001@deb209.lan>;tag=roasw
To: <sip:number_to_call@deb209.lan>;tag=K0HS3K104ZZ1H
Call-ID: auboqghblvjrqxh@PC-Z97
CSeq: 911 INVITE
Contact: <sip:number_to_call@my_public_ip:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.8.6-7-93b4c92e75~64bit
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
P-Early-Media: sendonly
Remote-Party-ID: "Outbound Call" <sip:number_to_call@deb209.lan>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1560495861 1560495862 IN IP4 172.16.16.209
s=FreeSWITCH
c=IN IP4 172.16.16.209
t=0 0
m=audio 40000 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
------------------------------------------------------------------------
recv 329 bytes from udp/[my_proxy_ip]:5060 at 20:11:01.134893:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP my_public_ip:5080;rport=5080;branch=z9hG4bKa7tUy2m8gXeja
To: <sip:number_to_call@123.ims.my_provider.net>;tag=ztesipsuDgPzVN*2-4-20481*giag.2
From: "?"<sip:123456789@ims.my_provider.net>;tag=8g41KmQBgB0pm
Call-ID: 9b55b86c-0972-1238-0aa8-080027755653
CSeq: 5714139 PRACK
Content-Length: 0
------------------------------------------------------------------------
recv 396 bytes from udp/[my_proxy_ip]:5060 at 20:11:06.525419:
------------------------------------------------------------------------
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP my_public_ip:5080;rport=5080;branch=z9hG4bK9X12v734KmrZe
To: <sip:number_to_call@123.ims.my_provider.net>;tag=ztesipsuDgPzVN*2-4-20481*giag.2
From: "?"<sip:123456789@ims.my_provider.net>;tag=8g41KmQBgB0pm
Call-ID: 9b55b86c-0972-1238-0aa8-080027755653
CSeq: 5714138 INVITE
X-ZTE-Cause: "CSCF-1.3154123179.miicscf1.ims.my_provider.net"
Content-Length: 0
------------------------------------------------------------------------
send 395 bytes to udp/[my_proxy_ip]:5060 at 20:11:06.525621:
------------------------------------------------------------------------
ACK sip:number_to_call@123.ims.my_provider.net:5060 SIP/2.0
Via: SIP/2.0/UDP my_public_ip:5080;rport;branch=z9hG4bK9X12v734KmrZe
Max-Forwards: 69
From: "123456789" <sip:123456789@ims.my_provider.net>;tag=8g41KmQBgB0pm
To: <sip:number_to_call@123.ims.my_provider.net>;tag=ztesipsuDgPzVN*2-4-20481*giag.2
Call-ID: 9b55b86c-0972-1238-0aa8-080027755653
CSeq: 5714138 ACK
Content-Length: 0
------------------------------------------------------------------------
2019-06-14 20:11:06.522456 [NOTICE] sofia.c:8516 Hangup sofia/external/number_to_call [CS_EXCHANGE_MEDIA] [UNALLOCATED_NUMBER]
2019-06-14 20:11:06.522456 [NOTICE] switch_channel.c:4901 Hangup sofia/internal/1001@deb209.lan [CS_EXECUTE] [UNALLOCATED_NUMBER]
2019-06-14 20:11:06.522456 [NOTICE] switch_core_session.c:1744 Session 2 (sofia/external/number_to_call) Ended
2019-06-14 20:11:06.522456 [NOTICE] switch_core_session.c:1748 Close Channel sofia/external/number_to_call [CS_DESTROY]
send 855 bytes to udp/[172.16.16.99]:5060 at 20:11:06.542699:
------------------------------------------------------------------------
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 172.16.16.99;rport=5060;branch=z9hG4bKcaittfdm
Max-Forwards: 70
From: "test" <sip:1001@deb209.lan>;tag=roasw
To: <sip:number_to_call@deb209.lan>;tag=K0HS3K104ZZ1H
Call-ID: auboqghblvjrqxh@PC-Z97
CSeq: 911 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.8.6-7-93b4c92e75~64bit
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
Reason: Q.850;cause=1;text="UNALLOCATED_NUMBER"
Content-Length: 0
X-ZTE-Cause: "CSCF-1.3154123179.miicscf1.ims.my_provider.net"
Remote-Party-ID: "Outbound Call" <sip:number_to_call@deb209.lan>;party=calling;privacy=off;screen=no
------------------------------------------------------------------------
recv 551 bytes from udp/[172.16.16.99]:5060 at 20:11:06.543387:
------------------------------------------------------------------------
ACK sip:number_to_call@deb209.lan SIP/2.0
Via: SIP/2.0/UDP 172.16.16.99;rport;branch=z9hG4bKcaittfdm
Max-Forwards: 70
Proxy-Authorization: Digest username="1001",realm="deb209.lan",nonce="06d647d3-498f-4787-8586-7cc4c724903a",uri="sip:number_to_call@deb209.lan",response="07eee45a79b2c4e303187fcfac03977d",algorithm=MD5,cnonce="65cd38f7f7",qop=auth,nc=00000001
To: <sip:number_to_call@deb209.lan>;tag=K0HS3K104ZZ1H
From: "test" <sip:1001@deb209.lan>;tag=roasw
Call-ID: auboqghblvjrqxh@PC-Z97
CSeq: 911 ACK
User-Agent: Twinkle/1.10.1
Content-Length: 0
------------------------------------------------------------------------
2019-06-14 20:11:06.522456 [NOTICE] switch_core_session.c:1744 Session 1 (sofia/internal/1001@deb209.lan) Ended
2019-06-14 20:11:06.522456 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/1001@deb209.lan [CS_DESTROY]
...
+OK
2019-06-14 20:11:14.139362 [INFO] switch_core.c:2854 Shutting down
2019-06-14 20:11:14.721580 [CONSOLE] switch_core.c:2981 End existing sessions
2019-06-14 20:11:14.721580 [CONSOLE] switch_core.c:2983 Clean up modules.