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: From: "test" ;tag=roasw Call-ID: auboqghblvjrqxh@PC-Z97 CSeq: 910 INVITE Contact: 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" ;tag=roasw To: ;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: ;tag=jQr01rgX7p9ep From: "test" ;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: From: "test" ;tag=roasw Call-ID: auboqghblvjrqxh@PC-Z97 CSeq: 911 INVITE Contact: 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" ;tag=roasw To: 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" ;tag=8g41KmQBgB0pm To: Call-ID: 9b55b86c-0972-1238-0aa8-080027755653 CSeq: 5714138 INVITE Contact: 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" ;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: From: "123456789";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: ;tag=ztesipsuDgPzVN*2-4-20481*giag.2 From: "?";tag=8g41KmQBgB0pm Call-ID: 9b55b86c-0972-1238-0aa8-080027755653 CSeq: 5714138 INVITE Contact: 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" ;tag=8g41KmQBgB0pm To: ;tag=ztesipsuDgPzVN*2-4-20481*giag.2 Call-ID: 9b55b86c-0972-1238-0aa8-080027755653 CSeq: 5714139 PRACK Contact: 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" ;tag=roasw To: ;tag=K0HS3K104ZZ1H Call-ID: auboqghblvjrqxh@PC-Z97 CSeq: 911 INVITE Contact: 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" ;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: ;tag=ztesipsuDgPzVN*2-4-20481*giag.2 From: "?";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: ;tag=ztesipsuDgPzVN*2-4-20481*giag.2 From: "?";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" ;tag=8g41KmQBgB0pm To: ;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" ;tag=roasw To: ;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" ;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: ;tag=K0HS3K104ZZ1H From: "test" ;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.