/101@MY.DOMAIN.NAME.COM Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action export(dialed_extension=${destination_number}) INLINE
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM export(dialed_extension=102)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [dialed_extension]=[102]
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Regex (PASS) [local_extension] () =~ // break=on-false
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(hangup_after_bridge=true)
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(continue_on_fail=true)
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(api_hangup_hook=lua app.lua hangup)
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action export(domain_name=${domain_name})
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action bridge(user/${destination_number}@${domain_name})
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action lua(app.lua failure_handler)
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM parsing [MY.DOMAIN.NAME.COM->voicemail] continue=false
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(record_append=false) INLINE
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(record_append=false)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [record_append]=[false]
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(voicemail_action=save) INLINE
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(voicemail_action=save)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [voicemail_action]=[save]
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(voicemail_id=${destination_number}) INLINE
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(voicemail_id=102)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [voicemail_id]=[102]
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action set(voicemail_profile=default) INLINE
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(voicemail_profile=default)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [voicemail_profile]=[default]
3ec21880-128b-42ad-bb7c-3c4033f0919e Dialplan: sofia/internal/101@MY.DOMAIN.NAME.COM Action lua(app.lua voicemail)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/101@MY.DOMAIN.NAME.COM) State Change CS_ROUTING -> CS_EXECUTE
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/101@MY.DOMAIN.NAME.COM) State ROUTING going to sleep
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/101@MY.DOMAIN.NAME.COM) Running State Change CS_EXECUTE (Cur 1 Tot 8)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/101@MY.DOMAIN.NAME.COM) State EXECUTE
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_sofia.c:209 sofia/internal/101@MY.DOMAIN.NAME.COM SOFIA EXECUTE
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:329 sofia/internal/101@MY.DOMAIN.NAME.COM Standard EXECUTE
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(caller_id_number=101)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [caller_id_number]=[101]
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(RFC2822_DATE=Sun, 24 May 2020 07:54:34 +0000)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [RFC2822_DATE]=[Sun, 24 May 2020 07:54:34 +0000]
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM export(origination_callee_id_name=102)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [origination_callee_id_name]=[102]
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM hash(insert/MY.DOMAIN.NAME.COM-last_dial/101/102)
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM eval(not_secure)
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM limit(hash MY.DOMAIN.NAME.COM 102 5 error/user_busy)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_limit.c:124 incr called: MY.DOMAIN.NAME.COM_102 max:5, interval:0
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_hash.c:196 Usage for MY.DOMAIN.NAME.COM_102 is now 1/5
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(hangup_after_bridge=true)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [hangup_after_bridge]=[true]
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(continue_on_fail=true)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [continue_on_fail]=[true]
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM hash(insert/MY.DOMAIN.NAME.COM-call_return/102/101)
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM hash(insert/MY.DOMAIN.NAME.COM-last_dial_ext/102/3ec21880-128b-42ad-bb7c-3c4033f0919e)
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(called_party_call_group=)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [called_party_call_group]=[UNDEF]
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM hash(insert/MY.DOMAIN.NAME.COM-last_dial//3ec21880-128b-42ad-bb7c-3c4033f0919e)
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM set(api_hangup_hook=lua app.lua hangup)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] mod_dptools.c:1672 SET sofia/internal/101@MY.DOMAIN.NAME.COM [api_hangup_hook]=[lua app.lua hangup]
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM export(domain_name=MY.DOMAIN.NAME.COM)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [domain_name]=[MY.DOMAIN.NAME.COM]
3ec21880-128b-42ad-bb7c-3c4033f0919e EXECUTE [depth=0] sofia/internal/101@MY.DOMAIN.NAME.COM bridge(user/102@MY.DOMAIN.NAME.COM)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [domain_name]=[MY.DOMAIN.NAME.COM] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [call_direction]=[local] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [dialed_extension]=[102] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [origination_callee_id_name]=[102] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [domain_name]=[MY.DOMAIN.NAME.COM] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [domain_name]=[MY.DOMAIN.NAME.COM] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [call_direction]=[local] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [dialed_extension]=[102] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [origination_callee_id_name]=[102] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_channel.c:1264 sofia/internal/101@MY.DOMAIN.NAME.COM EXPORTING[export_vars] [domain_name]=[MY.DOMAIN.NAME.COM] to event
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.212100 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/102@192.168.1.42:33795 [b6a7a9a2-3558-444e-bca8-659032801bc5]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] mod_sofia.c:5089 (sofia/internal/102@192.168.1.42:33795) State Change CS_NEW -> CS_INIT
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_INIT (Cur 2 Tot 9)
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/102@192.168.1.42:33795) State INIT
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] mod_sofia.c:93 sofia/internal/102@192.168.1.42:33795 SOFIA INIT
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] sofia_glue.c:1587 sip:102@50.75.94.242:33795;rinstance=3d6512 Setting proxy route to sofia/internal/102@192.168.1.42:33795
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] sofia_glue.c:1618 sofia/internal/102@192.168.1.42:33795 sending invite version: 1.10.3 -release-15-129de34d84 64bit
b6a7a9a2-3558-444e-bca8-659032801bc5 Local SDP:
b6a7a9a2-3558-444e-bca8-659032801bc5 v=0
b6a7a9a2-3558-444e-bca8-659032801bc5 o=FreeSWITCH 1590289280 1590289281 IN IP4 MY.DOMAIN.NAME.COM
b6a7a9a2-3558-444e-bca8-659032801bc5 s=FreeSWITCH
b6a7a9a2-3558-444e-bca8-659032801bc5 c=IN IP4 MY.DOMAIN.NAME.COM
b6a7a9a2-3558-444e-bca8-659032801bc5 t=0 0
b6a7a9a2-3558-444e-bca8-659032801bc5 m=audio 17594 RTP/AVP 0 9 8 101 13
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:0 PCMU/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:9 G722/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:8 PCMA/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:101 telephone-event/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=fmtp:101 0-16
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:13 CN/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=ptime:20
b6a7a9a2-3558-444e-bca8-659032801bc5 a=sendrecv
b6a7a9a2-3558-444e-bca8-659032801bc5
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:40 sofia/internal/102@192.168.1.42:33795 Standard INIT
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/102@192.168.1.42:33795) State Change CS_INIT -> CS_ROUTING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/102@192.168.1.42:33795) State INIT going to sleep
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_ROUTING (Cur 2 Tot 9)
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [calling][0]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/102@192.168.1.42:33795) State ROUTING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] mod_sofia.c:154 sofia/internal/102@192.168.1.42:33795 SOFIA ROUTING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/102@192.168.1.42:33795) State Change CS_ROUTING -> CS_CONSUME_MEDIA
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/102@192.168.1.42:33795) State ROUTING going to sleep
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 9)
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/102@192.168.1.42:33795) State CONSUME_MEDIA
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.212100 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/102@192.168.1.42:33795) State CONSUME_MEDIA going to sleep
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.612076 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [proceeding][180]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.612076 [NOTICE] sofia.c:7436 Ring-Ready sofia/internal/102@192.168.1.42:33795!
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.612076 [DEBUG] switch_channel.c:3437 (sofia/internal/102@192.168.1.42:33795) Callstate Change DOWN -> RINGING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:34.612076 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [proceeding][180]
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.612076 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/internal/101@MY.DOMAIN.NAME.COM!
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.612076 [DEBUG] sofia.c:7325 Channel sofia/internal/101@MY.DOMAIN.NAME.COM entering state [early][180]
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:34.612076 [NOTICE] switch_ivr_originate.c:556 Ring Ready sofia/internal/101@MY.DOMAIN.NAME.COM!
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [completing][200]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] sofia.c:7335 Remote SDP:
b6a7a9a2-3558-444e-bca8-659032801bc5 v=0
b6a7a9a2-3558-444e-bca8-659032801bc5 o=- 0 1 IN IP4 192.168.0.250
b6a7a9a2-3558-444e-bca8-659032801bc5 s=-
b6a7a9a2-3558-444e-bca8-659032801bc5 c=IN IP4 192.168.1.42
b6a7a9a2-3558-444e-bca8-659032801bc5 t=0 0
b6a7a9a2-3558-444e-bca8-659032801bc5 m=audio 4010 RTP/AVP 0 101
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:0 PCMU/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=rtpmap:101 telephone-event/8000
b6a7a9a2-3558-444e-bca8-659032801bc5 a=fmtp:101 0-15
b6a7a9a2-3558-444e-bca8-659032801bc5
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] sofia.c:7325 Channel sofia/internal/102@192.168.1.42:33795 entering state [ready][200]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/102@192.168.1.42:33795 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_codec.c:111 sofia/internal/102@192.168.1.42:33795 Original read codec set to PCMU:0
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:5909 sofia/internal/102@192.168.1.42:33795 Set 2833 dtmf send payload to 101 recv payload to 101
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/internal/102@192.168.1.42:33795] 172.26.3.197 port 17594 -> 192.168.1.42 port 4010 codec: 0 ms: 20
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 160 bytes per 20ms
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:8975 sofia/internal/102@192.168.1.42:33795 Set 2833 dtmf send payload to 101
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:8982 sofia/internal/102@192.168.1.42:33795 Set 2833 dtmf receive payload to 101
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_core_media.c:9005 sofia/internal/102@192.168.1.42:33795 Set rtp dtmf delay to 40
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [NOTICE] sofia.c:8503 Channel [sofia/internal/102@192.168.1.42:33795] has been answered
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.272050 [DEBUG] switch_channel.c:3865 (sofia/internal/102@192.168.1.42:33795) Callstate Change RINGING -> ACTIVE
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_core_media.c:8661 AUDIO RTP [sofia/internal/101@MY.DOMAIN.NAME.COM] 172.26.3.197 port 28094 -> 192.168.1.48 port 12104 codec: 0 ms: 20
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_rtp.c:4413 Starting timer [soft] 160 bytes per 20ms
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_core_media.c:8975 sofia/internal/101@MY.DOMAIN.NAME.COM Set 2833 dtmf send payload to 101
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_core_media.c:8982 sofia/internal/101@MY.DOMAIN.NAME.COM Set 2833 dtmf receive payload to 101
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_core_media.c:9005 sofia/internal/101@MY.DOMAIN.NAME.COM Set rtp dtmf delay to 40
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] mod_sofia.c:898 Local SDP sofia/internal/101@MY.DOMAIN.NAME.COM:
3ec21880-128b-42ad-bb7c-3c4033f0919e v=0
3ec21880-128b-42ad-bb7c-3c4033f0919e o=FreeSWITCH 1590278785 1590278786 IN IP4 MY.DOMAIN.NAME.COM
3ec21880-128b-42ad-bb7c-3c4033f0919e s=FreeSWITCH
3ec21880-128b-42ad-bb7c-3c4033f0919e c=IN IP4 MY.DOMAIN.NAME.COM
3ec21880-128b-42ad-bb7c-3c4033f0919e t=0 0
3ec21880-128b-42ad-bb7c-3c4033f0919e m=audio 28094 RTP/AVP 0 101
3ec21880-128b-42ad-bb7c-3c4033f0919e a=rtpmap:0 PCMU/8000
3ec21880-128b-42ad-bb7c-3c4033f0919e a=rtpmap:101 telephone-event/8000
3ec21880-128b-42ad-bb7c-3c4033f0919e a=fmtp:101 0-16
3ec21880-128b-42ad-bb7c-3c4033f0919e a=ptime:20
3ec21880-128b-42ad-bb7c-3c4033f0919e a=sendrecv
3ec21880-128b-42ad-bb7c-3c4033f0919e
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] sofia.c:7325 Channel sofia/internal/101@MY.DOMAIN.NAME.COM entering state [completed][200]
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [NOTICE] switch_ivr_originate.c:3794 Channel [sofia/internal/101@MY.DOMAIN.NAME.COM] has been answered
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_channel.c:3865 (sofia/internal/101@MY.DOMAIN.NAME.COM) Callstate Change RINGING -> ACTIVE
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/internal/102@192.168.1.42:33795]
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.292049 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/internal/102@192.168.1.42:33795]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.292049 [DEBUG] switch_ivr_bridge.c:1793 (sofia/internal/102@192.168.1.42:33795) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.292049 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 9)
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.292049 [DEBUG] switch_core_state_machine.c:654 (sofia/internal/102@192.168.1.42:33795) State EXCHANGE_MEDIA
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.292049 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:39.332051 [DEBUG] sofia.c:7325 Channel sofia/internal/101@MY.DOMAIN.NAME.COM entering state [ready][200]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:39.832052 [INFO] switch_rtp.c:7682 Auto Changing audio port from 192.168.1.42:4010 to 50.75.94.242:4010
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [NOTICE] sofia.c:1089 Hangup sofia/internal/102@192.168.1.42:33795 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/102@192.168.1.42:33795]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:654 (sofia/internal/102@192.168.1.42:33795) State EXCHANGE_MEDIA going to sleep
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_HANGUP (Cur 2 Tot 9)
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/102@192.168.1.42:33795) Callstate Change ACTIVE -> HANGUP
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/102@192.168.1.42:33795) State HANGUP
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] mod_sofia.c:453 Channel sofia/internal/102@192.168.1.42:33795 hanging up, cause: NORMAL_CLEARING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:60 sofia/internal/102@192.168.1.42:33795 Standard HANGUP, cause: NORMAL_CLEARING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/102@192.168.1.42:33795) State HANGUP going to sleep
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/102@192.168.1.42:33795) State Change CS_HANGUP -> CS_REPORTING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_REPORTING (Cur 2 Tot 9)
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/102@192.168.1.42:33795) State REPORTING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:174 sofia/internal/102@192.168.1.42:33795 Standard REPORTING, cause: NORMAL_CLEARING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/102@192.168.1.42:33795) State REPORTING going to sleep
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/102@192.168.1.42:33795) State Change CS_REPORTING -> CS_DESTROY
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.292076 [DEBUG] switch_core_session.c:1726 Session 9 (sofia/internal/102@192.168.1.42:33795) Locked, Waiting on external entities
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/internal/101@MY.DOMAIN.NAME.COM]
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/internal/101@MY.DOMAIN.NAME.COM [CS_EXECUTE] [NORMAL_CLEARING]
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] mod_hash.c:296 Usage for MY.DOMAIN.NAME.COM_102 is now 0
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_session.c:2905 sofia/internal/101@MY.DOMAIN.NAME.COM skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/101@MY.DOMAIN.NAME.COM) State EXECUTE going to sleep
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/101@MY.DOMAIN.NAME.COM) Running State Change CS_HANGUP (Cur 2 Tot 9)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/101@MY.DOMAIN.NAME.COM) Callstate Change ACTIVE -> HANGUP
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/101@MY.DOMAIN.NAME.COM) State HANGUP
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] mod_sofia.c:447 sofia/internal/101@MY.DOMAIN.NAME.COM Overriding SIP cause 480 with 200 from the other leg
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] mod_sofia.c:453 Channel sofia/internal/101@MY.DOMAIN.NAME.COM hanging up, cause: NORMAL_CLEARING
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/101@MY.DOMAIN.NAME.COM
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:60 sofia/internal/101@MY.DOMAIN.NAME.COM Standard HANGUP, cause: NORMAL_CLEARING
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/101@MY.DOMAIN.NAME.COM) State HANGUP going to sleep
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:781 Hangup Command with no Session lua(app.lua hangup):
3ec21880-128b-42ad-bb7c-3c4033f0919e
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/101@MY.DOMAIN.NAME.COM) State Change CS_HANGUP -> CS_REPORTING
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/101@MY.DOMAIN.NAME.COM) Running State Change CS_REPORTING (Cur 2 Tot 9)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/101@MY.DOMAIN.NAME.COM) State REPORTING
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [NOTICE] switch_core_session.c:1744 Session 9 (sofia/internal/102@192.168.1.42:33795) Ended
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/102@192.168.1.42:33795 [CS_DESTROY]
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/102@192.168.1.42:33795) Running State Change CS_DESTROY (Cur 1 Tot 9)
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/102@192.168.1.42:33795) State DESTROY
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] mod_sofia.c:364 sofia/internal/102@192.168.1.42:33795 SOFIA DESTROY
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:181 sofia/internal/102@192.168.1.42:33795 Standard DESTROY
b6a7a9a2-3558-444e-bca8-659032801bc5 2020-05-24 07:54:46.312099 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/102@192.168.1.42:33795) State DESTROY going to sleep
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:174 sofia/internal/101@MY.DOMAIN.NAME.COM Standard REPORTING, cause: NORMAL_CLEARING
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/101@MY.DOMAIN.NAME.COM) State REPORTING going to sleep
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/101@MY.DOMAIN.NAME.COM) State Change CS_REPORTING -> CS_DESTROY
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_session.c:1726 Session 8 (sofia/internal/101@MY.DOMAIN.NAME.COM) Locked, Waiting on external entities
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [NOTICE] switch_core_session.c:1744 Session 8 (sofia/internal/101@MY.DOMAIN.NAME.COM) Ended
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/101@MY.DOMAIN.NAME.COM [CS_DESTROY]
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/101@MY.DOMAIN.NAME.COM) Running State Change CS_DESTROY (Cur 0 Tot 9)
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/101@MY.DOMAIN.NAME.COM) State DESTROY
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] mod_sofia.c:364 sofia/internal/101@MY.DOMAIN.NAME.COM SOFIA DESTROY
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:181 sofia/internal/101@MY.DOMAIN.NAME.COM Standard DESTROY
3ec21880-128b-42ad-bb7c-3c4033f0919e 2020-05-24 07:54:46.352049 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/101@MY.DOMAIN.NAME.COM) State DESTROY going to sleep