span style="color: cornflowerblue; font-weight: bold;"> dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.896298 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/242@customer.voip.voipprovider.it:5060 [dc36fb2f-8319-42ae-b432-260172d5156b]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.896298 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/242@customer.voip.voipprovider.it:5060) Running State Change CS_NEW (Cur 11 Tot 2619)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.896298 [DEBUG] sofia.c:9825 sofia/internal/242@customer.voip.voipprovider.it:5060 receiving invite from 10.137.2.11:12675 version: 1.6.16 -33-e6d643b 64bit
2021-05-10 11:51:22.896298 [DEBUG] sofia.c:9996 IP 10.137.2.11 Rejected by acl "domains". Falling back to Digest auth.
2021-05-10 11:51:22.896298 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [00privacy95@customer.voip.voipprovider.it] from ip 10.137.2.11
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.896298 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/242@customer.voip.voipprovider.it:5060) State NEW
2021-05-10 11:51:22.896298 [DEBUG] sofia.c:2334 detaching session dc36fb2f-8319-42ae-b432-260172d5156b
2021-05-10 11:51:22.916301 [DEBUG] sofia.c:2442 Re-attaching to session dc36fb2f-8319-42ae-b432-260172d5156b
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] sofia.c:9825 sofia/internal/242@customer.voip.voipprovider.it:5060 receiving invite from 10.137.2.11:12675 version: 1.6.16 -33-e6d643b 64bit
2021-05-10 11:51:22.916301 [DEBUG] sofia.c:9996 IP 10.137.2.11 Rejected by acl "domains". Falling back to Digest auth.
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] sofia.c:7048 Channel sofia/internal/242@customer.voip.voipprovider.it:5060 entering state [received][100]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] sofia.c:7058 Remote SDP:
dc36fb2f-8319-42ae-b432-260172d5156b v=0
dc36fb2f-8319-42ae-b432-260172d5156b o=- 22971 22971 IN IP4 10.137.2.11
dc36fb2f-8319-42ae-b432-260172d5156b s=SDP data
dc36fb2f-8319-42ae-b432-260172d5156b c=IN IP4 10.137.2.11
dc36fb2f-8319-42ae-b432-260172d5156b t=0 0
dc36fb2f-8319-42ae-b432-260172d5156b m=audio 12420 RTP/AVP 18 9 8 101
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:18 G729/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=fmtp:18 annexb=no
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:9 G722/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:8 PCMA/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:101 telephone-event/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=fmtp:101 0-15
dc36fb2f-8319-42ae-b432-260172d5156b a=ptime:20
dc36fb2f-8319-42ae-b432-260172d5156b
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] sofia.c:7450 (sofia/internal/242@customer.voip.voipprovider.it:5060) State Change CS_NEW -> CS_INIT
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/242@customer.voip.voipprovider.it:5060) Running State Change CS_INIT (Cur 11 Tot 2619)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/242@customer.voip.voipprovider.it:5060) State INIT
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_sofia.c:90 sofia/internal/242@customer.voip.voipprovider.it:5060 SOFIA INIT
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:40 sofia/internal/242@customer.voip.voipprovider.it:5060 Standard INIT
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/242@customer.voip.voipprovider.it:5060) State Change CS_INIT -> CS_ROUTING
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/242@customer.voip.voipprovider.it:5060) State INIT going to sleep
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/242@customer.voip.voipprovider.it:5060) Running State Change CS_ROUTING (Cur 11 Tot 2619)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_channel.c:2249 (sofia/internal/242@customer.voip.voipprovider.it:5060) Callstate Change DOWN -> RINGING
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/242@customer.voip.voipprovider.it:5060) State ROUTING
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_sofia.c:143 sofia/internal/242@customer.voip.voipprovider.it:5060 SOFIA ROUTING
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:236 sofia/internal/242@customer.voip.voipprovider.it:5060 Standard ROUTING
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [INFO] mod_dialplan_xml.c:637 Processing 242 <242>->00privacy95 in context customer.voip.voipprovider.it
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 parsing [customer.voip.voipprovider.it->set_callee_and_caller_name] continue=true
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [set_callee_and_caller_name] destination_number(00privacy95) =~ /.*/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(domain_name=customer.voip.voipprovider.it)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action curl(http://${3rdparty_address}:${3rdparty_port}/contacts/lookup?number=${destination_number}&domain=${domain_name})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action export(callee_id_name=${curl_response_data})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action curl(http://${3rdparty_address}:${3rdparty_port}/contacts/lookup?number=${caller_id_number}&domain=${domain_name}&username=${destination_number}&user_context=false)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(effective_caller_id_name=${curl_response_data})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(caller_id_name=${effective_caller_id_name})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 parsing [customer.voip.voipprovider.it->extension_queue] continue=false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [extension_queue] destination_number(00privacy95) =~ /^\*800(.*)$/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 parsing [customer.voip.voipprovider.it->user_exists] continue=true
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Absolute Condition [user_exists]
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(user_exists=false)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [user_exists]=[false]
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 parsing [customer.voip.voipprovider.it->call-direction] continue=true
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 ANTI-Action set(call_direction=local)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [call-direction] ${user_exists}(false) =~ /^false$/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [call-direction] destination_number(00privacy95) =~ /^\d{7,20}$/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(call_direction=outbound)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 parsing [customer.voip.voipprovider.it->variables] continue=true
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Absolute Condition [variables]
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action export(origination_callee_id_name=${destination_number})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 parsing [customer.voip.voipprovider.it->user_record] continue=true
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Absolute Condition [user_record]
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(user_record=)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [user_record]=[UNDEF]
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(from_user_exists=true)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [from_user_exists]=[true]
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(from_user_record=)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [from_user_record]=[UNDEF]
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 parsing [customer.voip.voipprovider.it->07PRIVACY8.0d] continue=false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [07PRIVACY8.0d] destination_number(00privacy95) =~ /^0(\d+)$/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [07PRIVACY8.0d] ${dialed_extension}() =~ /^$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [07PRIVACY8.0d] destination_number(00privacy95) =~ /^0(\d+)$/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (FAIL) [07PRIVACY8.0d] ${outbound_caller_id_number}() =~ /^07PRIV4(.*)$/ break=never
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 ANTI-Action set(outbound_caller_id_number=07PRIVACY3)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Regex (PASS) [07PRIVACY8.0d] destination_number(00privacy95) =~ /^0(\d+)$/ break=on-false
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(sip_h_X-accountcode=${accountcode})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(sip_h_X-Tag=)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(call_direction=outbound)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(continue_on_fail=NORMAL_TEMPORARY_FAILURE,NETWORK_OUT_OF_ORDER,FACILITY_REJECTED,NORMAL_CIRCUIT_CONGESTION,SWITCH_CONGESTION,INVALID_GATEWAY,GATEWAY_DOWN)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(hangup_after_bridge=true)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(effective_caller_id_name=${outbound_caller_id_number})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(effective_caller_id_number=${outbound_caller_id_number})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(inherit_codec=true)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(continue_on_fail=true)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(outbound_prefix=0) INLINE
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(outbound_prefix=0)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [outbound_prefix]=[0]
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action set(bypass_media=${bypass_media_ext_enable})
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action lua(app.lua pre_bridge_actions outbound_route)
dc36fb2f-8319-42ae-b432-260172d5156b Dialplan: sofia/internal/242@customer.voip.voipprovider.it:5060 Action bridge(sofia/gateway/365c2d2c-6a37-4759-bc24-41492d0ca6b6/0privacy95)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/242@customer.voip.voipprovider.it:5060) State Change CS_ROUTING -> CS_EXECUTE
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/242@customer.voip.voipprovider.it:5060) State ROUTING going to sleep
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/242@customer.voip.voipprovider.it:5060) Running State Change CS_EXECUTE (Cur 11 Tot 2619)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/242@customer.voip.voipprovider.it:5060) State EXECUTE
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_sofia.c:198 sofia/internal/242@customer.voip.voipprovider.it:5060 SOFIA EXECUTE
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] switch_core_state_machine.c:328 sofia/internal/242@customer.voip.voipprovider.it:5060 Standard EXECUTE
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(domain_name=customer.voip.voipprovider.it)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.916301 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [domain_name]=[customer.voip.voipprovider.it]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 curl(http://localhost:9000/contacts/lookup?number=00privacy95&domain=customer.voip.voipprovider.it)
2021-05-10 11:51:22.916301 [DEBUG] mod_curl.c:182 method: get, url: http://localhost:9000/contacts/lookup?number=00privacy95&domain=customer.voip.voipprovider.it, content-type: (null)
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 export(callee_id_name=00privacy95)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [callee_id_name]=[00privacy95]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 curl(http://localhost:9000/contacts/lookup?number=242&domain=customer.voip.voipprovider.it&username=00privacy95&user_context=false)
2021-05-10 11:51:22.936300 [DEBUG] mod_curl.c:182 method: get, url: http://localhost:9000/contacts/lookup?number=242&domain=customer.voip.voipprovider.it&username=00privacy95&user_context=false, content-type: (null)
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(effective_caller_id_name=Amministrazione 2 )
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [effective_caller_id_name]=[Amministrazione 2 ]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(caller_id_name=Amministrazione 2 )
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [caller_id_name]=[Amministrazione 2 ]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(call_direction=local)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [call_direction]=[local]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(call_direction=outbound)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [call_direction]=[outbound]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 export(origination_callee_id_name=00privacy95)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[00privacy95]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(RFC2822_DATE=Mon, 10 May 2021 11:51:22 +0200)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [RFC2822_DATE]=[Mon, 10 May 2021 11:51:22 +0200]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(outbound_caller_id_number=07PRIVACY3)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [outbound_caller_id_number]=[07PRIVACY3]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(sip_h_X-accountcode=)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [sip_h_X-accountcode]=[UNDEF]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(sip_h_X-Tag=)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [sip_h_X-Tag]=[UNDEF]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(call_direction=outbound)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [call_direction]=[outbound]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(continue_on_fail=NORMAL_TEMPORARY_FAILURE,NETWORK_OUT_OF_ORDER,FACILITY_REJECTED,NORMAL_CIRCUIT_CONGESTION,SWITCH_CONGESTION,INVALID_GATEWAY,GATEWAY_DOWN)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [continue_on_fail]=[NORMAL_TEMPORARY_FAILURE,NETWORK_OUT_OF_ORDER,FACILITY_REJECTED,NORMAL_CIRCUIT_CONGESTION,SWITCH_CONGESTION,INVALID_GATEWAY,GATEWAY_DOWN]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(hangup_after_bridge=true)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [hangup_after_bridge]=[true]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(effective_caller_id_name=07PRIVACY3)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [effective_caller_id_name]=[07PRIVACY3]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(effective_caller_id_number=07PRIVACY3)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [effective_caller_id_number]=[07PRIVACY3]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(inherit_codec=true)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [inherit_codec]=[true]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(continue_on_fail=true)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [continue_on_fail]=[true]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 set(bypass_media=false)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] mod_dptools.c:1530 SET sofia/internal/242@customer.voip.voipprovider.it:5060 [bypass_media]=[false]
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 lua(app.lua pre_bridge_actions outbound_route)
2021-05-10 11:51:22.936300 [INFO] switch_cpp.cpp:1365 [pre_bridge_actions] bridge_destination: outbound_route
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(disable_q850_reason, true)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] switch_cpp.cpp:1112 sofia/internal/242@customer.voip.voipprovider.it:5060 destroy/unlink session from object
dc36fb2f-8319-42ae-b432-260172d5156b EXECUTE sofia/internal/242@customer.voip.voipprovider.it:5060 bridge(sofia/gateway/365c2d2c-6a37-4759-bc24-41492d0ca6b6/0privacy95)
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] switch_channel.c:1250 sofia/internal/242@customer.voip.voipprovider.it:5060 EXPORTING[export_vars] [domain_name]=[customer.voip.voipprovider.it] to event
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] switch_channel.c:1250 sofia/internal/242@customer.voip.voipprovider.it:5060 EXPORTING[export_vars] [callee_id_name]=[00privacy95] to event
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] switch_channel.c:1250 sofia/internal/242@customer.voip.voipprovider.it:5060 EXPORTING[export_vars] [origination_callee_id_name]=[00privacy95] to event
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:22.936300 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [NOTICE] switch_channel.c:1104 New Channel sofia/external/0privacy95 [54ae949c-851e-460b-b127-8370603b5c25]
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] mod_sofia.c:4818 (sofia/external/0privacy95) State Change CS_NEW -> CS_INIT
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0privacy95) Running State Change CS_INIT (Cur 12 Tot 2620)
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:627 (sofia/external/0privacy95) State INIT
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] mod_sofia.c:90 sofia/external/0privacy95 SOFIA INIT
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] sofia_glue.c:1295 sofia/external/0privacy95 sending invite version: 1.6.16 -33-e6d643b 64bit
54ae949c-851e-460b-b127-8370603b5c25 Local SDP:
54ae949c-851e-460b-b127-8370603b5c25 v=0
54ae949c-851e-460b-b127-8370603b5c25 o=FreeSWITCH 1620609016 1620609017 IN IP4 185.X.Y.Z
54ae949c-851e-460b-b127-8370603b5c25 s=FreeSWITCH
54ae949c-851e-460b-b127-8370603b5c25 c=IN IP4 185.X.Y.Z
54ae949c-851e-460b-b127-8370603b5c25 t=0 0
54ae949c-851e-460b-b127-8370603b5c25 m=audio 31266 RTP/AVP 8 101 13
54ae949c-851e-460b-b127-8370603b5c25 a=rtpmap:8 PCMA/8000
54ae949c-851e-460b-b127-8370603b5c25 a=rtpmap:101 telephone-event/8000
54ae949c-851e-460b-b127-8370603b5c25 a=fmtp:101 0-16
54ae949c-851e-460b-b127-8370603b5c25 a=rtpmap:13 CN/8000
54ae949c-851e-460b-b127-8370603b5c25 a=ptime:20
54ae949c-851e-460b-b127-8370603b5c25 a=sendrecv
54ae949c-851e-460b-b127-8370603b5c25
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:40 sofia/external/0privacy95 Standard INIT
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:48 (sofia/external/0privacy95) State Change CS_INIT -> CS_ROUTING
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:627 (sofia/external/0privacy95) State INIT going to sleep
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0privacy95) Running State Change CS_ROUTING (Cur 12 Tot 2620)
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] sofia.c:7048 Channel sofia/external/0privacy95 entering state [calling][0]
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:643 (sofia/external/0privacy95) State ROUTING
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] mod_sofia.c:143 sofia/external/0privacy95 SOFIA ROUTING
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_ivr_originate.c:67 (sofia/external/0privacy95) State Change CS_ROUTING -> CS_CONSUME_MEDIA
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:643 (sofia/external/0privacy95) State ROUTING going to sleep
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0privacy95) Running State Change CS_CONSUME_MEDIA (Cur 12 Tot 2620)
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:662 (sofia/external/0privacy95) State CONSUME_MEDIA
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.936300 [DEBUG] switch_core_state_machine.c:662 (sofia/external/0privacy95) State CONSUME_MEDIA going to sleep
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:22.956300 [DEBUG] sofia.c:7048 Channel sofia/external/0privacy95 entering state [calling][0]
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] sofia.c:7048 Channel sofia/external/0privacy95 entering state [proceeding][183]
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] sofia.c:7058 Remote SDP:
54ae949c-851e-460b-b127-8370603b5c25 v=0
54ae949c-851e-460b-b127-8370603b5c25 o=PVG 460363871 642359767 IN IP4 77.J.K.W
54ae949c-851e-460b-b127-8370603b5c25 s=-
54ae949c-851e-460b-b127-8370603b5c25 p=+1 6135555555
54ae949c-851e-460b-b127-8370603b5c25 c=IN IP4 77.J.K.W
54ae949c-851e-460b-b127-8370603b5c25 t=0 0
54ae949c-851e-460b-b127-8370603b5c25 a=sqn: 0
54ae949c-851e-460b-b127-8370603b5c25 a=cdsc: 1 image udptl t38
54ae949c-851e-460b-b127-8370603b5c25 a=cpar: a=T38FaxVersion:0
54ae949c-851e-460b-b127-8370603b5c25 a=cpar: a=T38FaxUdpEC:t38UDPRedundancy
54ae949c-851e-460b-b127-8370603b5c25 m=audio 44384 RTP/AVP 8 101
54ae949c-851e-460b-b127-8370603b5c25 a=rtpmap:101 telephone-event/8000
54ae949c-851e-460b-b127-8370603b5c25 a=fmtp:101 0-15
54ae949c-851e-460b-b127-8370603b5c25 a=ptime:20
54ae949c-851e-460b-b127-8370603b5c25
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_core_media.c:3049 Set Codec sofia/external/0privacy95 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_core_codec.c:111 sofia/external/0privacy95 Original read codec set to PCMA:8
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_core_media.c:4747 sofia/external/0privacy95 Set 2833 dtmf send payload to 101 recv payload to 101
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/external/0privacy95] 185.X.Y.Z port 31266 -> 77.J.K.W port 44384 codec: 8 ms: 20
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:23.156301 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:7109 sofia/external/0privacy95 Set 2833 dtmf send payload to 101
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:7116 sofia/external/0privacy95 Set 2833 dtmf receive payload to 101
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:7139 sofia/external/0privacy95 Set rtp dtmf delay to 40
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:24.176307 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/0privacy95!
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_ivr_originate.c:410 Setting codec string on sofia/internal/242@customer.voip.voipprovider.it:5060 to PCMA@8000h@20i
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [INFO] switch_ivr_originate.c:3639 Sending early media
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:3049 Set Codec sofia/internal/242@customer.voip.voipprovider.it:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_codec.c:111 sofia/internal/242@customer.voip.voipprovider.it:5060 Original read codec set to PCMA:8
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:24.176307 [DEBUG] switch_channel.c:3473 (sofia/external/0privacy95) Callstate Change DOWN -> EARLY
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:4747 sofia/internal/242@customer.voip.voipprovider.it:5060 Set 2833 dtmf send payload to 101 recv payload to 101
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_core_media.c:6803 AUDIO RTP [sofia/internal/242@customer.voip.voipprovider.it:5060] 10.137.0.1 port 18642 -> 10.137.2.11 port 12420 codec: 8 ms: 20
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:24.176307 [DEBUG] switch_rtp.c:4095 Starting timer [soft] 160 bytes per 20ms
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [DEBUG] switch_core_media.c:7109 sofia/internal/242@customer.voip.voipprovider.it:5060 Set 2833 dtmf send payload to 101
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [DEBUG] switch_core_media.c:7116 sofia/internal/242@customer.voip.voipprovider.it:5060 Set 2833 dtmf receive payload to 101
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [DEBUG] switch_core_media.c:7139 sofia/internal/242@customer.voip.voipprovider.it:5060 Set rtp dtmf delay to 40
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/242@customer.voip.voipprovider.it:5060!
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [DEBUG] switch_channel.c:3473 (sofia/internal/242@customer.voip.voipprovider.it:5060) Callstate Change RINGING -> EARLY
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/internal/242@customer.voip.voipprovider.it:5060.
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [DEBUG] mod_sofia.c:2363 Ring SDP:
dc36fb2f-8319-42ae-b432-260172d5156b v=0
dc36fb2f-8319-42ae-b432-260172d5156b o=FreeSWITCH 1620621643 1620621644 IN IP4 10.137.0.1
dc36fb2f-8319-42ae-b432-260172d5156b s=FreeSWITCH
dc36fb2f-8319-42ae-b432-260172d5156b c=IN IP4 10.137.0.1
dc36fb2f-8319-42ae-b432-260172d5156b t=0 0
dc36fb2f-8319-42ae-b432-260172d5156b m=audio 18642 RTP/AVP 8 101
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:8 PCMA/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:101 telephone-event/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=fmtp:101 0-16
dc36fb2f-8319-42ae-b432-260172d5156b a=ptime:20
dc36fb2f-8319-42ae-b432-260172d5156b a=sendrecv
dc36fb2f-8319-42ae-b432-260172d5156b
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/external/0privacy95]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:25.176307 [DEBUG] sofia.c:7048 Channel sofia/internal/242@customer.voip.voipprovider.it:5060 entering state [early][183]
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:25.176307 [DEBUG] switch_ivr_bridge.c:1574 (sofia/external/0privacy95) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:25.176307 [DEBUG] switch_core_state_machine.c:584 (sofia/external/0privacy95) Running State Change CS_EXCHANGE_MEDIA (Cur 14 Tot 2622)
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:25.176307 [DEBUG] switch_core_state_machine.c:653 (sofia/external/0privacy95) State EXCHANGE_MEDIA
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:25.176307 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
b71bc5ca-ade8-4845-9f8f-bff70d08f61d 2021-05-10 11:51:25.196300 [DEBUG] switch_rtp.c:7228 Correct audio ip/port confirmed.
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:28.336298 [DEBUG] sofia.c:7048 Channel sofia/external/0privacy95 entering state [completing][200]
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:28.336298 [DEBUG] sofia.c:7055 Duplicate SDP
54ae949c-851e-460b-b127-8370603b5c25 v=0
54ae949c-851e-460b-b127-8370603b5c25 o=PVG 460363871 642359767 IN IP4 77.J.K.W
54ae949c-851e-460b-b127-8370603b5c25 s=-
54ae949c-851e-460b-b127-8370603b5c25 p=+1 6135555555
54ae949c-851e-460b-b127-8370603b5c25 c=IN IP4 77.J.K.W
54ae949c-851e-460b-b127-8370603b5c25 t=0 0
54ae949c-851e-460b-b127-8370603b5c25 a=sqn: 0
54ae949c-851e-460b-b127-8370603b5c25 a=cdsc: 1 image udptl t38
54ae949c-851e-460b-b127-8370603b5c25 a=cpar: a=T38FaxVersion:0
54ae949c-851e-460b-b127-8370603b5c25 a=cpar: a=T38FaxUdpEC:t38UDPRedundancy
54ae949c-851e-460b-b127-8370603b5c25 m=audio 44384 RTP/AVP 8 101
54ae949c-851e-460b-b127-8370603b5c25 a=rtpmap:101 telephone-event/8000
54ae949c-851e-460b-b127-8370603b5c25 a=fmtp:101 0-15
54ae949c-851e-460b-b127-8370603b5c25 a=ptime:20
54ae949c-851e-460b-b127-8370603b5c25
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:28.356299 [DEBUG] sofia.c:7048 Channel sofia/external/0privacy95 entering state [ready][200]
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:28.356299 [NOTICE] sofia.c:8108 Channel [sofia/external/0privacy95] has been answered
54ae949c-851e-460b-b127-8370603b5c25 2021-05-10 11:51:28.356299 [DEBUG] switch_channel.c:3772 (sofia/external/0privacy95) Callstate Change EARLY -> ACTIVE
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.376300 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/internal/242@customer.voip.voipprovider.it:5060.
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.376300 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/242@customer.voip.voipprovider.it:5060:
dc36fb2f-8319-42ae-b432-260172d5156b v=0
dc36fb2f-8319-42ae-b432-260172d5156b o=FreeSWITCH 1620621643 1620621645 IN IP4 10.137.0.1
dc36fb2f-8319-42ae-b432-260172d5156b s=FreeSWITCH
dc36fb2f-8319-42ae-b432-260172d5156b c=IN IP4 10.137.0.1
dc36fb2f-8319-42ae-b432-260172d5156b t=0 0
dc36fb2f-8319-42ae-b432-260172d5156b m=audio 18642 RTP/AVP 8 101
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:8 PCMA/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:101 telephone-event/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=fmtp:101 0-16
dc36fb2f-8319-42ae-b432-260172d5156b a=ptime:20
dc36fb2f-8319-42ae-b432-260172d5156b a=sendrecv
dc36fb2f-8319-42ae-b432-260172d5156b
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.376300 [NOTICE] switch_ivr_bridge.c:598 Channel [sofia/internal/242@customer.voip.voipprovider.it:5060] has been answered
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.376300 [DEBUG] switch_channel.c:3772 (sofia/internal/242@customer.voip.voipprovider.it:5060) Callstate Change EARLY -> ACTIVE
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.376300 [DEBUG] sofia.c:7048 Channel sofia/internal/242@customer.voip.voipprovider.it:5060 entering state [completed][200]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.396298 [DEBUG] sofia.c:7048 Channel sofia/internal/242@customer.voip.voipprovider.it:5060 entering state [completed][0]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.396298 [DEBUG] sofia.c:7048 Channel sofia/internal/242@customer.voip.voipprovider.it:5060 entering state [ready][200]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] sofia.c:7048 Channel sofia/internal/242@customer.voip.voipprovider.it:5060 entering state [ready][200]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] sofia.c:7058 Remote SDP:
dc36fb2f-8319-42ae-b432-260172d5156b v=0
dc36fb2f-8319-42ae-b432-260172d5156b o=- 22971 22972 IN IP4 10.137.2.11
dc36fb2f-8319-42ae-b432-260172d5156b s=SDP data
dc36fb2f-8319-42ae-b432-260172d5156b c=IN IP4 10.137.2.11
dc36fb2f-8319-42ae-b432-260172d5156b t=0 0
dc36fb2f-8319-42ae-b432-260172d5156b m=audio 12420 RTP/AVP 8 101
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:8 PCMA/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:101 telephone-event/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=fmtp:101 0-15
dc36fb2f-8319-42ae-b432-260172d5156b a=ptime:20
dc36fb2f-8319-42ae-b432-260172d5156b
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] switch_core_media.c:4747 sofia/internal/242@customer.voip.voipprovider.it:5060 Set 2833 dtmf send payload to 101 recv payload to 101
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] sofia.c:8010 Processing updated SDP
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.416299 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/internal/242@customer.voip.voipprovider.it:5060.
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] sofia.c:7048 Channel sofia/internal/242@customer.voip.voipprovider.it:5060 entering state [calling][0]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] sofia.c:7048 Channel sofia/internal/242@customer.voip.voipprovider.it:5060 entering state [ready][200]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] sofia.c:7058 Remote SDP:
dc36fb2f-8319-42ae-b432-260172d5156b v=0
dc36fb2f-8319-42ae-b432-260172d5156b o=- 22971 22973 IN IP4 10.137.2.11
dc36fb2f-8319-42ae-b432-260172d5156b s=SDP data
dc36fb2f-8319-42ae-b432-260172d5156b c=IN IP4 10.137.2.11
dc36fb2f-8319-42ae-b432-260172d5156b t=0 0
dc36fb2f-8319-42ae-b432-260172d5156b m=audio 12420 RTP/AVP 8 101
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:8 PCMA/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=rtpmap:101 telephone-event/8000
dc36fb2f-8319-42ae-b432-260172d5156b a=fmtp:101 0-15
dc36fb2f-8319-42ae-b432-260172d5156b a=ptime:20
dc36fb2f-8319-42ae-b432-260172d5156b
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] switch_core_media.c:4429 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] switch_core_media.c:4484 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] switch_core_media.c:4345 Set telephone-event payload to 101@8000
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] switch_core_media.c:4688 Set telephone-event payload to 101@8000
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] switch_core_media.c:4747 sofia/internal/242@customer.voip.voipprovider.it:5060 Set 2833 dtmf send payload to 101 recv payload to 101
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] sofia.c:8010 Processing updated SDP
dc36fb2f-8319-42ae-b432-260172d5156b 2021-05-10 11:51:28.436298 [DEBUG] switch_core_media.c:6786 Audio params are unchanged for sofia/internal/242@customer.voip.voipprovider.it:5060.