recv 653 bytes from udp/[172.20.1.10]:5060 at 10:21:42.720276: ------------------------------------------------------------------------ REGISTER sip:172.20.1.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK477b65ee7a44203c;rport Contact: ;expires=5;reg-id=123 Max-Forwards: 70 Authorization: Digest username="radio1", realm="172.20.1.2", nonce="217b51f8-7916-11e7-a5b7-f77b865a2304", uri="sip:172.20.1.2:5060", response="1291bb46bca5d78989618e9ee9fdc46d", cnonce="29b30994607fbce8", qop=auth, nc=00037691 To: From: ;tag=39215c725235cf15 Call-ID: 29f9e1db035f537e CSeq: 422200 REGISTER User-Agent: Ervocom SIP UA 1.2.3 (?/?) Supported: gruu, outbound, path Content-Length: 0 ------------------------------------------------------------------------ send 582 bytes to udp/[172.20.1.10]:5060 at 10:21:42.730592: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK477b65ee7a44203c;rport=5060 From: ;tag=39215c725235cf15 To: ;tag=29tXye7mS4SyS Call-ID: 29f9e1db035f537e CSeq: 422200 REGISTER Contact: ;expires=5 Date: Wed, 16 Aug 2017 10:21:42 GMT User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ recv 1392 bytes from udp/[10.10.30.51]:5060 at 10:21:44.607542: ------------------------------------------------------------------------ INVITE sip:0792710365@172.20.1.2 SIP/2.0 Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-x6wtln1jlx24;rport From: "RBS Testing" ;tag=qcx12rgq6j To: Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 1 INVITE Max-Forwards: 70 User-Agent: snomD375/8.9.3.15 Contact: ;reg-id=1 X-Serialnumber: 000413910687 P-Key-Flags: resolution="31x13", keys="4" Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change Session-Expires: 3600 Min-SE: 90 Content-Type: application/sdp Content-Length: 634 v=0 o=root 983205152 983205152 IN IP4 10.10.30.51 s=call c=IN IP4 10.10.30.51 t=0 0 m=audio 60010 RTP/SAVP 0 8 3 99 112 18 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=ptime:20 a=sendrecv m=audio 60010 RTP/AVP 0 8 3 99 112 18 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=ptime:20 a=sendrecv ------------------------------------------------------------------------ send 352 bytes to udp/[10.10.30.51]:5060 at 10:21:44.609116: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-x6wtln1jlx24;rport=5060 From: "RBS Testing" ;tag=qcx12rgq6j To: Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Content-Length: 0 ------------------------------------------------------------------------ 2017-08-16 10:21:44.598073 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/1000@172.20.1.2 [b4006ee6-826c-11e7-a743-f77b865a2304] 2017-08-16 10:21:44.598073 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_NEW 2017-08-16 10:21:44.598073 [DEBUG] sofia.c:9248 sofia/internal/1000@172.20.1.2 receiving invite from 10.10.30.51:5060 version: 1.6.6 git df20d53 2017-02-03 08:57:21Z 64bit 2017-08-16 10:21:44.598073 [DEBUG] sofia.c:9415 IP 10.10.30.51 Rejected by acl "domains". Falling back to Digest auth. 2017-08-16 10:21:44.598073 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/1000@172.20.1.2) State NEW send 853 bytes to udp/[10.10.30.51]:5060 at 10:21:44.612844: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-x6wtln1jlx24;rport=5060 From: "RBS Testing" ;tag=qcx12rgq6j To: ;tag=3jmp09QrpDgHN Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~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="172.20.1.2", nonce="b400a78a-826c-11e7-a744-f77b865a2304", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ 2017-08-16 10:21:44.598073 [DEBUG] sofia.c:2147 detaching session b4006ee6-826c-11e7-a743-f77b865a2304 recv 413 bytes from udp/[10.10.30.51]:5060 at 10:21:44.740837: ------------------------------------------------------------------------ ACK sip:0792710365@172.20.1.2 SIP/2.0 Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-x6wtln1jlx24;rport From: "RBS Testing" ;tag=qcx12rgq6j To: ;tag=3jmp09QrpDgHN Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 1 ACK Max-Forwards: 70 User-Agent: snomD375/8.9.3.15 Contact: ;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ recv 1630 bytes from udp/[10.10.30.51]:5060 at 10:21:46.256889: ------------------------------------------------------------------------ INVITE sip:0792710365@172.20.1.2 SIP/2.0 Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport From: "RBS Testing" ;tag=qcx12rgq6j To: Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 2 INVITE Max-Forwards: 70 User-Agent: snomD375/8.9.3.15 Contact: ;reg-id=1 X-Serialnumber: 000413910687 P-Key-Flags: resolution="31x13", keys="4" Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change Session-Expires: 3600 Min-SE: 90 Proxy-Authorization: Digest username="1000",realm="172.20.1.2",nonce="b400a78a-826c-11e7-a744-f77b865a2304",uri="sip:0792710365@172.20.1.2",qop=auth,nc=00000001,cnonce="5df0c72c",response="7b7ae0c2c1b8eaffe954ca7affdcff08",algorithm=MD5 Content-Type: application/sdp Content-Length: 634 v=0 o=root 983205152 983205152 IN IP4 10.10.30.51 s=call c=IN IP4 10.10.30.51 t=0 0 m=audio 60010 RTP/SAVP 0 8 3 99 112 18 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=ptime:20 a=sendrecv m=audio 60010 RTP/AVP 0 8 3 99 112 18 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=ptime:20 a=sendrecv ------------------------------------------------------------------------ send 352 bytes to udp/[10.10.30.51]:5060 at 10:21:46.258522: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport=5060 From: "RBS Testing" ;tag=qcx12rgq6j To: Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Content-Length: 0 ------------------------------------------------------------------------ 2017-08-16 10:21:46.258063 [DEBUG] sofia.c:2255 Re-attaching to session b4006ee6-826c-11e7-a743-f77b865a2304 2017-08-16 10:21:46.258063 [DEBUG] sofia.c:9248 sofia/internal/1000@172.20.1.2 receiving invite from 10.10.30.51:5060 version: 1.6.6 git df20d53 2017-02-03 08:57:21Z 64bit 2017-08-16 10:21:46.258063 [DEBUG] sofia.c:9415 IP 10.10.30.51 Rejected by acl "domains". Falling back to Digest auth. 2017-08-16 10:21:46.278084 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@172.20.1.2 entering state [received][100] 2017-08-16 10:21:46.278084 [DEBUG] sofia.c:6770 Remote SDP: v=0 o=root 983205152 983205152 IN IP4 10.10.30.51 s=call c=IN IP4 10.10.30.51 t=0 0 m=audio 60010 RTP/SAVP 0 8 3 99 112 18 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU a=ptime:20 m=audio 60010 RTP/AVP 0 8 3 99 112 18 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=ptime:20 2017-08-16 10:21:46.278084 [DEBUG] sofia.c:7125 (sofia/internal/1000@172.20.1.2) State Change CS_NEW -> CS_INIT 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_INIT 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1000@172.20.1.2) State INIT 2017-08-16 10:21:46.278084 [DEBUG] mod_sofia.c:88 sofia/internal/1000@172.20.1.2 SOFIA INIT 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@172.20.1.2 Standard INIT 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@172.20.1.2) State Change CS_INIT -> CS_ROUTING 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1000@172.20.1.2) State INIT going to sleep 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_ROUTING 2017-08-16 10:21:46.278084 [DEBUG] switch_channel.c:2247 (sofia/internal/1000@172.20.1.2) Callstate Change DOWN -> RINGING 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1000@172.20.1.2) State ROUTING 2017-08-16 10:21:46.278084 [DEBUG] mod_sofia.c:141 sofia/internal/1000@172.20.1.2 SOFIA ROUTING 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1000@172.20.1.2 Standard ROUTING 2017-08-16 10:21:46.278084 [INFO] mod_dialplan_xml.c:637 Processing RBS Testing <1000>->0792710365 in context default Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->unloop] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->tod_example] continue=true Dialplan: sofia/internal/1000@172.20.1.2 Date/Time Match (PASS) [tod_example] break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Action set(open=true) Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->holiday_example] continue=true Dialplan: sofia/internal/1000@172.20.1.2 Date/TimeMatch (FAIL) [holiday_example] break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->global-intercept] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [global-intercept] destination_number(0792710365) =~ /^886$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [group-intercept] destination_number(0792710365) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [intercept-ext] destination_number(0792710365) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->redial] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [redial] destination_number(0792710365) =~ /^(redial|870)$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->global] continue=true Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [global] ${default_password}(12345) =~ /^1234$/ break=never Dialplan: sofia/internal/1000@172.20.1.2 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/1000@172.20.1.2 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [global] ${switch_r_sdp}(v=0 o=root 983205152 983205152 IN IP4 10.10.30.51 s=call c=IN IP4 10.10.30.51 t=0 0 m=audio 60010 RTP/SAVP 0 8 3 99 112 18 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU a=ptime:20 m=audio 60010 RTP/AVP 0 8 3 99 112 18 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:99 G726-32/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=ptime:20 ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never Dialplan: sofia/internal/1000@172.20.1.2 Action set(rtp_secure_media=true) Dialplan: sofia/internal/1000@172.20.1.2 Absolute Condition [global] Dialplan: sofia/internal/1000@172.20.1.2 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/internal/1000@172.20.1.2 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/internal/1000@172.20.1.2 Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/internal/1000@172.20.1.2 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->snom-demo-2] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [snom-demo-2] destination_number(0792710365) =~ /^9001$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->snom-demo-1] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [snom-demo-1] destination_number(0792710365) =~ /^9000$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->eavesdrop] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [eavesdrop] destination_number(0792710365) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->eavesdrop] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [eavesdrop] destination_number(0792710365) =~ /^779$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->call_return] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [call_return] destination_number(0792710365) =~ /^\*69$|^869$|^lcr$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->del-group] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [del-group] destination_number(0792710365) =~ /^80(\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->add-group] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [add-group] destination_number(0792710365) =~ /^81(\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->call-group-simo] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [call-group-simo] destination_number(0792710365) =~ /^82(\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->call-group-order] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [call-group-order] destination_number(0792710365) =~ /^83(\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->extension-intercom] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [extension-intercom] destination_number(0792710365) =~ /^8(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->Local_Extension] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Local_Extension] destination_number(0792710365) =~ /^(10[01][0-9])$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->Local_Extension_Skinny] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Local_Extension_Skinny] destination_number(0792710365) =~ /^(11[01][0-9])$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->group_dial_sales] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [group_dial_sales] destination_number(0792710365) =~ /^2000$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->group_dial_support] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [group_dial_support] destination_number(0792710365) =~ /^2001$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->group_dial_billing] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [group_dial_billing] destination_number(0792710365) =~ /^2002$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->operator] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [operator] destination_number(0792710365) =~ /^(operator|0)$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->vmain] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [vmain] destination_number(0792710365) =~ /^vmain$|^4000$|^\*98$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->sip_uri] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [sip_uri] destination_number(0792710365) =~ /^sip:(.*)$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->nb_conferences] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [nb_conferences] destination_number(0792710365) =~ /^(30\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->wb_conferences] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [wb_conferences] destination_number(0792710365) =~ /^(31\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->uwb_conferences] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [uwb_conferences] destination_number(0792710365) =~ /^(32\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->cdquality_conferences] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [cdquality_conferences] destination_number(0792710365) =~ /^(33\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->cdquality_conferences] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [cdquality_conferences] destination_number(0792710365) =~ /^(35\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->freeswitch_public_conf_via_sip] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(0792710365) =~ /^9(888|8888|1616|3232)$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->mad_boss_intercom] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [mad_boss_intercom] destination_number(0792710365) =~ /^0911$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->mad_boss_intercom] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [mad_boss_intercom] destination_number(0792710365) =~ /^0912$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->mad_boss] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [mad_boss] destination_number(0792710365) =~ /^0913$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ivr_demo] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ivr_demo] destination_number(0792710365) =~ /^5000$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->dynamic_conference] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [dynamic_conference] destination_number(0792710365) =~ /^5001$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->rtp_multicast_page] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [rtp_multicast_page] destination_number(0792710365) =~ /^pagegroup$|^7243$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->park] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [park] destination_number(0792710365) =~ /^5900$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->unpark] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [unpark] destination_number(0792710365) =~ /^5901$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->valet_park] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [valet_park] destination_number(0792710365) =~ /^(6000)$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->valet_park] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [valet_park] destination_number(0792710365) =~ /^((?!6000)60\d{2})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->park] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [park] destination_number(0792710365) =~ /park\+(\d+)/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->unpark] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [unpark] destination_number(0792710365) =~ /^parking$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->park] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [park] destination_number(0792710365) =~ /callpark/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->unpark] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [unpark] destination_number(0792710365) =~ /pickup/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->wait] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [wait] destination_number(0792710365) =~ /^wait$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->fax_receive] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [fax_receive] destination_number(0792710365) =~ /^9178$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->fax_transmit] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [fax_transmit] destination_number(0792710365) =~ /^9179$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_180] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_180] destination_number(0792710365) =~ /^9180$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_183_uk_ring] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_183_uk_ring] destination_number(0792710365) =~ /^9181$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_183_music_ring] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_183_music_ring] destination_number(0792710365) =~ /^9182$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_post_answer_uk_ring] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(0792710365) =~ /^9183$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_post_answer_music] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_post_answer_music] destination_number(0792710365) =~ /^9184$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ClueCon] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ClueCon] destination_number(0792710365) =~ /^9191$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->show_info] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [show_info] destination_number(0792710365) =~ /^9192$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->video_record] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [video_record] destination_number(0792710365) =~ /^9193$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->video_playback] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [video_playback] destination_number(0792710365) =~ /^9194$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->delay_echo] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [delay_echo] destination_number(0792710365) =~ /^9195$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->echo] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [echo] destination_number(0792710365) =~ /^9196$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->milliwatt] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [milliwatt] destination_number(0792710365) =~ /^9197$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->tone_stream] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [tone_stream] destination_number(0792710365) =~ /^9198$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->zrtp_enrollement] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [zrtp_enrollement] destination_number(0792710365) =~ /^9787$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->hold_music] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [hold_music] destination_number(0792710365) =~ /^9664$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->laugh break] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [laugh break] destination_number(0792710365) =~ /^9386$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->Emergency Numbers] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [Emergency Numbers] ${toll_allow}(domestic,international,local) =~ /local/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Emergency Numbers] destination_number(0792710365) =~ /^[0-9][0-9][0-9]$/ match=any Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Emergency Numbers] destination_number(0792710365) =~ /^1414$/ match=any Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Emergency Numbers] destination_number(0792710365) =~ /^1415$/ match=any Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->101] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [101] destination_number(0792710365) =~ /^101$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->outbound calls] continue=false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [outbound calls] ${toll_allow}(domestic,international,local) =~ /local/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [outbound calls] destination_number(0792710365) =~ /^(\d{7,18})$/ break=on-false Dialplan: sofia/internal/1000@172.20.1.2 Action set(effective_caller_id_number=${outbound_caller_id_number}) Dialplan: sofia/internal/1000@172.20.1.2 Action set(effective_caller_id_name=${outbound_caller_id_name}) Dialplan: sofia/internal/1000@172.20.1.2 Action bridge(sofia/gateway/swisscom/0792710365) 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1000@172.20.1.2) State Change CS_ROUTING -> CS_EXECUTE 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1000@172.20.1.2) State ROUTING going to sleep 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_EXECUTE 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1000@172.20.1.2) State EXECUTE 2017-08-16 10:21:46.278084 [DEBUG] mod_sofia.c:196 sofia/internal/1000@172.20.1.2 SOFIA EXECUTE 2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1000@172.20.1.2 Standard EXECUTE EXECUTE sofia/internal/1000@172.20.1.2 set(open=true) 2017-08-16 10:21:46.278084 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@172.20.1.2 [open]=[true] EXECUTE sofia/internal/1000@172.20.1.2 set(rtp_secure_media=true) 2017-08-16 10:21:46.298081 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@172.20.1.2 [rtp_secure_media]=[true] EXECUTE sofia/internal/1000@172.20.1.2 hash(insert/172.20.1.2-spymap/1000/b4006ee6-826c-11e7-a743-f77b865a2304) EXECUTE sofia/internal/1000@172.20.1.2 hash(insert/172.20.1.2-last_dial/1000/0792710365) EXECUTE sofia/internal/1000@172.20.1.2 hash(insert/172.20.1.2-last_dial/global/b4006ee6-826c-11e7-a743-f77b865a2304) EXECUTE sofia/internal/1000@172.20.1.2 export(RFC2822_DATE=Wed, 16 Aug 2017 10:21:46 +0000) 2017-08-16 10:21:46.298081 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 16 Aug 2017 10:21:46 +0000] EXECUTE sofia/internal/1000@172.20.1.2 set(effective_caller_id_number=0000000000) 2017-08-16 10:21:46.298081 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@172.20.1.2 [effective_caller_id_number]=[0000000000] EXECUTE sofia/internal/1000@172.20.1.2 set(effective_caller_id_name=FreeSWITCH) 2017-08-16 10:21:46.298081 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@172.20.1.2 [effective_caller_id_name]=[FreeSWITCH] EXECUTE sofia/internal/1000@172.20.1.2 bridge(sofia/gateway/swisscom/0792710365) 2017-08-16 10:21:46.298081 [DEBUG] switch_channel.c:1247 sofia/internal/1000@172.20.1.2 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 16 Aug 2017 10:21:46 +0000] to event 2017-08-16 10:21:46.298081 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables 2017-08-16 10:21:46.298081 [NOTICE] switch_channel.c:1101 New Channel sofia/external/0792710365 [b503fd62-826c-11e7-a752-f77b865a2304] 2017-08-16 10:21:46.298081 [DEBUG] mod_sofia.c:4776 (sofia/external/0792710365) State Change CS_NEW -> CS_INIT 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_INIT 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:516 (sofia/external/0792710365) State INIT 2017-08-16 10:21:46.298081 [DEBUG] mod_sofia.c:88 sofia/external/0792710365 SOFIA INIT 2017-08-16 10:21:46.298081 [DEBUG] sofia_glue.c:1257 sofia/external/0792710365 sending invite version: 1.6.6 git df20d53 2017-02-03 08:57:21Z 64bit Local SDP: v=0 o=FreeSWITCH 1502860554 1502860555 IN IP4 192.168.1.34 s=FreeSWITCH c=IN IP4 192.168.1.34 t=0 0 m=audio 18352 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:40 sofia/external/0792710365 Standard INIT 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:48 (sofia/external/0792710365) State Change CS_INIT -> CS_ROUTING 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:516 (sofia/external/0792710365) State INIT going to sleep 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_ROUTING send 1162 bytes to udp/[192.168.1.1]:5060 at 10:21:46.316172: ------------------------------------------------------------------------ INVITE sip:0792710365@192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKvtmNZ6X89ce6e Max-Forwards: 69 From: "FreeSWITCH" ;tag=9KSaFK40ScZKK To: Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0 CSeq: 111103389 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~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: 244 X-Serialnumber: 000413910687 P-Key-Flags: resolution="31x13", keys="4" X-FS-Support: update_display,send_info 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:532 (sofia/external/0792710365) State ROUTING Remote-Party-ID: "FreeSWITCH" ;party=calling;screen=yes;privacy=off 2017-08-16 10:21:46.298081 [DEBUG] mod_sofia.c:141 sofia/external/0792710365 SOFIA ROUTING v=0 o=FreeSWITCH 1502860554 1502860555 IN IP4 192.168.1.34 s=FreeSWITCH 2017-08-16 10:21:46.298081 [DEBUG] switch_ivr_originate.c:67 (sofia/external/0792710365) State Change CS_ROUTING -> CS_CONSUME_MEDIA c=IN IP4 192.168.1.34 t=0 0 m=audio 18352 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:532 (sofia/external/0792710365) State ROUTING going to sleep a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_CONSUME_MEDIA ------------------------------------------------------------------------ 2017-08-16 10:21:46.298081 [DEBUG] sofia.c:6760 Channel sofia/external/0792710365 entering state [calling][0] 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:551 (sofia/external/0792710365) State CONSUME_MEDIA 2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:551 (sofia/external/0792710365) State CONSUME_MEDIA going to sleep recv 545 bytes from udp/[192.168.1.1]:5060 at 10:21:46.318456: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required From: "FreeSWITCH";tag=9KSaFK40ScZKK To: ;tag=1a74cb8-101a8c0-13c4-5506-27909-62e2f23d-27909 Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0 CSeq: 111103389 INVITE Proxy-Authenticate: Digest realm="epicentro.localrealm",nonce="1a74cb8b6b34",stale=true,algorithm=MD5,qop="auth" Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKvtmNZ6X89ce6e Supported: replaces,100rel User-Agent: Epicentro - YAPS ADB Stack Content-Length: 0 ------------------------------------------------------------------------ send 371 bytes to udp/[192.168.1.1]:5060 at 10:21:46.318930: ------------------------------------------------------------------------ ACK sip:0792710365@192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKvtmNZ6X89ce6e Max-Forwards: 69 From: "FreeSWITCH" ;tag=9KSaFK40ScZKK To: ;tag=1a74cb8-101a8c0-13c4-5506-27909-62e2f23d-27909 Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0 CSeq: 111103389 ACK Content-Length: 0 ------------------------------------------------------------------------ send 1417 bytes to udp/[192.168.1.1]:5060 at 10:21:46.319882: ------------------------------------------------------------------------ INVITE sip:0792710365@192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKX3De11ec7N4ra Max-Forwards: 69 From: "FreeSWITCH" ;tag=9KSaFK40ScZKK To: Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0 CSeq: 111103390 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~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="+41554405801", realm="epicentro.localrealm", nonce="1a74cb8b6b34", cnonce="jGONsv0PEjW4eQANuUKz0A", algorithm=MD5, uri="sip:0792710365@192.168.1.1", response="23d0951b79f809b591998f604559d66b", qop=auth, nc=00000001 Content-Type: application/sdp Content-Disposition: session Content-Length: 244 X-Serialnumber: 000413910687 P-Key-Flags: resolution="31x13", keys="4" X-FS-Support: update_display,send_info Remote-Party-ID: "FreeSWITCH" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1502860554 1502860555 IN IP4 192.168.1.34 s=FreeSWITCH c=IN IP4 192.168.1.34 t=0 0 m=audio 18352 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2017-08-16 10:21:46.318087 [DEBUG] sofia.c:6760 Channel sofia/external/0792710365 entering state [calling][0] recv 396 bytes from udp/[192.168.1.1]:5060 at 10:21:46.323141: ------------------------------------------------------------------------ SIP/2.0 100 Trying From: "FreeSWITCH";tag=9KSaFK40ScZKK To: Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0 CSeq: 111103390 INVITE Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKX3De11ec7N4ra Supported: replaces,100rel User-Agent: Epicentro - YAPS ADB Stack Contact: Content-Length: 0 ------------------------------------------------------------------------ recv 653 bytes from udp/[172.20.1.10]:5060 at 10:21:47.240083: ------------------------------------------------------------------------ REGISTER sip:172.20.1.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK3f04087a58cf37a5;rport Contact: ;expires=5;reg-id=123 Max-Forwards: 70 Authorization: Digest username="radio1", realm="172.20.1.2", nonce="217b51f8-7916-11e7-a5b7-f77b865a2304", uri="sip:172.20.1.2:5060", response="774654fc6d67c70ffeab568251d907bd", cnonce="7808959e7b58dca3", qop=auth, nc=00037692 To: From: ;tag=39215c725235cf15 Call-ID: 29f9e1db035f537e CSeq: 422201 REGISTER User-Agent: Ervocom SIP UA 1.2.3 (?/?) Supported: gruu, outbound, path Content-Length: 0 ------------------------------------------------------------------------ send 582 bytes to udp/[172.20.1.10]:5060 at 10:21:47.250086: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK3f04087a58cf37a5;rport=5060 From: ;tag=39215c725235cf15 To: ;tag=54673ZSZgZvpc Call-ID: 29f9e1db035f537e CSeq: 422201 REGISTER Contact: ;expires=5 Date: Wed, 16 Aug 2017 10:21:47 GMT User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ recv 653 bytes from udp/[172.20.1.10]:5060 at 10:21:51.761768: ------------------------------------------------------------------------ REGISTER sip:172.20.1.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK79bca150693f58b0;rport Contact: ;expires=5;reg-id=123 Max-Forwards: 70 Authorization: Digest username="radio1", realm="172.20.1.2", nonce="217b51f8-7916-11e7-a5b7-f77b865a2304", uri="sip:172.20.1.2:5060", response="a2ffa1b4fb24a7b0ba0bfc743b87b401", cnonce="0046856750a02932", qop=auth, nc=00037693 To: From: ;tag=39215c725235cf15 Call-ID: 29f9e1db035f537e CSeq: 422202 REGISTER User-Agent: Ervocom SIP UA 1.2.3 (?/?) Supported: gruu, outbound, path Content-Length: 0 ------------------------------------------------------------------------ send 582 bytes to udp/[172.20.1.10]:5060 at 10:21:51.771857: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK79bca150693f58b0;rport=5060 From: ;tag=39215c725235cf15 To: ;tag=6D005ta3D8j9Q Call-ID: 29f9e1db035f537e CSeq: 422202 REGISTER Contact: ;expires=5 Date: Wed, 16 Aug 2017 10:21:51 GMT User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ recv 654 bytes from udp/[192.168.1.1]:5060 at 10:21:52.603947: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress From: "FreeSWITCH";tag=9KSaFK40ScZKK To: ;tag=1a76240-101a8c0-13c4-5506-2790f-293f0fcb-2790f Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0 CSeq: 111103390 INVITE Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKX3De11ec7N4ra Supported: replaces,100rel User-Agent: Epicentro - YAPS ADB Stack Contact: Content-Type: application/sdp Content-Length: 164 v=0 o=root 3066086076 3066086076 IN IP4 192.168.1.1 s=session c=IN IP4 192.168.1.1 t=0 0 m=audio 4988 RTP/AVP 8 a=rtpmap:8 PCMA/8000 a=ptime:20 a=sendrecv ------------------------------------------------------------------------ 2017-08-16 10:21:52.598067 [DEBUG] sofia.c:6760 Channel sofia/external/0792710365 entering state [proceeding][183] 2017-08-16 10:21:52.598067 [DEBUG] sofia.c:6770 Remote SDP: v=0 o=root 3066086076 3066086076 IN IP4 192.168.1.1 s=session c=IN IP4 192.168.1.1 t=0 0 m=audio 4988 RTP/AVP 8 a=rtpmap:8 PCMA/8000 a=ptime:20 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:2906 Set Codec sofia/external/0792710365 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2017-08-16 10:21:52.598067 [DEBUG] switch_core_codec.c:111 sofia/external/0792710365 Original read codec set to PCMA:8 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4459 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event. 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4485 sofia/external/0792710365 Set 2833 dtmf send payload to 101 recv payload to 101 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/external/0792710365] 172.20.1.2 port 18352 -> 192.168.1.1 port 4988 codec: 8 ms: 20 2017-08-16 10:21:52.598067 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:6332 sofia/external/0792710365 Set 2833 dtmf send payload to 101 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:6339 sofia/external/0792710365 Set 2833 dtmf receive payload to 101 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:6362 sofia/external/0792710365 Set rtp dtmf delay to 40 2017-08-16 10:21:52.598067 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/0792710365! 2017-08-16 10:21:52.598067 [DEBUG] switch_channel.c:3468 (sofia/external/0792710365) Callstate Change DOWN -> EARLY 2017-08-16 10:21:52.598067 [INFO] switch_ivr_originate.c:3557 Sending early media 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1334 Found suite AES_CM_128_HMAC_SHA1_32 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1400 Set Remote Key [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1062 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_32 inline:IpqklgUbMZW+Ierls8+fQp5p7+GryytNsO5wehcm] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4197 Audio Codec Compare [PCMU:0:8000:10:64000:1] is saved as a near-match 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4197 Audio Codec Compare [PCMA:8:8000:10:64000:1] is saved as a near-match 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G726-32:99:8000:20:0:1]/[PCMU:0:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G726-32:99:8000:20:0:1]/[PCMA:8:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMU:0:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMA:8:8000:10:64000:1] 2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:10:64000:1] 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:10:64000:1] 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:4267 Substituting codec PCMU@20i@8000h@1c 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/1000@172.20.1.2 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2017-08-16 10:21:52.618075 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@172.20.1.2 Original read codec set to PCMU:0 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:4459 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event. 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:4485 sofia/internal/1000@172.20.1.2 Set 2833 dtmf send payload to 101 recv payload to 101 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/1000@172.20.1.2] 172.20.1.2 port 18182 -> 10.10.30.51 port 60010 codec: 0 ms: 20 2017-08-16 10:21:52.618075 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:6332 sofia/internal/1000@172.20.1.2 Set 2833 dtmf send payload to 101 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:6339 sofia/internal/1000@172.20.1.2 Set 2833 dtmf receive payload to 101 2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:6362 sofia/internal/1000@172.20.1.2 Set rtp dtmf delay to 40 2017-08-16 10:21:52.618075 [INFO] switch_rtp.c:3614 Activating Audio Secure RTP SEND 2017-08-16 10:21:52.618075 [DEBUG] switch_core_sqldb.c:2607 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_32 2017-08-16 10:21:52.618075 [INFO] switch_rtp.c:3592 Activating Audio Secure RTP RECV 2017-08-16 10:21:52.618075 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1000@172.20.1.2! 2017-08-16 10:21:52.618075 [DEBUG] switch_core_sqldb.c:2607 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_32 2017-08-16 10:21:52.618075 [DEBUG] switch_channel.c:3468 (sofia/internal/1000@172.20.1.2) Callstate Change RINGING -> EARLY 2017-08-16 10:21:52.618075 [DEBUG] mod_sofia.c:2330 Ring SDP: v=0 o=FreeSWITCH 1502860730 1502860731 IN IP4 172.20.1.2 s=FreeSWITCH c=IN IP4 172.20.1.2 t=0 0 m=audio 18182 RTP/SAVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:IpqklgUbMZW+Ierls8+fQp5p7+GryytNsO5wehcm 2017-08-16 10:21:52.618075 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/external/0792710365] send 1261 bytes to udp/[10.10.30.51]:5060 at 10:21:52.629113: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport=5060 From: "RBS Testing" ;tag=qcx12rgq6j To: ;tag=4UDF248UKp63g Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 323 Remote-Party-ID: "Outbound Call" ;party=calling;privacy=off;screen=no v=0 o=FreeSWITCH 1502860730 1502860731 IN IP4 172.20.1.2 s=FreeSWITCH c=IN IP4 172.20.1.2 t=0 0 m=audio 18182 RTP/SAVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:IpqklgUbMZW+Ierls8+fQp5p7+GryytNsO5wehcm m=audio 0 RTP/AVP 19 ------------------------------------------------------------------------ 2017-08-16 10:21:52.618075 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@172.20.1.2 entering state [early][183] 2017-08-16 10:21:52.618075 [DEBUG] switch_ivr_bridge.c:1591 (sofia/external/0792710365) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2017-08-16 10:21:52.618075 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_EXCHANGE_MEDIA 2017-08-16 10:21:52.618075 [DEBUG] switch_core_state_machine.c:542 (sofia/external/0792710365) State EXCHANGE_MEDIA 2017-08-16 10:21:52.618075 [DEBUG] mod_sofia.c:613 SOFIA EXCHANGE_MEDIA 2017-08-16 10:21:53.218075 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed. 2017-08-16 10:21:54.798069 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed. recv 420 bytes from udp/[192.168.1.1]:5060 at 10:21:54.861595: ------------------------------------------------------------------------ SIP/2.0 487 Request Terminated From: "FreeSWITCH";tag=9KSaFK40ScZKK To: ;tag=1a76240-101a8c0-13c4-5506-2790f-293f0fcb-2790f Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0 CSeq: 111103390 INVITE Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKX3De11ec7N4ra Supported: replaces,100rel User-Agent: Epicentro - YAPS ADB Stack Content-Length: 0 ------------------------------------------------------------------------ send 371 bytes to udp/[192.168.1.1]:5060 at 10:21:54.862166: ------------------------------------------------------------------------ ACK sip:0792710365@192.168.1.1 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKX3De11ec7N4ra Max-Forwards: 69 From: "FreeSWITCH" ;tag=9KSaFK40ScZKK To: ;tag=1a76240-101a8c0-13c4-5506-2790f-293f0fcb-2790f Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0 CSeq: 111103390 ACK Content-Length: 0 ------------------------------------------------------------------------ 2017-08-16 10:21:54.858092 [DEBUG] sofia.c:6760 Channel sofia/external/0792710365 entering state [terminated][487] 2017-08-16 10:21:54.858092 [NOTICE] sofia.c:7779 Hangup sofia/external/0792710365 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL] 2017-08-16 10:21:54.858092 [DEBUG] switch_ivr_bridge.c:705 sofia/external/0792710365 ending bridge by request from read function 2017-08-16 10:21:54.858092 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/external/0792710365] 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:542 (sofia/external/0792710365) State EXCHANGE_MEDIA going to sleep 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_HANGUP 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:739 (sofia/external/0792710365) Callstate Change EARLY -> HANGUP 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:741 (sofia/external/0792710365) State HANGUP 2017-08-16 10:21:54.858092 [DEBUG] mod_sofia.c:431 Channel sofia/external/0792710365 hanging up, cause: ORIGINATOR_CANCEL 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:60 sofia/external/0792710365 Standard HANGUP, cause: ORIGINATOR_CANCEL 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:741 (sofia/external/0792710365) State HANGUP going to sleep 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:508 (sofia/external/0792710365) State Change CS_HANGUP -> CS_REPORTING 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_REPORTING 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:827 (sofia/external/0792710365) State REPORTING 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:104 sofia/external/0792710365 Standard REPORTING, cause: ORIGINATOR_CANCEL 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:827 (sofia/external/0792710365) State REPORTING going to sleep 2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:499 (sofia/external/0792710365) State Change CS_REPORTING -> CS_DESTROY 2017-08-16 10:21:54.858092 [DEBUG] mod_uci_disco.cpp:516 (catch_hangup) Caught hangup 2017-08-16 10:21:54.858092 [DEBUG] switch_core_session.c:1646 Session 1961 (sofia/external/0792710365) Locked, Waiting on external entities 2017-08-16 10:21:54.858092 [DEBUG] mod_uci_disco.cpp:526 (catch_hangup) Hang-up from RBS Testing to 0792710365 found! 2017-08-16 10:21:54.858092 [DEBUG] mod_uci_sip.cpp:451 (catch_hangup) Caught hangup 2017-08-16 10:21:54.858092 [DEBUG] mod_uci_sip.cpp:461 (catch_hangup) Hang-up from 1000 to 0792710365 found! 2017-08-16 10:21:54.878116 [DEBUG] switch_ivr_bridge.c:699 sofia/external/0792710365 ending bridge by request from write function 2017-08-16 10:21:54.878116 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1000@172.20.1.2] 2017-08-16 10:21:54.878116 [NOTICE] switch_core_session.c:1664 Session 1961 (sofia/external/0792710365) Ended 2017-08-16 10:21:54.878116 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/0792710365 [CS_DESTROY] 2017-08-16 10:21:54.878116 [NOTICE] switch_core_state_machine.c:315 sofia/internal/1000@172.20.1.2 has executed the last dialplan instruction, hanging up. 2017-08-16 10:21:54.878116 [NOTICE] switch_core_state_machine.c:317 Hangup sofia/internal/1000@172.20.1.2 [CS_EXECUTE] [NORMAL_CLEARING] 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:630 (sofia/external/0792710365) Running State Change CS_DESTROY 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:640 (sofia/external/0792710365) State DESTROY 2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:341 sofia/external/0792710365 SOFIA DESTROY 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:111 sofia/external/0792710365 Standard DESTROY 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:640 (sofia/external/0792710365) State DESTROY going to sleep 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1000@172.20.1.2) State EXECUTE going to sleep 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_HANGUP 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1000@172.20.1.2) Callstate Change EARLY -> HANGUP 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@172.20.1.2) State HANGUP 2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:425 sofia/internal/1000@172.20.1.2 Overriding SIP cause 480 with 487 from the other leg 2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1000@172.20.1.2 hanging up, cause: NORMAL_CLEARING 2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:568 Responding to INVITE with: 487 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@172.20.1.2 Standard HANGUP, cause: NORMAL_CLEARING 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@172.20.1.2) State HANGUP going to sleep 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1000@172.20.1.2) State Change CS_HANGUP -> CS_REPORTING send 860 bytes to udp/[10.10.30.51]:5060 at 10:21:54.891735: ------------------------------------------------------------------------ SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport=5060 Max-Forwards: 70 From: "RBS Testing" ;tag=qcx12rgq6j To: ;tag=4UDF248UKp63g Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 Remote-Party-ID: "Outbound Call" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_REPORTING 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1000@172.20.1.2) State REPORTING 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1000@172.20.1.2 Standard REPORTING, cause: NORMAL_CLEARING 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1000@172.20.1.2) State REPORTING going to sleep 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1000@172.20.1.2) State Change CS_REPORTING -> CS_DESTROY 2017-08-16 10:21:54.878116 [DEBUG] mod_uci_disco.cpp:516 (catch_hangup) Caught hangup 2017-08-16 10:21:54.878116 [DEBUG] switch_core_session.c:1646 Session 1960 (sofia/internal/1000@172.20.1.2) Locked, Waiting on external entities 2017-08-16 10:21:54.878116 [DEBUG] mod_uci_disco.cpp:526 (catch_hangup) Hang-up from RBS Testing to 0792710365 found! 2017-08-16 10:21:54.878116 [DEBUG] mod_uci_sip.cpp:451 (catch_hangup) Caught hangup 2017-08-16 10:21:54.878116 [NOTICE] switch_core_session.c:1664 Session 1960 (sofia/internal/1000@172.20.1.2) Ended 2017-08-16 10:21:54.878116 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1000@172.20.1.2 [CS_DESTROY] 2017-08-16 10:21:54.878116 [DEBUG] mod_uci_sip.cpp:461 (catch_hangup) Hang-up from 1000 to 0792710365 found! 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1000@172.20.1.2) Running State Change CS_DESTROY 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@172.20.1.2) State DESTROY 2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:341 sofia/internal/1000@172.20.1.2 SOFIA DESTROY 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1000@172.20.1.2 Standard DESTROY 2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@172.20.1.2) State DESTROY going to sleep recv 413 bytes from udp/[10.10.30.51]:5060 at 10:21:54.923213: ------------------------------------------------------------------------ ACK sip:0792710365@172.20.1.2 SIP/2.0 Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport From: "RBS Testing" ;tag=qcx12rgq6j To: ;tag=4UDF248UKp63g Call-ID: 3135303238373836373632363431-00jupnh0yqw5 CSeq: 2 ACK Max-Forwards: 70 User-Agent: snomD375/8.9.3.15 Contact: ;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ recv 653 bytes from udp/[172.20.1.10]:5060 at 10:21:56.280080: ------------------------------------------------------------------------ REGISTER sip:172.20.1.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK5efb5e8d08a4dfff;rport Contact: ;expires=5;reg-id=123 Max-Forwards: 70 Authorization: Digest username="radio1", realm="172.20.1.2", nonce="217b51f8-7916-11e7-a5b7-f77b865a2304", uri="sip:172.20.1.2:5060", response="0d1ebe2c429cac82584a95c870b3f8df", cnonce="271e41a00d847bea", qop=auth, nc=00037694 To: From: ;tag=39215c725235cf15 Call-ID: 29f9e1db035f537e CSeq: 422203 REGISTER User-Agent: Ervocom SIP UA 1.2.3 (?/?) Supported: gruu, outbound, path Content-Length: 0 ------------------------------------------------------------------------ send 582 bytes to udp/[172.20.1.10]:5060 at 10:21:56.290110: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK5efb5e8d08a4dfff;rport=5060 From: ;tag=39215c725235cf15 To: ;tag=7pSS7NU6aH9UK Call-ID: 29f9e1db035f537e CSeq: 422203 REGISTER Contact: ;expires=5 Date: Wed, 16 Aug 2017 10:21:56 GMT User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ freeswitch@internal> send 861 bytes to udp/[192.168.1.1]:5060 at 10:21:58.084161: ------------------------------------------------------------------------ REGISTER sip:192.168.1.1;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKyc762vZF4ytBp Max-Forwards: 70 From: ;tag=S2U4S0UQ40HjQ To: Call-ID: cbd6523e-7915-11e7-a55a-f77b865a2304 CSeq: 111027789 REGISTER Contact: Expires: 60 User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Authorization: Digest username="+41554405801", realm="epicentro.localrealm", nonce="57d190b6b154", cnonce="oze44/O4EjW4eQANuUKz0A", algorithm=MD5, uri="sip:192.168.1.1;transport=udp", response="30092fd76126405eef9c08114a89e466", qop=auth, nc=000085cd Content-Length: 0 ------------------------------------------------------------------------ recv 516 bytes from udp/[192.168.1.1]:5060 at 10:21:58.087011: ------------------------------------------------------------------------ SIP/2.0 200 OK From: ;tag=S2U4S0UQ40HjQ To: ;tag=1a75850-101a8c0-13c4-5506-27914-17e340a7-27914 Call-ID: cbd6523e-7915-11e7-a55a-f77b865a2304 CSeq: 111027789 REGISTER Date: Wed, 16 Aug 2017 10:18:10 GMT User-Agent: Epicentro - YAPS ADB Stack Supported: replaces,100rel Contact: ;expires=60 Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKyc762vZF4ytBp Content-Length: 0 ------------------------------------------------------------------------