[m[33m[44m.=======================================================. | _____ ____ ____ _ ___ | | | ___/ ___| / ___| | |_ _| | | | |_ \___ \ | | | | | | | | | _| ___) | | |___| |___ | | | | |_| |____/ \____|_____|___| | | | .=======================================================. | Anthony Minessale II, Ken Rice, | | Michael Jerris, Travis Cross | | FreeSWITCH (http://www.freeswitch.org) | | Paypal Donations Appreciated: paypal@freeswitch.org | | Brought to you by ClueCon http://www.cluecon.com/ | .=======================================================. .===============================================================. | _ | | ___| |_ _ ___ ___ ___ _ __ ___ ___ _ __ ___ | | / __| | | | |/ _ \/ __/ _ \| '_ \ / __/ _ \| '_ ` _ \ | | | (__| | |_| | __/ (_| (_) | | | | _ | (_| (_) | | | | | | | | \___|_|\__,_|\___|\___\___/|_| |_| (_) \___\___/|_| |_| |_| | | | .===============================================================. [m Type /help to see a list of commands [m [This app Best viewed at 160x60 or more..] +OK log level 7 [7] freeswitch@internal> Enabled sip debugging on internal freeswitch@internal> recv 1387 bytes from udp/[118.70.126.89]:44414 at 02:35:50.080629: ------------------------------------------------------------------------ INVITE sip:12345@52.193.89.3 SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.LHIuw7F5A;rport From: ;tag=aVBTEb6Xj To: sip:12345@52.193.89.3 CSeq: 20 INVITE Call-ID: p8QPFQE58e Max-Forwards: 70 Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 802 Contact: ;+sip.instance="" User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) v=0 o=100005 1658 3942 IN IP4 10.22.30.124 s=Talk c=IN IP4 10.22.30.124 b=AS:660 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 SILK/16000 a=rtpmap:98 speex/16000 a=fmtp:98 vbr=on a=rtpmap:99 speex/8000 a=fmtp:99 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:100 telephone-event/16000 a=rtpmap:102 telephone-event/8000 a=rtcp-fb:* ccm tmmbr m=video 9078 RTP/AVP 96 97 a=rtpmap:96 VP8/90000 a=rtpmap:97 H264/90000 a=fmtp:97 profile-level-id=42801F a=rtcp-fb:* ccm tmmbr a=rtcp-fb:96 nack pli a=rtcp-fb:96 nack sli a=rtcp-fb:96 ack rpsi a=rtcp-fb:96 ccm fir a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir ------------------------------------------------------------------------ send 296 bytes to udp/[118.70.126.89]:44414 at 02:35:50.080973: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.LHIuw7F5A;rport=44414;received=118.70.126.89 From: ;tag=aVBTEb6Xj To: sip:12345@52.193.89.3 Call-ID: p8QPFQE58e CSeq: 20 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit Content-Length: 0 ------------------------------------------------------------------------ 2017-08-07 02:35:50.073459 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/100005@52.193.89.3 [201628ce-7b19-11e7-a73a-c76caa719387] 2017-08-07 02:35:50.073459 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_NEW (Cur 1 Tot 1) 2017-08-07 02:35:50.073459 [DEBUG] sofia.c:9873 sofia/internal/100005@52.193.89.3 receiving invite from 118.70.126.89:44414 version: 1.6.19 64bit 2017-08-07 02:35:50.073459 [DEBUG] sofia.c:10044 IP 118.70.126.89 Rejected by acl "domains". Falling back to Digest auth. 2017-08-07 02:35:50.073459 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/100005@52.193.89.3) State NEW send 800 bytes to udp/[118.70.126.89]:44414 at 02:35:50.081965: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.LHIuw7F5A;rport=44414;received=118.70.126.89 From: ;tag=aVBTEb6Xj To: ;tag=Z76mFj4jeBgDQ Call-ID: p8QPFQE58e CSeq: 20 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19~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="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ 2017-08-07 02:35:50.073459 [DEBUG] sofia.c:2334 detaching session 201628ce-7b19-11e7-a73a-c76caa719387 recv 372 bytes from udp/[118.70.126.89]:44414 at 02:35:50.357036: ------------------------------------------------------------------------ ACK sip:12345@52.193.89.3 SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.LHIuw7F5A;rport Call-ID: p8QPFQE58e From: ;tag=aVBTEb6Xj To: ;tag=Z76mFj4jeBgDQ Contact: ;+sip.instance="" Max-Forwards: 70 CSeq: 20 ACK ------------------------------------------------------------------------ recv 1642 bytes from udp/[118.70.126.89]:44414 at 02:35:50.360466: ------------------------------------------------------------------------ INVITE sip:12345@52.193.89.3 SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.juTJlWzmD;rport From: ;tag=aVBTEb6Xj To: sip:12345@52.193.89.3 CSeq: 21 INVITE Call-ID: p8QPFQE58e Max-Forwards: 70 Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 802 Contact: ;+sip.instance="" User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) Proxy-Authorization: Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, username="100005", uri="sip:12345@52.193.89.3", response="395a4c96831c94b4811be24eba26ddb7", cnonce="XXnpmhu1sw96ruLT", nc=00000001, qop=auth v=0 o=100005 1658 3942 IN IP4 10.22.30.124 s=Talk c=IN IP4 10.22.30.124 b=AS:660 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 SILK/16000 a=rtpmap:98 speex/16000 a=fmtp:98 vbr=on a=rtpmap:99 speex/8000 a=fmtp:99 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:100 telephone-event/16000 a=rtpmap:102 telephone-event/8000 a=rtcp-fb:* ccm tmmbr m=video 9078 RTP/AVP 96 97 a=rtpmap:96 VP8/90000 a=rtpmap:97 H264/90000 a=fmtp:97 profile-level-id=42801F a=rtcp-fb:* ccm tmmbr a=rtcp-fb:96 nack pli a=rtcp-fb:96 nack sli a=rtcp-fb:96 ack rpsi a=rtcp-fb:96 ccm fir a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir ------------------------------------------------------------------------ send 296 bytes to udp/[118.70.126.89]:44414 at 02:35:50.360718: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.juTJlWzmD;rport=44414;received=118.70.126.89 From: ;tag=aVBTEb6Xj To: sip:12345@52.193.89.3 Call-ID: p8QPFQE58e CSeq: 21 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit Content-Length: 0 ------------------------------------------------------------------------ 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:2442 Re-attaching to session 201628ce-7b19-11e7-a73a-c76caa719387 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:9873 sofia/internal/100005@52.193.89.3 receiving invite from 118.70.126.89:44414 version: 1.6.19 64bit 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:10044 IP 118.70.126.89 Rejected by acl "domains". Falling back to Digest auth. 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:11168 Setting NAT mode based on nat.auto 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [received][100] 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=100005 1658 3942 IN IP4 10.22.30.124 s=Talk c=IN IP4 10.22.30.124 b=AS:660 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 SILK/16000 a=rtpmap:98 speex/16000 a=fmtp:98 vbr=on a=rtpmap:99 speex/8000 a=fmtp:99 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:100 telephone-event/16000 a=rtpmap:102 telephone-event/8000 a=rtcp-fb:* ccm tmmbr m=video 9078 RTP/AVP 96 97 a=rtpmap:96 VP8/90000 a=rtpmap:97 H264/90000 a=fmtp:97 profile-level-id=42801F a=rtcp-fb:* ccm tmmbr a=rtcp-fb:96 nack pli a=rtcp-fb:96 nack sli a=rtcp-fb:96 ack rpsi a=rtcp-fb:96 ccm fir a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:7486 (sofia/internal/100005@52.193.89.3) State Change CS_NEW -> CS_INIT 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_INIT (Cur 1 Tot 1) 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100005@52.193.89.3) State INIT 2017-08-07 02:35:50.353458 [DEBUG] mod_sofia.c:90 sofia/internal/100005@52.193.89.3 SOFIA INIT 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:40 sofia/internal/100005@52.193.89.3 Standard INIT 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/100005@52.193.89.3) State Change CS_INIT -> CS_ROUTING 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100005@52.193.89.3) State INIT going to sleep 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_ROUTING (Cur 1 Tot 1) 2017-08-07 02:35:50.353458 [DEBUG] switch_channel.c:2249 (sofia/internal/100005@52.193.89.3) Callstate Change DOWN -> RINGING 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100005@52.193.89.3) State ROUTING 2017-08-07 02:35:50.353458 [DEBUG] mod_sofia.c:143 sofia/internal/100005@52.193.89.3 SOFIA ROUTING 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:236 sofia/internal/100005@52.193.89.3 Standard ROUTING 2017-08-07 02:35:50.353458 [INFO] mod_dialplan_xml.c:637 Processing 100005 <100005>->12345 in context default Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->unloop] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->tod_example] continue=true Dialplan: sofia/internal/100005@52.193.89.3 Date/TimeMatch (FAIL) [tod_example] break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->holiday_example] continue=true Dialplan: sofia/internal/100005@52.193.89.3 Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->global-intercept] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global-intercept] destination_number(12345) =~ /^886$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group-intercept] destination_number(12345) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [intercept-ext] destination_number(12345) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->redial] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [redial] destination_number(12345) =~ /^(redial|870)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->global] continue=true Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${default_password}(123456) =~ /^1234$/ break=never Dialplan: sofia/internal/100005@52.193.89.3 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/internal/100005@52.193.89.3 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${switch_r_sdp}(v=0 o=100005 1658 3942 IN IP4 10.22.30.124 s=Talk c=IN IP4 10.22.30.124 b=AS:660 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 SILK/16000 a=rtpmap:98 speex/16000 a=fmtp:98 vbr=on a=rtpmap:99 speex/8000 a=fmtp:99 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:100 telephone-event/16000 a=rtpmap:102 telephone-event/8000 a=rtcp-fb:* ccm tmmbr m=video 9078 RTP/AVP 96 97 a=rtpmap:96 VP8/90000 a=rtpmap:97 H264/90000 a=fmtp:97 profile-level-id=42801F a=rtcp-fb:* ccm tmmbr a=rtcp-fb:96 nack pli a=rtcp-fb:96 nack sli a=rtcp-fb:96 ack rpsi a=rtcp-fb:96 ccm fir a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never Dialplan: sofia/internal/100005@52.193.89.3 Absolute Condition [global] Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/internal/100005@52.193.89.3 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->snom-demo-2] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [snom-demo-2] destination_number(12345) =~ /^9001$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->snom-demo-1] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [snom-demo-1] destination_number(12345) =~ /^9000$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->eavesdrop] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [eavesdrop] destination_number(12345) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->eavesdrop] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [eavesdrop] destination_number(12345) =~ /^779$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call_return] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call_return] destination_number(12345) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->del-group] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [del-group] destination_number(12345) =~ /^80(\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->add-group] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [add-group] destination_number(12345) =~ /^81(\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call-group-simo] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call-group-simo] destination_number(12345) =~ /^82(\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call-group-order] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call-group-order] destination_number(12345) =~ /^83(\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->extension-intercom] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [extension-intercom] destination_number(12345) =~ /^8(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Local_Extension] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Local_Extension] destination_number(12345) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Nal_Peer_Extension] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Nal_Peer_Extension] destination_number(12345) =~ /^(\d{6})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Nal_Peer_Extension_Admin] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Nal_Peer_Extension_Admin] destination_number(12345) =~ /^(\d{4})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Local_Extension_Skinny] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Local_Extension_Skinny] destination_number(12345) =~ /^(11[01][0-9])$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_sales] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_sales] destination_number(12345) =~ /^2000$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_support] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_support] destination_number(12345) =~ /^2001$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_billing] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_billing] destination_number(12345) =~ /^2002$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->operator] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [operator] destination_number(12345) =~ /^(operator|0)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->vmain] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [vmain] destination_number(12345) =~ /^vmain$|^4000$|^\*98$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->sip_uri] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [sip_uri] destination_number(12345) =~ /^sip:(.*)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->nb_conferences] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [nb_conferences] destination_number(12345) =~ /^(30\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->nal_conferences] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [nal_conferences] destination_number(12345) =~ /^(\d{5})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 Action answer() Dialplan: sofia/internal/100005@52.193.89.3 Action conference(12345) 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/100005@52.193.89.3) State Change CS_ROUTING -> CS_EXECUTE 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100005@52.193.89.3) State ROUTING going to sleep 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_EXECUTE (Cur 1 Tot 1) 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/100005@52.193.89.3) State EXECUTE 2017-08-07 02:35:50.353458 [DEBUG] mod_sofia.c:198 sofia/internal/100005@52.193.89.3 SOFIA EXECUTE 2017-08-07 02:35:50.353458 [DEBUG] switch_core_state_machine.c:328 sofia/internal/100005@52.193.89.3 Standard EXECUTE EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-spymap/100005/201628ce-7b19-11e7-a73a-c76caa719387) EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-last_dial/100005/12345) EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-last_dial/global/201628ce-7b19-11e7-a73a-c76caa719387) EXECUTE sofia/internal/100005@52.193.89.3 export(RFC2822_DATE=Mon, 07 Aug 2017 02:35:50 +0000) 2017-08-07 02:35:50.353458 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 07 Aug 2017 02:35:50 +0000] EXECUTE sofia/internal/100005@52.193.89.3 answer() 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[G722:9:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@48000 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/100005@52.193.89.3 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2017-08-07 02:35:50.353458 [DEBUG] switch_core_codec.c:111 sofia/internal/100005@52.193.89.3 Original read codec set to PCMU:0 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 102@8000 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4767 sofia/internal/100005@52.193.89.3 Set 2833 dtmf send payload to 102 recv payload to 102 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:96]/[VP8:99] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:96] +++ is saved as a match 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:4950 Video Codec Compare [H264:97]/[VP8:99] 2017-08-07 02:35:50.353458 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-08-07 02:35:50.353458 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/100005@52.193.89.3 VP8/90000 0 ms 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/100005@52.193.89.3] 172.31.26.52 port 21390 -> 10.22.30.124 port 7076 codec: 0 ms: 20 2017-08-07 02:35:50.353458 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7179 sofia/internal/100005@52.193.89.3 Set 2833 dtmf send payload to 102 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7186 sofia/internal/100005@52.193.89.3 Set 2833 dtmf receive payload to 102 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7209 sofia/internal/100005@52.193.89.3 Set rtp dtmf delay to 40 2017-08-07 02:35:50.353458 [DEBUG] switch_rtp.c:4126 Starting video timer. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7398 VIDEO RTP [sofia/internal/100005@52.193.89.3] 172.31.26.52:21692->10.22.30.124:9078 codec: 96 ms: 0 [SUCCESS] 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:6003 sofia/internal/100005@52.193.89.3 Starting Video thread 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:5869 sofia/internal/100005@52.193.89.3 Video thread started. Echo is off 2017-08-07 02:35:50.353458 [INFO] switch_core_media.c:7478 Activating VIDEO RTCP PORT 9079 interval 1000 mux -1 2017-08-07 02:35:50.353458 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 9079 2017-08-07 02:35:50.353458 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 10.22.30.124:9079 2 2017-08-07 02:35:50.353458 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/100005@52.193.89.3! 2017-08-07 02:35:50.353458 [DEBUG] switch_channel.c:3474 (sofia/internal/100005@52.193.89.3) Callstate Change RINGING -> EARLY 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/100005@52.193.89.3. 2017-08-07 02:35:50.353458 [DEBUG] switch_core_media.c:7256 Video params are unchanged for sofia/internal/100005@52.193.89.3. 2017-08-07 02:35:50.353458 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/100005@52.193.89.3: v=0 o=FreeSWITCH 1502051960 1502051961 IN IP4 52.193.89.3 s=FreeSWITCH c=IN IP4 52.193.89.3 t=0 0 m=audio 21390 RTP/AVP 0 102 a=rtpmap:0 PCMU/8000 a=rtpmap:102 telephone-event/8000 a=fmtp:102 0-16 a=ptime:20 a=sendrecv m=video 21692 RTP/AVP 96 b=AS:1024 a=rtpmap:96 VP8/90000 a=rtcp:21693 IN IP4 52.193.89.3 a=rtcp-fb:96 ccm fir a=rtcp-fb:96 ccm tmmbr a=rtcp-fb:96 nack a=rtcp-fb:96 nack pli 2017-08-07 02:35:50.353458 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/100005@52.193.89.3] has been answered send 1289 bytes to udp/[118.70.126.89]:44414 at 02:35:50.372877: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.juTJlWzmD;rport=44414;received=118.70.126.89 From: ;tag=aVBTEb6Xj To: ;tag=0g0DHDNpBm6Zj Call-ID: p8QPFQE58e CSeq: 21 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.19~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 Session-Expires: 120;refresher=uas Content-Type: application/sdp Content-Disposition: session Content-Length: 399 Remote-Party-ID: "12345" ;party=calling;privacy=off;screen=no v=0 o=FreeSWITCH 1502051960 1502051961 IN IP4 52.193.89.3 s=FreeSWITCH c=IN IP4 52.193.89.3 t=0 0 m=audio 21390 RTP/AVP 0 102 a=rtpmap:0 PCMU/8000 a=rtpmap:102 telephone-event/8000 a=fmtp:102 0-16 a=ptime:20 m=video 21692 RTP/AVP 96 b=AS:1024 a=rtpmap:96 VP8/90000 a=rtcp:21693 IN IP4 52.193.89.3 a=rtcp-fb:96 ccm fir a=rtcp-fb:96 ccm tmmbr a=rtcp-fb:96 nack a=rtcp-fb:96 nack pli ------------------------------------------------------------------------ 2017-08-07 02:35:50.353458 [DEBUG] switch_channel.c:3773 (sofia/internal/100005@52.193.89.3) Callstate Change EARLY -> ACTIVE 2017-08-07 02:35:50.353458 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [completed][200] EXECUTE sofia/internal/100005@52.193.89.3 conference(12345) 2017-08-07 02:35:50.374028 [WARNING] mod_conference.c:2915 Unspecified video-canvas-size, falling back to 1280x720 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:275 Adding layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 2x1 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1x1+2x1 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 4x4 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 5x5 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 6x6 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 8x8 to layout group grid 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:275 Adding layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 2x1-zoom to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3x1-zoom to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 5-grid-zoom to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3x2-zoom to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 7-grid-zoom to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 4x2-zoom to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid-zoom 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:275 Adding layout group 1up_top_left_plus 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1up_top_left+5 to layout group 1up_top_left_plus 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1up_top_left+7 to layout group 1up_top_left_plus 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 1up_top_left+9 to layout group 1up_top_left_plus 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:275 Adding layout group 3up_plus 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3up+4 to layout group 3up_plus 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:284 Adding node 3up+9 to layout group 3up_plus 2017-08-07 02:35:50.374028 [DEBUG] mod_conference.c:3076 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:1132 Canvas position 1 applied layout 3x3 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:1222 Layout set to 3x3 2017-08-07 02:35:50.374028 [DEBUG] conference_video.c:1182 Canvas attached to position 0 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:1691 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:1738 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2017-08-07 02:35:50.374028 [DEBUG] switch_core_codec.c:223 sofia/internal/100005@52.193.89.3 Push codec L16:100 2017-08-07 02:35:50.374028 [DEBUG] mod_conference.c:220 Setup timer success interval: 20 samples: 160 2017-08-07 02:35:50.374028 [ERR] switch_core_video.c:2267 This function is not available, libpng not installed 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '0' to 'mute' 2017-08-07 02:35:50.374028 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092b70 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '*' to 'deaf mute' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092ba0 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '9' to 'energy up' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092bd0 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '8' to 'energy equ' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092c00 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '7' to 'energy dn' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092c30 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '3' to 'vol talk up' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092c60 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '2' to 'vol talk zero' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092c90 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '1' to 'vol talk dn' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092cc0 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '6' to 'vol listen up' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092cf0 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '5' to 'vol listen zero' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092d20 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '4' to 'vol listen dn' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092d50 2017-08-07 02:35:50.374028 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '#' to 'hangup' 2017-08-07 02:35:50.374028 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe840092d80 2017-08-07 02:35:50.374028 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20 samples: 160 from codec PCMU 2017-08-07 02:35:50.404187 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 320x240 to 1280x720 1024 BW 2017-08-07 02:35:50.414205 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 1280x720 to 1280x720 1024 BW 2017-08-07 02:35:50.414205 [INFO] switch_vpx.c:502 VPX ENCODER RESET recv 578 bytes from udp/[118.70.126.89]:44414 at 02:35:50.492502: ------------------------------------------------------------------------ ACK sip:12345@52.193.89.3:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;rport;branch=z9hG4bK.YkHM7cnfV From: ;tag=aVBTEb6Xj To: ;tag=0g0DHDNpBm6Zj CSeq: 21 ACK Call-ID: p8QPFQE58e Max-Forwards: 70 Proxy-Authorization: Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, username="100005", uri="sip:12345@52.193.89.3", response="395a4c96831c94b4811be24eba26ddb7", cnonce="XXnpmhu1sw96ruLT", nc=00000001, qop=auth User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) ------------------------------------------------------------------------ 2017-08-07 02:35:50.494186 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [ready][200] 2017-08-07 02:35:50.794192 [INFO] switch_rtp.c:6700 Auto Changing video RTCP port from 10.22.30.124:9079 to 123.25.21.255:9079 2017-08-07 02:35:50.794192 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 123.25.21.255:9079 2 2017-08-07 02:35:50.924189 [INFO] switch_rtp.c:7231 Auto Changing audio port from 10.22.30.124:7076 to 118.70.128.64:7076 2017-08-07 02:35:53.134186 [DEBUG] mod_local_stream.c:871 Opening Stream [moh/8000] 8000hz recv 597 bytes from udp/[118.70.126.89]:44414 at 02:36:04.793052: ------------------------------------------------------------------------ BYE sip:12345@52.193.89.3:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.aCnpbEUwp;rport From: ;tag=aVBTEb6Xj To: ;tag=0g0DHDNpBm6Zj CSeq: 22 BYE Call-ID: p8QPFQE58e Max-Forwards: 70 User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) Proxy-Authorization: Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, username="100005", uri="sip:12345@52.193.89.3:5060;transport=udp", response="34ee3921d1356d0b02b05e92ef7793be", cnonce="CW0y01IEvzrHTN0n", nc=00000002, qop=auth ------------------------------------------------------------------------ 2017-08-07 02:36:04.794188 [NOTICE] sofia.c:1012 Hangup sofia/internal/100005@52.193.89.3 [CS_EXECUTE] [NORMAL_CLEARING] send 453 bytes to udp/[118.70.126.89]:44414 at 02:36:04.798633: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.aCnpbEUwp;rport=44414;received=118.70.126.89 From: ;tag=aVBTEb6Xj To: ;tag=0g0DHDNpBm6Zj Call-ID: p8QPFQE58e CSeq: 22 BYE User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2017-08-07 02:36:04.794188 [DEBUG] switch_core_media.c:5970 sofia/internal/100005@52.193.89.3 Video thread ended 2017-08-07 02:36:04.804185 [INFO] conference_loop.c:1469 Channel leaving conference, cause: NORMAL_CLEARING 2017-08-07 02:36:04.804185 [DEBUG] switch_core_session.c:2577 sofia/internal/100005@52.193.89.3 skip receive message [VIDEO_REFRESH_REQ] (channel is hungup already) 2017-08-07 02:36:04.804185 [DEBUG] mod_conference.c:2247 sofia/internal/100005@52.193.89.3 skip receive message [UNBRIDGE] (channel is hungup already) 2017-08-07 02:36:04.804185 [DEBUG] switch_core_media.c:9779 sofia/internal/100005@52.193.89.3 skip receive message [HARD_MUTE] (channel is hungup already) 2017-08-07 02:36:04.804185 [DEBUG] switch_core_codec.c:248 sofia/internal/100005@52.193.89.3 Restore previous codec PCMU:0. 2017-08-07 02:36:04.804185 [DEBUG] switch_core_session.c:2815 sofia/internal/100005@52.193.89.3 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/100005@52.193.89.3) State EXECUTE going to sleep 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_HANGUP (Cur 1 Tot 1) 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/100005@52.193.89.3) Callstate Change ACTIVE -> HANGUP 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/100005@52.193.89.3) State HANGUP 2017-08-07 02:36:04.804185 [DEBUG] mod_sofia.c:438 Channel sofia/internal/100005@52.193.89.3 hanging up, cause: NORMAL_CLEARING 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:60 sofia/internal/100005@52.193.89.3 Standard HANGUP, cause: NORMAL_CLEARING 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/100005@52.193.89.3) State HANGUP going to sleep 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/100005@52.193.89.3) State Change CS_HANGUP -> CS_REPORTING 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_REPORTING (Cur 1 Tot 1) 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/100005@52.193.89.3) State REPORTING 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:174 sofia/internal/100005@52.193.89.3 Standard REPORTING, cause: NORMAL_CLEARING 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/100005@52.193.89.3) State REPORTING going to sleep 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/100005@52.193.89.3) State Change CS_REPORTING -> CS_DESTROY 2017-08-07 02:36:04.804185 [DEBUG] switch_core_session.c:1665 Session 1 (sofia/internal/100005@52.193.89.3) Locked, Waiting on external entities 2017-08-07 02:36:04.804185 [NOTICE] switch_core_session.c:1683 Session 1 (sofia/internal/100005@52.193.89.3) Ended 2017-08-07 02:36:04.804185 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/100005@52.193.89.3 [CS_DESTROY] 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/100005@52.193.89.3) Running State Change CS_DESTROY (Cur 0 Tot 1) 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/100005@52.193.89.3) State DESTROY 2017-08-07 02:36:04.804185 [DEBUG] mod_sofia.c:343 sofia/internal/100005@52.193.89.3 SOFIA DESTROY 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:181 sofia/internal/100005@52.193.89.3 Standard DESTROY 2017-08-07 02:36:04.804185 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/100005@52.193.89.3) State DESTROY going to sleep 2017-08-07 02:36:04.824252 [DEBUG] mod_conference.c:788 Write Lock ON 2017-08-07 02:36:04.824252 [DEBUG] mod_conference.c:791 Write Lock OFF recv 597 bytes from udp/[118.70.126.89]:44414 at 02:36:05.612650: ------------------------------------------------------------------------ BYE sip:12345@52.193.89.3:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.aCnpbEUwp;rport From: ;tag=aVBTEb6Xj To: ;tag=0g0DHDNpBm6Zj CSeq: 22 BYE Call-ID: p8QPFQE58e Max-Forwards: 70 User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) Proxy-Authorization: Digest realm="52.193.89.3", nonce="201639d6-7b19-11e7-a73b-c76caa719387", algorithm=MD5, username="100005", uri="sip:12345@52.193.89.3:5060;transport=udp", response="34ee3921d1356d0b02b05e92ef7793be", cnonce="CW0y01IEvzrHTN0n", nc=00000002, qop=auth ------------------------------------------------------------------------ send 453 bytes to udp/[118.70.126.89]:44414 at 02:36:05.612752: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.aCnpbEUwp;rport=44414;received=118.70.126.89 From: ;tag=aVBTEb6Xj To: ;tag=0g0DHDNpBm6Zj Call-ID: p8QPFQE58e CSeq: 22 BYE User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> recv 1387 bytes from udp/[118.70.126.89]:44414 at 02:36:50.680148: ------------------------------------------------------------------------ INVITE sip:12345@52.193.89.3 SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.9-wqPGP~v;rport From: ;tag=tH-SJK~Z- To: sip:12345@52.193.89.3 CSeq: 20 INVITE Call-ID: NNFynTe-jL Max-Forwards: 70 Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 802 Contact: ;+sip.instance="" User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) v=0 o=100005 2816 4029 IN IP4 10.22.30.124 s=Talk c=IN IP4 10.22.30.124 b=AS:660 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 SILK/16000 a=rtpmap:98 speex/16000 a=fmtp:98 vbr=on a=rtpmap:99 speex/8000 a=fmtp:99 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:100 telephone-event/16000 a=rtpmap:102 telephone-event/8000 a=rtcp-fb:* ccm tmmbr m=video 9078 RTP/AVP 96 97 a=rtpmap:96 VP8/90000 a=rtpmap:97 H264/90000 a=fmtp:97 profile-level-id=42801F a=rtcp-fb:* ccm tmmbr a=rtcp-fb:96 nack pli a=rtcp-fb:96 nack sli a=rtcp-fb:96 ack rpsi a=rtcp-fb:96 ccm fir a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir ------------------------------------------------------------------------ send 296 bytes to udp/[118.70.126.89]:44414 at 02:36:50.680528: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.9-wqPGP~v;rport=44414;received=118.70.126.89 From: ;tag=tH-SJK~Z- To: sip:12345@52.193.89.3 Call-ID: NNFynTe-jL CSeq: 20 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit Content-Length: 0 ------------------------------------------------------------------------ 2017-08-07 02:36:50.674186 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/100005@52.193.89.3 [4434e9e8-7b19-11e7-a744-c76caa719387] 2017-08-07 02:36:50.674186 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_NEW (Cur 1 Tot 2) 2017-08-07 02:36:50.674186 [DEBUG] sofia.c:9873 sofia/internal/100005@52.193.89.3 receiving invite from 118.70.126.89:44414 version: 1.6.19 64bit 2017-08-07 02:36:50.674186 [DEBUG] sofia.c:10044 IP 118.70.126.89 Rejected by acl "domains". Falling back to Digest auth. 2017-08-07 02:36:50.674186 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/100005@52.193.89.3) State NEW send 800 bytes to udp/[118.70.126.89]:44414 at 02:36:50.681300: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.9-wqPGP~v;rport=44414;received=118.70.126.89 From: ;tag=tH-SJK~Z- To: ;tag=1SS6j85S8vvje Call-ID: NNFynTe-jL CSeq: 20 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19~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="52.193.89.3", nonce="4434f5b4-7b19-11e7-a745-c76caa719387", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ 2017-08-07 02:36:50.674186 [DEBUG] sofia.c:2334 detaching session 4434e9e8-7b19-11e7-a744-c76caa719387 recv 372 bytes from udp/[118.70.126.89]:44414 at 02:36:50.831021: ------------------------------------------------------------------------ ACK sip:12345@52.193.89.3 SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.9-wqPGP~v;rport Call-ID: NNFynTe-jL From: ;tag=tH-SJK~Z- To: ;tag=1SS6j85S8vvje Contact: ;+sip.instance="" Max-Forwards: 70 CSeq: 20 ACK ------------------------------------------------------------------------ recv 1642 bytes from udp/[118.70.126.89]:44414 at 02:36:50.832117: ------------------------------------------------------------------------ INVITE sip:12345@52.193.89.3 SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.PotJzfLZa;rport From: ;tag=tH-SJK~Z- To: sip:12345@52.193.89.3 CSeq: 21 INVITE Call-ID: NNFynTe-jL Max-Forwards: 70 Supported: replaces, outbound Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE Content-Type: application/sdp Content-Length: 802 Contact: ;+sip.instance="" User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) Proxy-Authorization: Digest realm="52.193.89.3", nonce="4434f5b4-7b19-11e7-a745-c76caa719387", algorithm=MD5, username="100005", uri="sip:12345@52.193.89.3", response="7b5ab9d9d7bb97534957ad7c97693882", cnonce="KsoDY2XgxVohOJ0P", nc=00000001, qop=auth v=0 o=100005 2816 4029 IN IP4 10.22.30.124 s=Talk c=IN IP4 10.22.30.124 b=AS:660 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 SILK/16000 a=rtpmap:98 speex/16000 a=fmtp:98 vbr=on a=rtpmap:99 speex/8000 a=fmtp:99 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:100 telephone-event/16000 a=rtpmap:102 telephone-event/8000 a=rtcp-fb:* ccm tmmbr m=video 9078 RTP/AVP 96 97 a=rtpmap:96 VP8/90000 a=rtpmap:97 H264/90000 a=fmtp:97 profile-level-id=42801F a=rtcp-fb:* ccm tmmbr a=rtcp-fb:96 nack pli a=rtcp-fb:96 nack sli a=rtcp-fb:96 ack rpsi a=rtcp-fb:96 ccm fir a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir ------------------------------------------------------------------------ send 296 bytes to udp/[118.70.126.89]:44414 at 02:36:50.832383: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.PotJzfLZa;rport=44414;received=118.70.126.89 From: ;tag=tH-SJK~Z- To: sip:12345@52.193.89.3 Call-ID: NNFynTe-jL CSeq: 21 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.19~64bit Content-Length: 0 ------------------------------------------------------------------------ 2017-08-07 02:36:50.824213 [DEBUG] sofia.c:2442 Re-attaching to session 4434e9e8-7b19-11e7-a744-c76caa719387 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:9873 sofia/internal/100005@52.193.89.3 receiving invite from 118.70.126.89:44414 version: 1.6.19 64bit 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:10044 IP 118.70.126.89 Rejected by acl "domains". Falling back to Digest auth. 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:11168 Setting NAT mode based on nat.auto 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [received][100] 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=100005 2816 4029 IN IP4 10.22.30.124 s=Talk c=IN IP4 10.22.30.124 b=AS:660 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 SILK/16000 a=rtpmap:98 speex/16000 a=fmtp:98 vbr=on a=rtpmap:99 speex/8000 a=fmtp:99 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:100 telephone-event/16000 a=rtpmap:102 telephone-event/8000 a=rtcp-fb:* ccm tmmbr m=video 9078 RTP/AVP 96 97 a=rtpmap:96 VP8/90000 a=rtpmap:97 H264/90000 a=fmtp:97 profile-level-id=42801F a=rtcp-fb:* ccm tmmbr a=rtcp-fb:96 nack pli a=rtcp-fb:96 nack sli a=rtcp-fb:96 ack rpsi a=rtcp-fb:96 ccm fir a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir 2017-08-07 02:36:50.835794 [DEBUG] sofia.c:7486 (sofia/internal/100005@52.193.89.3) State Change CS_NEW -> CS_INIT 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_INIT (Cur 1 Tot 2) 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100005@52.193.89.3) State INIT 2017-08-07 02:36:50.835794 [DEBUG] mod_sofia.c:90 sofia/internal/100005@52.193.89.3 SOFIA INIT 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:40 sofia/internal/100005@52.193.89.3 Standard INIT 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/100005@52.193.89.3) State Change CS_INIT -> CS_ROUTING 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/100005@52.193.89.3) State INIT going to sleep 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_ROUTING (Cur 1 Tot 2) 2017-08-07 02:36:50.835794 [DEBUG] switch_channel.c:2249 (sofia/internal/100005@52.193.89.3) Callstate Change DOWN -> RINGING 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100005@52.193.89.3) State ROUTING 2017-08-07 02:36:50.835794 [DEBUG] mod_sofia.c:143 sofia/internal/100005@52.193.89.3 SOFIA ROUTING 2017-08-07 02:36:50.835794 [DEBUG] switch_core_state_machine.c:236 sofia/internal/100005@52.193.89.3 Standard ROUTING 2017-08-07 02:36:50.835794 [INFO] mod_dialplan_xml.c:637 Processing 100005 <100005>->12345 in context default Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->unloop] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->tod_example] continue=true Dialplan: sofia/internal/100005@52.193.89.3 Date/TimeMatch (FAIL) [tod_example] break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->holiday_example] continue=true Dialplan: sofia/internal/100005@52.193.89.3 Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->global-intercept] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global-intercept] destination_number(12345) =~ /^886$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group-intercept] destination_number(12345) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [intercept-ext] destination_number(12345) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->redial] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [redial] destination_number(12345) =~ /^(redial|870)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->global] continue=true Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${default_password}(123456) =~ /^1234$/ break=never Dialplan: sofia/internal/100005@52.193.89.3 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/internal/100005@52.193.89.3 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [global] ${switch_r_sdp}(v=0 o=100005 2816 4029 IN IP4 10.22.30.124 s=Talk c=IN IP4 10.22.30.124 b=AS:660 t=0 0 a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics m=audio 7076 RTP/AVP 96 97 98 99 0 8 18 101 100 102 a=rtpmap:96 opus/48000/2 a=fmtp:96 useinbandfec=1 a=rtpmap:97 SILK/16000 a=rtpmap:98 speex/16000 a=fmtp:98 vbr=on a=rtpmap:99 speex/8000 a=fmtp:99 vbr=on a=fmtp:18 annexb=yes a=rtpmap:101 telephone-event/48000 a=rtpmap:100 telephone-event/16000 a=rtpmap:102 telephone-event/8000 a=rtcp-fb:* ccm tmmbr m=video 9078 RTP/AVP 96 97 a=rtpmap:96 VP8/90000 a=rtpmap:97 H264/90000 a=fmtp:97 profile-level-id=42801F a=rtcp-fb:* ccm tmmbr a=rtcp-fb:96 nack pli a=rtcp-fb:96 nack sli a=rtcp-fb:96 ack rpsi a=rtcp-fb:96 ccm fir a=rtcp-fb:97 nack pli a=rtcp-fb:97 ccm fir ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never Dialplan: sofia/internal/100005@52.193.89.3 Absolute Condition [global] Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/internal/100005@52.193.89.3 Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/internal/100005@52.193.89.3 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->snom-demo-2] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [snom-demo-2] destination_number(12345) =~ /^9001$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->snom-demo-1] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [snom-demo-1] destination_number(12345) =~ /^9000$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->eavesdrop] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [eavesdrop] destination_number(12345) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->eavesdrop] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [eavesdrop] destination_number(12345) =~ /^779$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call_return] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call_return] destination_number(12345) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->del-group] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [del-group] destination_number(12345) =~ /^80(\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->add-group] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [add-group] destination_number(12345) =~ /^81(\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call-group-simo] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call-group-simo] destination_number(12345) =~ /^82(\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->call-group-order] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [call-group-order] destination_number(12345) =~ /^83(\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->extension-intercom] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [extension-intercom] destination_number(12345) =~ /^8(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Local_Extension] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Local_Extension] destination_number(12345) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Nal_Peer_Extension] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Nal_Peer_Extension] destination_number(12345) =~ /^(\d{6})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Nal_Peer_Extension_Admin] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Nal_Peer_Extension_Admin] destination_number(12345) =~ /^(\d{4})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->Local_Extension_Skinny] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [Local_Extension_Skinny] destination_number(12345) =~ /^(11[01][0-9])$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_sales] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_sales] destination_number(12345) =~ /^2000$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_support] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_support] destination_number(12345) =~ /^2001$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->group_dial_billing] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [group_dial_billing] destination_number(12345) =~ /^2002$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->operator] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [operator] destination_number(12345) =~ /^(operator|0)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->vmain] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [vmain] destination_number(12345) =~ /^vmain$|^4000$|^\*98$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->sip_uri] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [sip_uri] destination_number(12345) =~ /^sip:(.*)$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->nb_conferences] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (FAIL) [nb_conferences] destination_number(12345) =~ /^(30\d{2})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 parsing [default->nal_conferences] continue=false Dialplan: sofia/internal/100005@52.193.89.3 Regex (PASS) [nal_conferences] destination_number(12345) =~ /^(\d{5})$/ break=on-false Dialplan: sofia/internal/100005@52.193.89.3 Action answer() Dialplan: sofia/internal/100005@52.193.89.3 Action conference(12345) 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/100005@52.193.89.3) State Change CS_ROUTING -> CS_EXECUTE 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/100005@52.193.89.3) State ROUTING going to sleep 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/100005@52.193.89.3) Running State Change CS_EXECUTE (Cur 1 Tot 2) 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/100005@52.193.89.3) State EXECUTE 2017-08-07 02:36:50.844249 [DEBUG] mod_sofia.c:198 sofia/internal/100005@52.193.89.3 SOFIA EXECUTE 2017-08-07 02:36:50.844249 [DEBUG] switch_core_state_machine.c:328 sofia/internal/100005@52.193.89.3 Standard EXECUTE EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-spymap/100005/4434e9e8-7b19-11e7-a744-c76caa719387) EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-last_dial/100005/12345) EXECUTE sofia/internal/100005@52.193.89.3 hash(insert/172.31.26.52-last_dial/global/4434e9e8-7b19-11e7-a744-c76caa719387) EXECUTE sofia/internal/100005@52.193.89.3 export(RFC2822_DATE=Mon, 07 Aug 2017 02:36:50 +0000) 2017-08-07 02:36:50.844249 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 07 Aug 2017 02:36:50 +0000] EXECUTE sofia/internal/100005@52.193.89.3 answer() 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[G722:9:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [SILK:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [speex:99:8000:20:0:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@48000 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/100005@52.193.89.3 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2017-08-07 02:36:50.844249 [DEBUG] switch_core_codec.c:111 sofia/internal/100005@52.193.89.3 Original read codec set to PCMU:0 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 102@8000 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4767 sofia/internal/100005@52.193.89.3 Set 2833 dtmf send payload to 102 recv payload to 102 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4950 Video Codec Compare [VP8:96]/[VP8:99] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4982 Video Codec Compare [VP8:96] +++ is saved as a match 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:4950 Video Codec Compare [H264:97]/[VP8:99] 2017-08-07 02:36:50.844249 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-08-07 02:36:50.844249 [DEBUG] switch_vpx.c:631 VPX VER:v1.6.0 VPX_IMAGE_ABI_VERSION:4 VPX_CODEC_ABI_VERSION:7 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:2871 Set VIDEO Codec sofia/internal/100005@52.193.89.3 VP8/90000 0 ms 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/100005@52.193.89.3] 172.31.26.52 port 26554 -> 10.22.30.124 port 7076 codec: 0 ms: 20 2017-08-07 02:36:50.844249 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7179 sofia/internal/100005@52.193.89.3 Set 2833 dtmf send payload to 102 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7186 sofia/internal/100005@52.193.89.3 Set 2833 dtmf receive payload to 102 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7209 sofia/internal/100005@52.193.89.3 Set rtp dtmf delay to 40 2017-08-07 02:36:50.844249 [DEBUG] switch_rtp.c:4126 Starting video timer. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7398 VIDEO RTP [sofia/internal/100005@52.193.89.3] 172.31.26.52:29328->10.22.30.124:9078 codec: 96 ms: 0 [SUCCESS] 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:6003 sofia/internal/100005@52.193.89.3 Starting Video thread 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:5869 sofia/internal/100005@52.193.89.3 Video thread started. Echo is off 2017-08-07 02:36:50.844249 [INFO] switch_core_media.c:7478 Activating VIDEO RTCP PORT 9079 interval 1000 mux -1 2017-08-07 02:36:50.844249 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 90000 Remote Port: 9079 2017-08-07 02:36:50.844249 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 10.22.30.124:9079 2 2017-08-07 02:36:50.844249 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/100005@52.193.89.3! 2017-08-07 02:36:50.844249 [DEBUG] switch_channel.c:3474 (sofia/internal/100005@52.193.89.3) Callstate Change RINGING -> EARLY 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/100005@52.193.89.3. 2017-08-07 02:36:50.844249 [DEBUG] switch_core_media.c:7256 Video params are unchanged for sofia/internal/100005@52.193.89.3. 2017-08-07 02:36:50.844249 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/100005@52.193.89.3: v=0 o=FreeSWITCH 1502046856 1502046857 IN IP4 52.193.89.3 s=FreeSWITCH c=IN IP4 52.193.89.3 t=0 0 m=audio 26554 RTP/AVP 0 102 a=rtpmap:0 PCMU/8000 a=rtpmap:102 telephone-event/8000 a=fmtp:102 0-16 a=ptime:20 a=sendrecv m=video 29328 RTP/AVP 96 b=AS:1024 a=rtpmap:96 VP8/90000 a=rtcp:29329 IN IP4 52.193.89.3 a=rtcp-fb:96 ccm fir a=rtcp-fb:96 ccm tmmbr a=rtcp-fb:96 nack a=rtcp-fb:96 nack pli 2017-08-07 02:36:50.844249 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/100005@52.193.89.3] has been answered 2017-08-07 02:36:50.844249 [DEBUG] switch_channel.c:3773 (sofia/internal/100005@52.193.89.3) Callstate Change EARLY -> ACTIVE EXECUTE sofia/internal/100005@52.193.89.3 conference(12345) 2017-08-07 02:36:50.844249 [WARNING] mod_conference.c:2915 Unspecified video-canvas-size, falling back to 1280x720 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:275 Adding layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 2x1 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1x1+2x1 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 4x4 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 5x5 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 6x6 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 8x8 to layout group grid 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:275 Adding layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1x1 to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 2x1-zoom to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3x1-zoom to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 2x2 to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 5-grid-zoom to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3x2-zoom to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 7-grid-zoom to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 4x2-zoom to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3x3 to layout group grid-zoom 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:275 Adding layout group 1up_top_left_plus 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1up_top_left+5 to layout group 1up_top_left_plus 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1up_top_left+7 to layout group 1up_top_left_plus 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 1up_top_left+9 to layout group 1up_top_left_plus 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:275 Adding layout group 3up_plus 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3up+4 to layout group 3up_plus 2017-08-07 02:36:50.844249 [DEBUG] conference_video.c:284 Adding node 3up+9 to layout group 3up_plus 2017-08-07 02:36:50.844249 [DEBUG] mod_conference.c:3076 using channel sound prefix: /usr/share/freeswitch/sounds/en/us/callie send 1289 bytes to udp/[118.70.126.89]:44414 at 02:36:50.853246: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.22.30.124:44414;branch=z9hG4bK.PotJzfLZa;rport=44414;received=118.70.126.89 From: ;tag=tH-SJK~Z- To: ;tag=22jZm3pX55j5S Call-ID: NNFynTe-jL CSeq: 21 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.19~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 Session-Expires: 120;refresher=uas Content-Type: application/sdp Content-Disposition: session Content-Length: 399 Remote-Party-ID: "12345" ;party=calling;privacy=off;screen=no v=0 o=FreeSWITCH 1502046856 1502046857 IN IP4 52.193.89.3 s=FreeSWITCH c=IN IP4 52.193.89.3 t=0 0 m=audio 26554 RTP/AVP 0 102 a=rtpmap:0 PCMU/8000 a=rtpmap:102 telephone-event/8000 a=fmtp:102 0-16 a=ptime:20 m=video 29328 RTP/AVP 96 b=AS:1024 a=rtpmap:96 VP8/90000 a=rtcp:29329 IN IP4 52.193.89.3 a=rtcp-fb:96 ccm fir a=rtcp-fb:96 ccm tmmbr a=rtcp-fb:96 nack a=rtcp-fb:96 nack pli ------------------------------------------------------------------------ 2017-08-07 02:36:50.854191 [DEBUG] conference_video.c:1132 Canvas position 1 applied layout 3x3 2017-08-07 02:36:50.854191 [DEBUG] conference_video.c:1222 Layout set to 3x3 2017-08-07 02:36:50.854191 [DEBUG] conference_video.c:1182 Canvas attached to position 0 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:1691 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:1738 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2017-08-07 02:36:50.854191 [DEBUG] switch_core_codec.c:223 sofia/internal/100005@52.193.89.3 Push codec L16:100 2017-08-07 02:36:50.854191 [ERR] switch_core_video.c:2267 This function is not available, libpng not installed 2017-08-07 02:36:50.854191 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [completed][200] 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '0' to 'mute' 2017-08-07 02:36:50.854191 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cd80 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '*' to 'deaf mute' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cdb0 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '9' to 'energy up' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cde0 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '8' to 'energy equ' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08ce10 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '7' to 'energy dn' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08ce40 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '3' to 'vol talk up' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08ce70 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '2' to 'vol talk zero' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cea0 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '1' to 'vol talk dn' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08ced0 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '6' to 'vol listen up' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cf00 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '5' to 'vol listen zero' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cf30 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '4' to 'vol listen dn' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cf60 2017-08-07 02:36:50.854191 [DEBUG] conference_member.c:128 sofia/internal/100005@52.193.89.3 binding '#' to 'hangup' 2017-08-07 02:36:50.854191 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7fe81f0f52e0 data: 0x7fe82c08cf90 2017-08-07 02:36:50.854191 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20 samples: 160 from codec PCMU 2017-08-07 02:36:50.854191 [DEBUG] mod_conference.c:220 Setup timer success interval: 20 samples: 160 2017-08-07 02:36:50.884208 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 320x240 to 1280x720 1024 BW 2017-08-07 02:36:50.894197 [NOTICE] switch_vpx.c:406 VPX reset encoder picture from 1280x720 to 1280x720 1024 BW 2017-08-07 02:36:50.894197 [INFO] switch_vpx.c:502 VPX ENCODER RESET recv 578 bytes from udp/[118.70.126.89]:44414 at 02:36:51.003211: ------------------------------------------------------------------------ ACK sip:12345@52.193.89.3:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 10.22.30.124:44414;rport;branch=z9hG4bK.oK4jaG2wi From: ;tag=tH-SJK~Z- To: ;tag=22jZm3pX55j5S CSeq: 21 ACK Call-ID: NNFynTe-jL Max-Forwards: 70 Proxy-Authorization: Digest realm="52.193.89.3", nonce="4434f5b4-7b19-11e7-a745-c76caa719387", algorithm=MD5, username="100005", uri="sip:12345@52.193.89.3", response="7b5ab9d9d7bb97534957ad7c97693882", cnonce="KsoDY2XgxVohOJ0P", nc=00000001, qop=auth User-Agent: LinphoneAndroid/3.2.7 (belle-sip/1.6.1) ------------------------------------------------------------------------ 2017-08-07 02:36:51.014202 [DEBUG] sofia.c:7084 Channel sofia/internal/100005@52.193.89.3 entering state [ready][200] 2017-08-07 02:36:51.244208 [INFO] switch_rtp.c:6700 Auto Changing video RTCP port from 10.22.30.124:9079 to 123.25.21.255:9079 2017-08-07 02:36:51.244208 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 123.25.21.255:9079 2 2017-08-07 02:36:51.384198 [INFO] switch_rtp.c:7231 Auto Changing audio port from 10.22.30.124:7076 to 118.70.126.89:7076 2017-08-07 02:36:53.614186 [DEBUG] mod_local_stream.c:871 Opening Stream [moh/8000] 8000hz