freeswitch@freeswitch-test> originate {ignore_early_media=true,origination_caller_id_number=13342120202}sofia/gateway/flowroute/13342120101 &txfax(/tmp/txfax-sample.tiff) +OK 1e8b620e-426a-44ba-ab13-862043c77fe4 2017-05-11 15:15:16.749601 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables 2017-05-11 15:15:16.749601 [NOTICE] switch_channel.c:1104 New Channel sofia/external/13342120101 [1e8b620e-426a-44ba-ab13-862043c77fe4] 2017-05-11 15:15:16.749601 [DEBUG] mod_sofia.c:5026 (sofia/external/13342120101) State Change CS_NEW -> CS_INIT 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:584 (sofia/external/13342120101) Running State Change CS_INIT (Cur 1 Tot 125) 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:627 (sofia/external/13342120101) State INIT 2017-05-11 15:15:16.749601 [DEBUG] mod_sofia.c:93 sofia/external/13342120101 SOFIA INIT 2017-05-11 15:15:16.749601 [DEBUG] sofia_glue.c:1298 sofia/external/13342120101 sending invite version: 1.9.0 git 5d4ac1c 2017-05-05 16:19:17Z 64bit Local SDP: v=0 o=FreeSWITCH 1494501984 1494501985 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 28132 RTP/AVP 102 9 0 8 105 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:105 telephone-event/48000 a=fmtp:105 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 23772 RTP/AVP 103 104 34 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtpmap:104 H264/90000 a=rtpmap:34 H263/90000 a=sendrecv a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli a=rtcp-fb:104 ccm fir a=rtcp-fb:104 ccm tmmbr a=rtcp-fb:104 nack a=rtcp-fb:104 nack pli a=rtcp-fb:34 ccm fir a=rtcp-fb:34 ccm tmmbr a=rtcp-fb:34 nack a=rtcp-fb:34 nack pli 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:40 sofia/external/13342120101 Standard INIT 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:48 (sofia/external/13342120101) State Change CS_INIT -> CS_ROUTING 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:627 (sofia/external/13342120101) State INIT going to sleep 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:584 (sofia/external/13342120101) Running State Change CS_ROUTING (Cur 1 Tot 125) 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:643 (sofia/external/13342120101) State ROUTING 2017-05-11 15:15:16.749601 [DEBUG] mod_sofia.c:154 sofia/external/13342120101 SOFIA ROUTING 2017-05-11 15:15:16.749601 [DEBUG] switch_ivr_originate.c:67 (sofia/external/13342120101) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:643 (sofia/external/13342120101) State ROUTING going to sleep 2017-05-11 15:15:16.749601 [DEBUG] switch_core_state_machine.c:584 (sofia/external/13342120101) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 125) send 1700 bytes to udp/[216.115.69.144]:5060 at 15:15:15.435596: ------------------------------------------------------------------------ INVITE sip:13342120101@sip.flowroute.com SIP/2.0 Via: SIP/2.0/UDP 192.168.1.9:5080;rport;branch=z9hG4bKF1vXvpjXpH7pe Max-Forwards: 70 From: "" ;tag=ZFBZHj5e9D11p To: Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928993 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit 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: 855 X-FS-Support: update_display,send_info Remote-Party-ID: ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1494501984 1494501985 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 28132 RTP/AVP 102 9 0 8 105 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:105 telephone-event/48000 a=fmtp:105 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 23772 RTP/AVP 103 104 34 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtpmap:104 H264/90000 a=rtpmap:34 H263/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli a=rtcp-fb:104 ccm fir a=rtcp-fb:104 ccm tmmbr a=rtcp-fb:104 nack a=rtcp-fb:104 nack pli a=rtcp-fb:34 ccm fir a=rtcp-fb:34 ccm tmmbr a=rtcp-fb:34 nack a=rtcp-fb:34 nack pli ------------------------------------------------------------------------ 2017-05-11 15:15:16.768624 [DEBUG] switch_core_state_machine.c:662 (sofia/external/13342120101) State CONSUME_MEDIA 2017-05-11 15:15:16.768624 [DEBUG] switch_core_state_machine.c:662 (sofia/external/13342120101) State CONSUME_MEDIA going to sleep 2017-05-11 15:15:16.768624 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [calling][0] recv 312 bytes from udp/[216.115.69.144]:5060 at 15:15:15.578778: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.9:5080;rport=5080;branch=z9hG4bKF1vXvpjXpH7pe;received=68.157.150.128 From: "" ;tag=ZFBZHj5e9D11p To: Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928993 INVITE Content-Length: 0 ------------------------------------------------------------------------ recv 485 bytes from udp/[216.115.69.144]:5060 at 15:15:15.625228: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.1.9:5080;received=68.157.150.128;rport=5080;branch=z9hG4bKF1vXvpjXpH7pe From: "" ;tag=ZFBZHj5e9D11p To: ;tag=870a5b262384e4f9f82f59836d699db5.b95b Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928993 INVITE Proxy-Authenticate: Digest realm="sip.flowroute.com", nonce="WRS5b1kUuEN/adTD6CuWvpqXwB8IjeFy", qop="auth" Content-Length: 0 ------------------------------------------------------------------------ send 367 bytes to udp/[216.115.69.144]:5060 at 15:15:15.625355: ------------------------------------------------------------------------ ACK sip:13342120101@sip.flowroute.com SIP/2.0 Via: SIP/2.0/UDP 192.168.1.9:5080;rport;branch=z9hG4bKF1vXvpjXpH7pe Max-Forwards: 70 From: "" ;tag=ZFBZHj5e9D11p To: ;tag=870a5b262384e4f9f82f59836d699db5.b95b Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928993 ACK Content-Length: 0 ------------------------------------------------------------------------ send 1975 bytes to udp/[216.115.69.144]:5060 at 15:15:15.625782: ------------------------------------------------------------------------ INVITE sip:13342120101@sip.flowroute.com SIP/2.0 Via: SIP/2.0/UDP 192.168.1.9:5080;rport;branch=z9hG4bKgappyH30KtX9S Max-Forwards: 70 From: "" ;tag=ZFBZHj5e9D11p To: Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928994 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Proxy-Authorization: Digest username="28308479", realm="sip.flowroute.com", nonce="WRS5b1kUuEN/adTD6CuWvpqXwB8IjeFy", cnonce="AVpTnbEhEjWe0wgAJ3evvg", algorithm=MD5, uri="sip:13342120101@sip.flowroute.com", response="6684a94d055d89e1cca692ab87e86933", qop=auth, nc=00000001 Content-Type: application/sdp Content-Disposition: session Content-Length: 855 X-FS-Support: update_display,send_info Remote-Party-ID: ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1494501984 1494501985 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 28132 RTP/AVP 102 9 0 8 105 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:105 telephone-event/48000 a=fmtp:105 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 23772 RTP/AVP 103 104 34 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtpmap:104 H264/90000 a=rtpmap:34 H263/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli a=rtcp-fb:104 ccm fir a=rtcp-fb:104 ccm tmmbr a=rtcp-fb:104 nack a=rtcp-fb:104 nack pli a=rtcp-fb:34 ccm fir a=rtcp-fb:34 ccm tmmbr a=rtcp-fb:34 nack a=rtcp-fb:34 nack pli ------------------------------------------------------------------------ 2017-05-11 15:15:16.949177 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [calling][0] recv 312 bytes from udp/[216.115.69.144]:5060 at 15:15:15.792413: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.9:5080;rport=5080;branch=z9hG4bKgappyH30KtX9S;received=68.157.150.128 From: "" ;tag=ZFBZHj5e9D11p To: Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928994 INVITE Content-Length: 0 ------------------------------------------------------------------------ recv 496 bytes from udp/[216.115.69.144]:5060 at 15:15:20.611897: ------------------------------------------------------------------------ OPTIONS sip:68.157.150.128:5080;transport=udp SIP/2.0 Max-Forwards: 10 Record-Route: Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK5853.7bfee1e475e7cc412b84b42c8bd2c972.0 Via: SIP/2.0/UDP 216.115.69.131:5060;branch=0 Route: From: sip:ping@invalid;tag=5b2a14e5 To: sip:68.157.150.128:5080;transport=udp Call-ID: 84599eb4-4c8c7c47-c61d9c2@216.115.69.131 CSeq: 1 OPTIONS Content-Length: 0 ------------------------------------------------------------------------ send 723 bytes to udp/[216.115.69.144]:5060 at 15:15:20.612601: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK5853.7bfee1e475e7cc412b84b42c8bd2c972.0 Via: SIP/2.0/UDP 216.115.69.131:5060;branch=0 Record-Route: From: sip:ping@invalid;tag=5b2a14e5 To: ;transport=udp;tag=0r4QKDpj6pQmj Call-ID: 84599eb4-4c8c7c47-c61d9c2@216.115.69.131 CSeq: 1 OPTIONS Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit 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 1089 bytes from udp/[216.115.69.144]:5060 at 15:15:22.673682: ------------------------------------------------------------------------ INVITE sip:13342120101@192.168.1.9:5080;transport=udp SIP/2.0 Record-Route: From: "LINDEN AL" ;tag=gK0c300052 Max-Forwards: 66 Record-Route: To: Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKde3d.b6abe1765ca3337723754c0aceab93ec.0 Via: SIP/2.0/UDP 216.115.69.131;branch=z9hG4bKde3d.3b36e9596ae94e3d90f7a8cdd95fc2b3.1 Via: SIP/2.0/UDP 216.115.69.132;branch=z9hG4bKde3d.a66297e5adbd5bdfc0293963ead47325.0 Via: SIP/2.0/UDP 74.120.93.200:5060;branch=z9hG4bK0cB2b5717fe78101b1f Call-ID: 522992438_133801219@74.120.93.200 CSeq: 85750 INVITE Contact: "3342120202" Session-Expires: 1800 Min-SE: 90 Content-Length: 222 Content-Type: application/sdp P-Asserted-Identity: "LINDEN AL" v=0 o=- 54431 302410 IN IP4 74.120.93.202 s=- c=IN IP4 74.120.93.202 t=0 0 m=audio 45992 RTP/AVP 0 8 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 ------------------------------------------------------------------------ 2017-05-11 15:15:23.989005 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+13342120202@fl.gg [6314b885-19e6-40fb-b20e-4e099422bbe4] 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+13342120202@fl.gg) Running State Change CS_NEW (Cur 2 Tot 126) 2017-05-11 15:15:23.989005 [DEBUG] sofia.c:10028 sofia/external/+13342120202@fl.gg receiving invite from 216.115.69.144:5060 version: 1.9.0 git 5d4ac1c 2017-05-05 16:19:17Z 64bit 2017-05-11 15:15:23.989005 [DEBUG] sofia.c:7247 Channel sofia/external/+13342120202@fl.gg entering state [received][100] 2017-05-11 15:15:23.989005 [DEBUG] sofia.c:7257 Remote SDP: v=0 o=- 54431 302410 IN IP4 74.120.93.202 s=- c=IN IP4 74.120.93.202 t=0 0 m=audio 45992 RTP/AVP 0 8 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2017-05-11 15:15:23.989005 [DEBUG] sofia.c:7649 (sofia/external/+13342120202@fl.gg) State Change CS_NEW -> CS_INIT 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+13342120202@fl.gg) State NEW 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+13342120202@fl.gg) Running State Change CS_INIT (Cur 2 Tot 126) 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+13342120202@fl.gg) State INIT 2017-05-11 15:15:23.989005 [DEBUG] mod_sofia.c:93 sofia/external/+13342120202@fl.gg SOFIA INIT 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:40 sofia/external/+13342120202@fl.gg Standard INIT 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+13342120202@fl.gg) State Change CS_INIT -> CS_ROUTING 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+13342120202@fl.gg) State INIT going to sleep 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+13342120202@fl.gg) Running State Change CS_ROUTING (Cur 2 Tot 126) 2017-05-11 15:15:23.989005 [DEBUG] switch_channel.c:2249 (sofia/external/+13342120202@fl.gg) Callstate Change DOWN -> RINGING 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+13342120202@fl.gg) State ROUTING 2017-05-11 15:15:23.989005 [DEBUG] mod_sofia.c:154 sofia/external/+13342120202@fl.gg SOFIA ROUTING 2017-05-11 15:15:23.989005 [DEBUG] switch_core_state_machine.c:236 sofia/external/+13342120202@fl.gg Standard ROUTING 2017-05-11 15:15:23.989005 [INFO] mod_dialplan_xml.c:637 Processing LINDEN AL <+13342120202>->13342120101 in context public send 365 bytes to udp/[216.115.69.144]:5060 at 15:15:22.675349: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKde3d.b6abe1765ca3337723754c0aceab93ec.0 From: "LINDEN AL" ;tag=gK0c300052 To: Call-ID: 522992438_133801219@74.120.93.200 CSeq: 85750 INVITE User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Content-Length: 0 ------------------------------------------------------------------------ Dialplan: sofia/external/+13342120202@fl.gg parsing [public->unloop] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [public->outside_call] continue=true Dialplan: sofia/external/+13342120202@fl.gg Absolute Condition [outside_call] Dialplan: sofia/external/+13342120202@fl.gg Action set(outside_call=true) Dialplan: sofia/external/+13342120202@fl.gg Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/external/+13342120202@fl.gg parsing [public->call_debug] continue=true Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/external/+13342120202@fl.gg parsing [public->public_extensions] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [public_extensions] destination_number(13342120101) =~ /^(10[01][0-9]|35\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [public->did 0101] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [did 0101] destination_number(13342120101) =~ /^(\+?1?3342120101)$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Action set(domain_name=192.168.1.9) Dialplan: sofia/external/+13342120202@fl.gg Action transfer(9178 XML default) 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+13342120202@fl.gg) State Change CS_ROUTING -> CS_EXECUTE 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+13342120202@fl.gg) State ROUTING going to sleep 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+13342120202@fl.gg) Running State Change CS_EXECUTE (Cur 2 Tot 126) 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+13342120202@fl.gg) State EXECUTE 2017-05-11 15:15:24.008697 [DEBUG] mod_sofia.c:209 sofia/external/+13342120202@fl.gg SOFIA EXECUTE 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:328 sofia/external/+13342120202@fl.gg Standard EXECUTE EXECUTE sofia/external/+13342120202@fl.gg set(outside_call=true) 2017-05-11 15:15:24.008697 [DEBUG] mod_dptools.c:1570 SET sofia/external/+13342120202@fl.gg [outside_call]=[true] EXECUTE sofia/external/+13342120202@fl.gg export(RFC2822_DATE=Thu, 11 May 2017 15:15:24 -0400) 2017-05-11 15:15:24.008697 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 11 May 2017 15:15:24 -0400] EXECUTE sofia/external/+13342120202@fl.gg set(domain_name=192.168.1.9) 2017-05-11 15:15:24.008697 [DEBUG] mod_dptools.c:1570 SET sofia/external/+13342120202@fl.gg [domain_name]=[192.168.1.9] EXECUTE sofia/external/+13342120202@fl.gg transfer(9178 XML default) 2017-05-11 15:15:24.008697 [DEBUG] switch_ivr.c:2194 (sofia/external/+13342120202@fl.gg) State Change CS_EXECUTE -> CS_ROUTING 2017-05-11 15:15:24.008697 [NOTICE] switch_ivr.c:2201 Transfer sofia/external/+13342120202@fl.gg to XML[9178@default] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+13342120202@fl.gg) State EXECUTE going to sleep 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+13342120202@fl.gg) Running State Change CS_ROUTING (Cur 2 Tot 126) 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+13342120202@fl.gg) State ROUTING 2017-05-11 15:15:24.008697 [DEBUG] mod_sofia.c:145 Call appears to be already acknowledged 2017-05-11 15:15:24.008697 [DEBUG] mod_sofia.c:154 sofia/external/+13342120202@fl.gg SOFIA ROUTING 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:236 sofia/external/+13342120202@fl.gg Standard ROUTING 2017-05-11 15:15:24.008697 [INFO] mod_dialplan_xml.c:637 Processing LINDEN AL <+13342120202>->9178 in context default Dialplan: sofia/external/+13342120202@fl.gg parsing [default->dial-10-digit-number] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [dial-10-digit-number] destination_number(9178) =~ /^(1?\d{10})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->unloop] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->tod_example] continue=true Dialplan: sofia/external/+13342120202@fl.gg Date/Time Match (PASS) [tod_example] break=on-false Dialplan: sofia/external/+13342120202@fl.gg Action set(open=true) Dialplan: sofia/external/+13342120202@fl.gg parsing [default->holiday_example] continue=true Dialplan: sofia/external/+13342120202@fl.gg Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->global-intercept] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [global-intercept] destination_number(9178) =~ /^886$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->group-intercept] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [group-intercept] destination_number(9178) =~ /^\*8$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->intercept-ext] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [intercept-ext] destination_number(9178) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->redial] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [redial] destination_number(9178) =~ /^(redial|870)$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->global] continue=true Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [global] ${default_password}(j0sh3b0sh) =~ /^1234$/ break=never Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [global] ${switch_r_sdp}(v=0 o=- 54431 302410 IN IP4 74.120.93.202 s=- c=IN IP4 74.120.93.202 t=0 0 m=audio 45992 RTP/AVP 0 8 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never Dialplan: sofia/external/+13342120202@fl.gg Absolute Condition [global] Dialplan: sofia/external/+13342120202@fl.gg Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/external/+13342120202@fl.gg Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/external/+13342120202@fl.gg Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/external/+13342120202@fl.gg Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/external/+13342120202@fl.gg parsing [default->snom-demo-2] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [snom-demo-2] destination_number(9178) =~ /^9001$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->snom-demo-1] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [snom-demo-1] destination_number(9178) =~ /^9000$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->eavesdrop] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [eavesdrop] destination_number(9178) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->eavesdrop] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [eavesdrop] destination_number(9178) =~ /^779$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->call_return] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [call_return] destination_number(9178) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->del-group] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [del-group] destination_number(9178) =~ /^80(\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->add-group] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [add-group] destination_number(9178) =~ /^81(\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->call-group-simo] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [call-group-simo] destination_number(9178) =~ /^82(\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->call-group-order] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [call-group-order] destination_number(9178) =~ /^83(\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->extension-intercom] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [extension-intercom] destination_number(9178) =~ /^8(10[01][0-9])$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->Local_Extension] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [Local_Extension] destination_number(9178) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->Local_Extension_Skinny] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [Local_Extension_Skinny] destination_number(9178) =~ /^(11[01][0-9])$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->group_dial_sales] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [group_dial_sales] destination_number(9178) =~ /^2000$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->group_dial_support] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [group_dial_support] destination_number(9178) =~ /^2001$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->group_dial_billing] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [group_dial_billing] destination_number(9178) =~ /^2002$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->operator] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [operator] destination_number(9178) =~ /^(operator|0)$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->vmain] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [vmain] destination_number(9178) =~ /^vmain$|^4000$|^\*98$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->sip_uri] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [sip_uri] destination_number(9178) =~ /^sip:(.*)$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->nb_conferences] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [nb_conferences] destination_number(9178) =~ /^(30\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->wb_conferences] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [wb_conferences] destination_number(9178) =~ /^(31\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->uwb_conferences] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [uwb_conferences] destination_number(9178) =~ /^(32\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->cdquality_conferences] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [cdquality_conferences] destination_number(9178) =~ /^(33\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->cdquality_stereo_conferences] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [cdquality_stereo_conferences] destination_number(9178) =~ /^(35\d{2}).*?-screen$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->conference-canvases] continue=true Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [conference-canvases] destination_number(9178) =~ /(35\d{2})-canvas-(\d+)/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->conf mod] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [conf mod] destination_number(9178) =~ /^6070-moderator$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->cdquality_conferences] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [cdquality_conferences] destination_number(9178) =~ /^(35\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->freeswitch_public_conf_via_sip] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(9178) =~ /^9(888|8888|1616|3232)$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->mad_boss_intercom] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [mad_boss_intercom] destination_number(9178) =~ /^0911$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->mad_boss_intercom] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [mad_boss_intercom] destination_number(9178) =~ /^0912$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->mad_boss] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [mad_boss] destination_number(9178) =~ /^0913$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->ivr_demo] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [ivr_demo] destination_number(9178) =~ /^5000$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->dynamic_conference] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [dynamic_conference] destination_number(9178) =~ /^5001$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->rtp_multicast_page] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [rtp_multicast_page] destination_number(9178) =~ /^pagegroup$|^7243$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->park] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [park] destination_number(9178) =~ /^5900$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->unpark] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [unpark] destination_number(9178) =~ /^5901$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->valet_park] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [valet_park] destination_number(9178) =~ /^(6000)$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->valet_park] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [valet_park] destination_number(9178) =~ /^((?!6000)60\d{2})$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->park] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [park] destination_number(9178) =~ /park\+(\d+)/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->unpark] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [unpark] destination_number(9178) =~ /^parking$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->park] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [park] destination_number(9178) =~ /callpark/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->unpark] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [unpark] destination_number(9178) =~ /pickup/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->wait] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (FAIL) [wait] destination_number(9178) =~ /^wait$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg parsing [default->fax_receive] continue=false Dialplan: sofia/external/+13342120202@fl.gg Regex (PASS) [fax_receive] destination_number(9178) =~ /^9178$/ break=on-false Dialplan: sofia/external/+13342120202@fl.gg Action answer() Dialplan: sofia/external/+13342120202@fl.gg Action playback(silence_stream://2000) Dialplan: sofia/external/+13342120202@fl.gg Action rxfax(/tmp/rxfax.tif) Dialplan: sofia/external/+13342120202@fl.gg Action hangup() 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+13342120202@fl.gg) State Change CS_ROUTING -> CS_EXECUTE 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+13342120202@fl.gg) State ROUTING going to sleep 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+13342120202@fl.gg) Running State Change CS_EXECUTE (Cur 2 Tot 126) 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+13342120202@fl.gg) State EXECUTE 2017-05-11 15:15:24.008697 [DEBUG] mod_sofia.c:209 sofia/external/+13342120202@fl.gg SOFIA EXECUTE 2017-05-11 15:15:24.008697 [DEBUG] switch_core_state_machine.c:328 sofia/external/+13342120202@fl.gg Standard EXECUTE EXECUTE sofia/external/+13342120202@fl.gg set(open=true) 2017-05-11 15:15:24.008697 [DEBUG] mod_dptools.c:1570 SET sofia/external/+13342120202@fl.gg [open]=[true] EXECUTE sofia/external/+13342120202@fl.gg hash(insert/192.168.1.9-spymap/+13342120202/6314b885-19e6-40fb-b20e-4e099422bbe4) EXECUTE sofia/external/+13342120202@fl.gg hash(insert/192.168.1.9-last_dial/+13342120202/9178) EXECUTE sofia/external/+13342120202@fl.gg hash(insert/192.168.1.9-last_dial/global/6314b885-19e6-40fb-b20e-4e099422bbe4) EXECUTE sofia/external/+13342120202@fl.gg export(RFC2822_DATE=Thu, 11 May 2017 15:15:24 -0400) 2017-05-11 15:15:24.008697 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 11 May 2017 15:15:24 -0400] EXECUTE sofia/external/+13342120202@fl.gg answer() 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5169 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5169 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5030 Set telephone-event payload to 101@8000 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:3425 Set Codec sofia/external/+13342120202@fl.gg PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2017-05-11 15:15:24.008697 [DEBUG] switch_core_codec.c:111 sofia/external/+13342120202@fl.gg Original read codec set to PCMU:0 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5373 Set telephone-event payload to 101@8000 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:5431 sofia/external/+13342120202@fl.gg Set 2833 dtmf send payload to 101 recv payload to 101 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:8148 AUDIO RTP [sofia/external/+13342120202@fl.gg] 192.168.1.9 port 30352 -> 74.120.93.202 port 45992 codec: 0 ms: 20 2017-05-11 15:15:24.008697 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:8451 sofia/external/+13342120202@fl.gg Set 2833 dtmf send payload to 101 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:8458 sofia/external/+13342120202@fl.gg Set 2833 dtmf receive payload to 101 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:8481 sofia/external/+13342120202@fl.gg Set rtp dtmf delay to 40 2017-05-11 15:15:24.008697 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/+13342120202@fl.gg! 2017-05-11 15:15:24.008697 [DEBUG] switch_channel.c:3481 (sofia/external/+13342120202@fl.gg) Callstate Change RINGING -> EARLY 2017-05-11 15:15:24.008697 [DEBUG] switch_core_media.c:8131 Audio params are unchanged for sofia/external/+13342120202@fl.gg. 2017-05-11 15:15:24.008697 [DEBUG] mod_sofia.c:881 Local SDP sofia/external/+13342120202@fl.gg: v=0 o=FreeSWITCH 1494499772 1494499773 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 30352 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2017-05-11 15:15:24.008697 [NOTICE] mod_dptools.c:1352 Channel [sofia/external/+13342120202@fl.gg] has been answered 2017-05-11 15:15:24.008697 [DEBUG] switch_channel.c:3780 (sofia/external/+13342120202@fl.gg) Callstate Change EARLY -> ACTIVE send 1334 bytes to udp/[216.115.69.144]:5060 at 15:15:22.683627: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKde3d.b6abe1765ca3337723754c0aceab93ec.0 Via: SIP/2.0/UDP 216.115.69.131;branch=z9hG4bKde3d.3b36e9596ae94e3d90f7a8cdd95fc2b3.1 Via: SIP/2.0/UDP 216.115.69.132;branch=z9hG4bKde3d.a66297e5adbd5bdfc0293963ead47325.0 Via: SIP/2.0/UDP 74.120.93.200:5060;branch=z9hG4bK0cB2b5717fe78101b1f Record-Route: Record-Route: From: "LINDEN AL" ;tag=gK0c300052 To: ;tag=11XgN86N3ZD7D Call-ID: 522992438_133801219@74.120.93.200 CSeq: 85750 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit 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 Session-Expires: 1800;refresher=uas Content-Type: application/sdp Content-Disposition: session Content-Length: 218 P-Asserted-Identity: "13342120101" EXECUTE sofia/external/+13342120202@fl.gg playback(silence_stream://2000) v=0 o=FreeSWITCH 1494499772 1494499773 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 30352 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2017-05-11 15:15:24.008697 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 2017-05-11 15:15:24.008697 [DEBUG] sofia.c:7247 Channel sofia/external/+13342120202@fl.gg entering state [completed][200] recv 597 bytes from udp/[216.115.69.144]:5060 at 15:15:22.988713: ------------------------------------------------------------------------ ACK sip:+13342120101@192.168.1.9:5080;transport=udp SIP/2.0 Record-Route: Max-Forwards: 67 Record-Route: From: "LINDEN AL" ;tag=gK0c300052 To: ;tag=11XgN86N3ZD7D Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKde3d.1f2733b236be9217010961c30b610d34.0 Via: SIP/2.0/UDP 216.115.69.132;branch=z9hG4bKde3d.f4a0957d6b43b5b1b5630f5a5aae81ca.0 Via: SIP/2.0/UDP 74.120.93.200:5060;branch=z9hG4bK0cB2b64939e78101b1f Call-ID: 522992438_133801219@74.120.93.200 CSeq: 85750 ACK Content-Length: 0 ------------------------------------------------------------------------ 2017-05-11 15:15:24.332589 [DEBUG] sofia.c:7247 Channel sofia/external/+13342120202@fl.gg entering state [ready][200] 2017-05-11 15:15:24.831641 [DEBUG] switch_rtp.c:7245 Correct audio ip/port confirmed. recv 676 bytes from udp/[216.115.69.144]:5060 at 15:15:23.788997: ------------------------------------------------------------------------ SIP/2.0 200 OK From: "" ;tag=ZFBZHj5e9D11p To: ;tag=9311370351365563085 Via: SIP/2.0/UDP 192.168.1.9:5080;received=68.157.150.128;rport=5080;branch=z9hG4bKgappyH30KtX9S Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928994 INVITE Content-Type: application/sdp Contact: Content-Length: 166 Record-Route: Record-Route: Supported: timer v=0 o=- 188 1 IN IP4 64.194.139.60 s=- c=IN IP4 64.194.139.60 t=0 0 m=audio 31434 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 ------------------------------------------------------------------------ 2017-05-11 15:15:25.108665 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [completing][200] 2017-05-11 15:15:25.108665 [DEBUG] sofia.c:7257 Remote SDP: v=0 o=- 188 1 IN IP4 64.194.139.60 s=- c=IN IP4 64.194.139.60 t=0 0 m=audio 31434 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 send 764 bytes to udp/[216.115.69.144]:5060 at 15:15:23.790283: ------------------------------------------------------------------------ ACK sip:64.194.139.60:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.1.9:5080;rport;branch=z9hG4bKHKFF0cm4g3KvN Route: Route: Max-Forwards: 70 From: "" ;tag=ZFBZHj5e9D11p To: ;tag=9311370351365563085 Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928994 ACK Contact: Proxy-Authorization: Digest username="28308479", realm="sip.flowroute.com", nonce="WRS5b1kUuEN/adTD6CuWvpqXwB8IjeFy", cnonce="AVpTnbEhEjWe0wgAJ3evvg", algorithm=MD5, uri="sip:13342120101@sip.flowroute.com", response="6684a94d055d89e1cca692ab87e86933", qop=auth, nc=00000001 Content-Length: 0 ------------------------------------------------------------------------ 2017-05-11 15:15:25.108665 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [ready][200] 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:5169 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:5030 Set telephone-event payload to 101@8000 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:3425 Set Codec sofia/external/13342120101 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2017-05-11 15:15:25.108665 [DEBUG] switch_core_codec.c:111 sofia/external/13342120101 Original read codec set to PCMU:0 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:5373 Set telephone-event payload to 101@8000 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:5431 sofia/external/13342120101 Set 2833 dtmf send payload to 101 recv payload to 101 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:8148 AUDIO RTP [sofia/external/13342120101] 192.168.1.9 port 28132 -> 64.194.139.60 port 31434 codec: 0 ms: 20 2017-05-11 15:15:25.108665 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:8451 sofia/external/13342120101 Set 2833 dtmf send payload to 101 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:8458 sofia/external/13342120101 Set 2833 dtmf receive payload to 101 2017-05-11 15:15:25.108665 [DEBUG] switch_core_media.c:8481 sofia/external/13342120101 Set rtp dtmf delay to 40 2017-05-11 15:15:25.108665 [NOTICE] sofia.c:8383 Channel [sofia/external/13342120101] has been answered 2017-05-11 15:15:25.108665 [DEBUG] switch_channel.c:3780 (sofia/external/13342120101) Callstate Change DOWN -> ACTIVE 2017-05-11 15:15:25.129136 [DEBUG] switch_ivr_originate.c:3695 Originate Resulted in Success: [sofia/external/13342120101] 2017-05-11 15:15:25.129136 [INFO] switch_channel.c:3130 sofia/external/13342120101 Flipping CID from "" <13342120202> to "Outbound Call" <13342120101> 2017-05-11 15:15:25.129136 [DEBUG] mod_commands.c:4865 (sofia/external/13342120101) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2017-05-11 15:15:25.129136 [DEBUG] switch_core_state_machine.c:584 (sofia/external/13342120101) Running State Change CS_EXECUTE (Cur 2 Tot 126) 2017-05-11 15:15:25.129136 [DEBUG] switch_core_state_machine.c:650 (sofia/external/13342120101) State EXECUTE 2017-05-11 15:15:25.129136 [DEBUG] mod_sofia.c:209 sofia/external/13342120101 SOFIA EXECUTE 2017-05-11 15:15:25.129136 [DEBUG] switch_core_state_machine.c:328 sofia/external/13342120101 Standard EXECUTE EXECUTE sofia/external/13342120101 txfax(/tmp/txfax-sample.tiff) 2017-05-11 15:15:25.129136 [DEBUG] mod_spandsp_fax.c:1459 Raw read codec activation Success L16 20000 2017-05-11 15:15:25.129136 [DEBUG] switch_core_codec.c:223 sofia/external/13342120101 Push codec L16:100 2017-05-11 15:15:25.129136 [DEBUG] mod_spandsp_fax.c:1477 Raw write codec activation Success L16 recv 676 bytes from udp/[216.115.69.144]:5060 at 15:15:24.107603: ------------------------------------------------------------------------ SIP/2.0 200 OK From: "" ;tag=ZFBZHj5e9D11p To: ;tag=9311370351365563085 Via: SIP/2.0/UDP 192.168.1.9:5080;received=68.157.150.128;rport=5080;branch=z9hG4bKgappyH30KtX9S Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928994 INVITE Content-Type: application/sdp Contact: Content-Length: 166 Record-Route: Record-Route: Supported: timer v=0 o=- 188 1 IN IP4 64.194.139.60 s=- c=IN IP4 64.194.139.60 t=0 0 m=audio 31434 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 ------------------------------------------------------------------------ send 764 bytes to udp/[216.115.69.144]:5060 at 15:15:24.107719: ------------------------------------------------------------------------ ACK sip:64.194.139.60:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.1.9:5080;rport;branch=z9hG4bKHKFF0cm4g3KvN Route: Route: Max-Forwards: 70 From: "" ;tag=ZFBZHj5e9D11p To: ;tag=9311370351365563085 Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 106928994 ACK Contact: Proxy-Authorization: Digest username="28308479", realm="sip.flowroute.com", nonce="WRS5b1kUuEN/adTD6CuWvpqXwB8IjeFy", cnonce="AVpTnbEhEjWe0wgAJ3evvg", algorithm=MD5, uri="sip:13342120101@sip.flowroute.com", response="6684a94d055d89e1cca692ab87e86933", qop=auth, nc=00000001 Content-Length: 0 ------------------------------------------------------------------------ 2017-05-11 15:15:25.572268 [DEBUG] switch_rtp.c:7245 Correct audio ip/port confirmed. 2017-05-11 15:15:26.009173 [DEBUG] switch_ivr_play_say.c:1942 done playing file silence_stream://2000 EXECUTE sofia/external/+13342120202@fl.gg rxfax(/tmp/rxfax.tif) 2017-05-11 15:15:26.009173 [DEBUG] mod_spandsp_fax.c:1459 Raw read codec activation Success L16 20000 2017-05-11 15:15:26.009173 [DEBUG] switch_core_codec.c:223 sofia/external/+13342120202@fl.gg Push codec L16:100 2017-05-11 15:15:26.009173 [DEBUG] mod_spandsp_fax.c:1477 Raw write codec activation Success L16 recv 953 bytes from udp/[216.115.69.144]:5060 at 15:15:28.921900: ------------------------------------------------------------------------ INVITE sip:gw+flowroute@192.168.1.9:5080;transport=udp;gw=flowroute SIP/2.0 Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK7d7c.f7fa80f1cd9cf2de99fe619c161a72b6.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK7d7c.be28f3b035f1db2386928d127fed92f6.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-g0ka Call-ID: 013d52c8-b121-1235-d39e-08002777afbe Content-Type: application/sdp CSeq: 1 INVITE Contact: Max-Forwards: 67 Content-Length: 213 Supported: timer v=0 o=- 188 2 IN IP4 64.194.139.60 s=- c=IN IP4 64.194.139.60 t=0 0 m=audio 31434 RTP/AVP 0 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 ------------------------------------------------------------------------ 2017-05-11 15:15:30.248832 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [received][100] 2017-05-11 15:15:30.248832 [DEBUG] sofia.c:7257 Remote SDP: v=0 o=- 188 2 IN IP4 64.194.139.60 s=- c=IN IP4 64.194.139.60 t=0 0 m=audio 31434 RTP/AVP 0 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 send 395 bytes to udp/[216.115.69.144]:5060 at 15:15:28.932181: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK7d7c.f7fa80f1cd9cf2de99fe619c161a72b6.0 From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Content-Length: 0 ------------------------------------------------------------------------ 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5169 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1] 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5030 Set telephone-event payload to 101@8000 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5373 Set telephone-event payload to 101@8000 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:5431 sofia/external/13342120101 Set 2833 dtmf send payload to 101 recv payload to 101 2017-05-11 15:15:30.248832 [DEBUG] switch_core_media.c:8131 Audio params are unchanged for sofia/external/13342120101. 2017-05-11 15:15:30.248832 [DEBUG] sofia.c:8162 Processing updated SDP send 1164 bytes to udp/[216.115.69.144]:5060 at 15:15:28.933432: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK7d7c.f7fa80f1cd9cf2de99fe619c161a72b6.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK7d7c.be28f3b035f1db2386928d127fed92f6.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-g0ka Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 1 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 240 v=0 o=FreeSWITCH 1494501984 1494501986 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 28132 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 0 RTP/AVP 19 ------------------------------------------------------------------------ 2017-05-11 15:15:30.268849 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [completed][200] send 1164 bytes to udp/[216.115.69.144]:5060 at 15:15:29.435870: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK7d7c.f7fa80f1cd9cf2de99fe619c161a72b6.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK7d7c.be28f3b035f1db2386928d127fed92f6.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-g0ka Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 1 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 240 v=0 o=FreeSWITCH 1494501984 1494501986 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 28132 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 0 RTP/AVP 19 ------------------------------------------------------------------------ recv 636 bytes from udp/[216.115.69.144]:5060 at 15:15:29.724580: ------------------------------------------------------------------------ ACK sip:gw+flowroute@192.168.1.9:5080;transport=udp;gw=flowroute SIP/2.0 Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK7d7c.a62f727a0796cc5dc430bb77d434bc2d.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK7d7c.46aa921efde510d4faceef72629450d4.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-g0oa.1 Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 1 ACK Max-Forwards: 67 Content-Length: 0 ------------------------------------------------------------------------ 2017-05-11 15:15:31.048955 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [ready][200] recv 952 bytes from udp/[216.115.69.144]:5060 at 15:15:29.961946: ------------------------------------------------------------------------ INVITE sip:gw+flowroute@192.168.1.9:5080;transport=udp;gw=flowroute SIP/2.0 Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK4d7c.47bddee33a2277ce16a1efeba0c3cffe.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK4d7c.2be7bbcc85afe9c142a3586dd5143ba2.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-1ka Call-ID: 013d52c8-b121-1235-d39e-08002777afbe Content-Type: application/sdp CSeq: 2 INVITE Contact: Max-Forwards: 67 Content-Length: 213 Supported: timer v=0 o=- 188 3 IN IP4 64.194.139.60 s=- c=IN IP4 64.194.139.60 t=0 0 m=audio 31434 RTP/AVP 0 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 ------------------------------------------------------------------------ send 395 bytes to udp/[216.115.69.144]:5060 at 15:15:29.972568: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK4d7c.47bddee33a2277ce16a1efeba0c3cffe.0 From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Content-Length: 0 ------------------------------------------------------------------------ 2017-05-11 15:15:31.290516 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [received][100] 2017-05-11 15:15:31.290516 [DEBUG] sofia.c:7257 Remote SDP: v=0 o=- 188 3 IN IP4 64.194.139.60 s=- c=IN IP4 64.194.139.60 t=0 0 m=audio 31434 RTP/AVP 0 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5169 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1] 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5114 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5030 Set telephone-event payload to 101@8000 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5373 Set telephone-event payload to 101@8000 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:5431 sofia/external/13342120101 Set 2833 dtmf send payload to 101 recv payload to 101 2017-05-11 15:15:31.290516 [DEBUG] switch_core_media.c:8131 Audio params are unchanged for sofia/external/13342120101. 2017-05-11 15:15:31.290516 [DEBUG] sofia.c:8162 Processing updated SDP send 1163 bytes to udp/[216.115.69.144]:5060 at 15:15:29.973883: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK4d7c.47bddee33a2277ce16a1efeba0c3cffe.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK4d7c.2be7bbcc85afe9c142a3586dd5143ba2.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-1ka Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 240 v=0 o=FreeSWITCH 1494501984 1494501986 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 28132 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 0 RTP/AVP 19 ------------------------------------------------------------------------ 2017-05-11 15:15:31.308966 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [completed][200] send 1163 bytes to udp/[216.115.69.144]:5060 at 15:15:30.475014: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK4d7c.47bddee33a2277ce16a1efeba0c3cffe.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK4d7c.2be7bbcc85afe9c142a3586dd5143ba2.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-1ka Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 240 v=0 o=FreeSWITCH 1494501984 1494501986 IN IP4 192.168.1.9 s=FreeSWITCH c=IN IP4 192.168.1.9 t=0 0 m=audio 28132 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 0 RTP/AVP 19 ------------------------------------------------------------------------ recv 635 bytes from udp/[216.115.69.144]:5060 at 15:15:30.739333: ------------------------------------------------------------------------ ACK sip:gw+flowroute@192.168.1.9:5080;transport=udp;gw=flowroute SIP/2.0 Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK4d7c.d189142d598e03dcb5cb81d0f97f128b.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK4d7c.d9bacb52a7a5c864826e47bd88f50149.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-1oa.1 Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 2 ACK Max-Forwards: 67 Content-Length: 0 ------------------------------------------------------------------------ 2017-05-11 15:15:32.070550 [DEBUG] sofia.c:7247 Channel sofia/external/13342120101 entering state [ready][200] recv 495 bytes from udp/[216.115.69.144]:5060 at 15:16:00.252434: ------------------------------------------------------------------------ OPTIONS sip:68.157.150.128:5080;transport=udp SIP/2.0 Max-Forwards: 10 Record-Route: Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK97e1.f645f0f31e7ce35a5fad5fe97e29beb3.0 Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 Route: From: sip:ping@invalid;tag=dfa02e29 To: sip:68.157.150.128:5080;transport=udp Call-ID: 031b3b74-b2524d32-dbb0b6@70.167.153.136 CSeq: 1 OPTIONS Content-Length: 0 ------------------------------------------------------------------------ send 722 bytes to udp/[216.115.69.144]:5060 at 15:16:00.252929: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK97e1.f645f0f31e7ce35a5fad5fe97e29beb3.0 Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 Record-Route: From: sip:ping@invalid;tag=dfa02e29 To: ;transport=udp;tag=2aQ9p3QS083SS Call-ID: 031b3b74-b2524d32-dbb0b6@70.167.153.136 CSeq: 1 OPTIONS Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit 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 ------------------------------------------------------------------------ 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:496 ============================================================================== 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:508 Fax processing not successful - result (3) Timed out waiting for the first message. 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:513 Remote station id: 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:514 Local station id: SpanDSP Fax Ident 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 0 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:517 Total fax pages: 0 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:518 Image resolution: 0x0 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate: 14400 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:521 ECM status off 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:522 remote country: 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:523 remote vendor: 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:524 remote model: 2017-05-11 15:16:05.332888 [DEBUG] mod_spandsp_fax.c:526 ============================================================================== 2017-05-11 15:16:05.348781 [DEBUG] switch_core_codec.c:248 sofia/external/+13342120202@fl.gg Restore previous codec PCMU:0. EXECUTE sofia/external/+13342120202@fl.gg hangup() 2017-05-11 15:16:05.348781 [NOTICE] mod_dptools.c:1326 Hangup sofia/external/+13342120202@fl.gg [CS_EXECUTE] [NORMAL_CLEARING] 2017-05-11 15:16:05.348781 [DEBUG] switch_core_session.c:2884 sofia/external/+13342120202@fl.gg skip receive message [PHONE_EVENT] (channel is hungup already) 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+13342120202@fl.gg) State EXECUTE going to sleep 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+13342120202@fl.gg) Running State Change CS_HANGUP (Cur 2 Tot 126) 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:850 (sofia/external/+13342120202@fl.gg) Callstate Change ACTIVE -> HANGUP 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+13342120202@fl.gg) State HANGUP 2017-05-11 15:16:05.348781 [DEBUG] mod_sofia.c:449 Channel sofia/external/+13342120202@fl.gg hanging up, cause: NORMAL_CLEARING 2017-05-11 15:16:05.348781 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/external/+13342120202@fl.gg 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:60 sofia/external/+13342120202@fl.gg Standard HANGUP, cause: NORMAL_CLEARING 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+13342120202@fl.gg) State HANGUP going to sleep 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:619 (sofia/external/+13342120202@fl.gg) State Change CS_HANGUP -> CS_REPORTING 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+13342120202@fl.gg) Running State Change CS_REPORTING (Cur 2 Tot 126) 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+13342120202@fl.gg) State REPORTING 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:174 sofia/external/+13342120202@fl.gg Standard REPORTING, cause: NORMAL_CLEARING 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+13342120202@fl.gg) State REPORTING going to sleep send 648 bytes to udp/[216.115.69.144]:5060 at 15:16:04.022757: ------------------------------------------------------------------------ BYE sip:+13342120202@74.120.93.200:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.9:5080;rport;branch=z9hG4bKjv871747DcaFH Route: Route: Max-Forwards: 70 From: ;tag=11XgN86N3ZD7D To: "LINDEN AL" ;tag=gK0c300052 Call-ID: 522992438_133801219@74.120.93.200 CSeq: 106929018 BYE User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit 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 ------------------------------------------------------------------------ 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:610 (sofia/external/+13342120202@fl.gg) State Change CS_REPORTING -> CS_DESTROY 2017-05-11 15:16:05.348781 [DEBUG] switch_core_session.c:1712 Session 126 (sofia/external/+13342120202@fl.gg) Locked, Waiting on external entities 2017-05-11 15:16:05.348781 [NOTICE] switch_core_session.c:1730 Session 126 (sofia/external/+13342120202@fl.gg) Ended 2017-05-11 15:16:05.348781 [NOTICE] switch_core_session.c:1734 Close Channel sofia/external/+13342120202@fl.gg [CS_DESTROY] 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:741 (sofia/external/+13342120202@fl.gg) Running State Change CS_DESTROY (Cur 1 Tot 126) 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+13342120202@fl.gg) State DESTROY 2017-05-11 15:16:05.348781 [DEBUG] mod_sofia.c:354 sofia/external/+13342120202@fl.gg SOFIA DESTROY 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:181 sofia/external/+13342120202@fl.gg Standard DESTROY 2017-05-11 15:16:05.348781 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+13342120202@fl.gg) State DESTROY going to sleep recv 403 bytes from udp/[216.115.69.144]:5060 at 15:16:04.202932: ------------------------------------------------------------------------ SIP/2.0 200 OK From: ;tag=11XgN86N3ZD7D To: "LINDEN AL" ;tag=gK0c300052 Via: SIP/2.0/UDP 192.168.1.9:5080;received=68.157.150.128;rport=5080;branch=z9hG4bKjv871747DcaFH Call-ID: 522992438_133801219@74.120.93.200 CSeq: 106929018 BYE Record-Route: Record-Route: Content-Length: 0 ------------------------------------------------------------------------ recv 652 bytes from udp/[216.115.69.144]:5060 at 15:16:04.853765: ------------------------------------------------------------------------ BYE sip:gw+flowroute@192.168.1.9:5080;transport=udp;gw=flowroute SIP/2.0 Record-Route: Record-Route: From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK5d7c.89d5cf83b641f01f91b4c109dd31d9e1.0 Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK5d7c.a5ef42e1eedcbde16bc8bd65c6b85cbf.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-g1sa Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 3 BYE Max-Forwards: 67 Content-Length: 0 Supported: timer ------------------------------------------------------------------------ 2017-05-11 15:16:06.169434 [NOTICE] sofia.c:1079 Hangup sofia/external/13342120101 [CS_EXECUTE] [NORMAL_CLEARING] 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:496 ============================================================================== 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:508 Fax processing not successful - result (49) The call dropped prematurely. 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:513 Remote station id: 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:514 Local station id: SpanDSP Fax Ident 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 0 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:517 Total fax pages: 0 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:518 Image resolution: 0x0 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate: 14400 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:521 ECM status off 2017-05-11 15:16:06.169434 [DEBUG] mod_spandsp_fax.c:522 remote country: 2017-05-11 15:16:06.189077 [DEBUG] mod_spandsp_fax.c:523 remote vendor: 2017-05-11 15:16:06.189077 [DEBUG] mod_spandsp_fax.c:524 remote model: send 682 bytes to udp/[216.115.69.144]:5060 at 15:16:04.856262: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK5d7c.89d5cf83b641f01f91b4c109dd31d9e1.0 2017-05-11 15:16:06.189077 [DEBUG] mod_spandsp_fax.c:526 ============================================================================== Via: SIP/2.0/UDP 216.115.69.133;branch=z9hG4bK5d7c.a5ef42e1eedcbde16bc8bd65c6b85cbf.0 Via: SIP/2.0/UDP 64.194.139.60:5060;branch=z9hG4bKvuf6ja4vqae7era50vf8vir143-g1sa From: ;tag=9311370351365563085 To: "" ;tag=ZFBZHj5e9D11p Call-ID: 013d52c8-b121-1235-d39e-08002777afbe CSeq: 3 BYE User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2017-05-11 15:16:06.189077 [DEBUG] switch_core_codec.c:248 sofia/external/13342120101 Restore previous codec PCMU:0. 2017-05-11 15:16:06.189077 [DEBUG] switch_core_session.c:2884 sofia/external/13342120101 skip receive message [PHONE_EVENT] (channel is hungup already) 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:650 (sofia/external/13342120101) State EXECUTE going to sleep 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:584 (sofia/external/13342120101) Running State Change CS_HANGUP (Cur 1 Tot 126) 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:850 (sofia/external/13342120101) Callstate Change ACTIVE -> HANGUP 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:852 (sofia/external/13342120101) State HANGUP 2017-05-11 15:16:06.189077 [DEBUG] mod_sofia.c:449 Channel sofia/external/13342120101 hanging up, cause: NORMAL_CLEARING 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:60 sofia/external/13342120101 Standard HANGUP, cause: NORMAL_CLEARING 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:852 (sofia/external/13342120101) State HANGUP going to sleep 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:619 (sofia/external/13342120101) State Change CS_HANGUP -> CS_REPORTING 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:584 (sofia/external/13342120101) Running State Change CS_REPORTING (Cur 1 Tot 126) 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:938 (sofia/external/13342120101) State REPORTING 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:174 sofia/external/13342120101 Standard REPORTING, cause: NORMAL_CLEARING 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:938 (sofia/external/13342120101) State REPORTING going to sleep 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:610 (sofia/external/13342120101) State Change CS_REPORTING -> CS_DESTROY 2017-05-11 15:16:06.189077 [DEBUG] switch_core_session.c:1712 Session 125 (sofia/external/13342120101) Locked, Waiting on external entities 2017-05-11 15:16:06.189077 [NOTICE] switch_core_session.c:1730 Session 125 (sofia/external/13342120101) Ended 2017-05-11 15:16:06.189077 [NOTICE] switch_core_session.c:1734 Close Channel sofia/external/13342120101 [CS_DESTROY] 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:741 (sofia/external/13342120101) Running State Change CS_DESTROY (Cur 0 Tot 126) 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:751 (sofia/external/13342120101) State DESTROY 2017-05-11 15:16:06.189077 [DEBUG] mod_sofia.c:354 sofia/external/13342120101 SOFIA DESTROY 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:181 sofia/external/13342120101 Standard DESTROY 2017-05-11 15:16:06.189077 [DEBUG] switch_core_state_machine.c:751 (sofia/external/13342120101) State DESTROY going to sleep freeswitch@freeswitch-test> show calls 0 total. recv 757 bytes from udp/[192.168.1.26]:5060 at 15:16:47.032094: ------------------------------------------------------------------------ REGISTER sip:192.168.1.9 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.26:5060;branch=z9hG4bK72c07dbdf3b3fa9f From: ;tag=773c4c169c139c19 To: Contact: Supported: path Authorization: Digest username="1009", realm="192.168.1.9", algorithm=MD5, uri="sip:192.168.1.9", qop=auth, nc=00000003, cnonce="6bb850224bf56cc1", nonce="17e1a876-403f-4250-ba07-9d0ac6aa0fe5", response="317a6fb5b32cea35dd8d825015f60c74" Call-ID: 7b8e2647b544354a@192.168.1.26 CSeq: 10062 REGISTER Expires: 3600 User-Agent: Grandstream BT200 1.1.6.32 Max-Forwards: 70 Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE,PRACK Content-Length: 0 ------------------------------------------------------------------------ send 621 bytes to udp/[192.168.1.26]:5060 at 15:16:47.034312: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.26:5060;branch=z9hG4bK72c07dbdf3b3fa9f From: ;tag=773c4c169c139c19 To: ;tag=yN0XB1ge8Qc4N Call-ID: 7b8e2647b544354a@192.168.1.26 CSeq: 10062 REGISTER Contact: ;expires=3600 Date: Thu, 11 May 2017 19:16:48 GMT User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ recv 495 bytes from udp/[216.115.69.144]:5060 at 15:16:55.391028: ------------------------------------------------------------------------ OPTIONS sip:68.157.150.128:5080;transport=udp SIP/2.0 Max-Forwards: 10 Record-Route: Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKf09a.9ef1429cd5c4d40209eb14b57630178b.0 Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 Route: From: sip:ping@invalid;tag=ee732e29 To: sip:68.157.150.128:5080;transport=udp Call-ID: 031b3b74-c1254d32-5fb0b6@70.167.153.136 CSeq: 1 OPTIONS Content-Length: 0 ------------------------------------------------------------------------ send 722 bytes to udp/[216.115.69.144]:5060 at 15:16:55.391601: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bKf09a.9ef1429cd5c4d40209eb14b57630178b.0 Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 Record-Route: From: sip:ping@invalid;tag=ee732e29 To: ;transport=udp;tag=3Kg2ry8vXHtcN Call-ID: 031b3b74-c1254d32-5fb0b6@70.167.153.136 CSeq: 1 OPTIONS Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit 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 411 bytes from udp/[62.141.42.201]:5062 at 15:16:57.723319: ------------------------------------------------------------------------ OPTIONS sip:100@68.157.150.128 SIP/2.0 Via: SIP/2.0/UDP 62.141.42.201:5062;branch=z9hG4bK-2381670077;rport Content-Length: 0 From: "sipvicious"; tag=343439643936383031336334013336393435393136 Accept: application/sdp User-Agent: friendly-scanner To: "sipvicious" Contact: sip:100@62.141.42.201:5062 CSeq: 1 OPTIONS Call-ID: 39583324158554579239185 Max-Forwards: 70 ------------------------------------------------------------------------ send 646 bytes to udp/[62.141.42.201]:5062 at 15:16:57.723786: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 62.141.42.201:5062;branch=z9hG4bK-2381670077;rport=5062 From: "sipvicious"; tag=343439643936383031336334013336393435393136 To: "sipvicious" ;tag=4v9ttSS0ttgZg Call-ID: 39583324158554579239185 CSeq: 1 OPTIONS Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit 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 496 bytes from udp/[216.115.69.144]:5060 at 15:17:12.220460: ------------------------------------------------------------------------ OPTIONS sip:68.157.150.128:5080;transport=udp SIP/2.0 Max-Forwards: 10 Record-Route: Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK03e3.617bca3a5f7fbd8eda9f23ac9181ec05.0 Via: SIP/2.0/UDP 216.115.69.131:5060;branch=0 Route: From: sip:ping@invalid;tag=2c6024e5 To: sip:68.157.150.128:5080;transport=udp Call-ID: 84599eb4-1dc28c47-cd1d9c2@216.115.69.131 CSeq: 1 OPTIONS Content-Length: 0 ------------------------------------------------------------------------ send 723 bytes to udp/[216.115.69.144]:5060 at 15:17:12.221207: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 216.115.69.144;branch=z9hG4bK03e3.617bca3a5f7fbd8eda9f23ac9181ec05.0 Via: SIP/2.0/UDP 216.115.69.131:5060;branch=0 Record-Route: From: sip:ping@invalid;tag=2c6024e5 To: ;transport=udp;tag=552Kvma4Q36Hc Call-ID: 84599eb4-1dc28c47-cd1d9c2@216.115.69.131 CSeq: 1 OPTIONS Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170505T161917Z~5d4ac1cd3b~64bit 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 ------------------------------------------------------------------------ freeswitch@freeswitch-test>