df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/106@66.228.34.184) State DESTROY going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:181 sofia/internal/106@66.228.34.184 Standard DESTROY df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] mod_sofia.c:343 sofia/internal/106@66.228.34.184 SOFIA DESTROY df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/106@66.228.34.184) State DESTROY df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/106@66.228.34.184) Running State Change CS_DESTROY (Cur 0 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/106@66.228.34.184 [CS_DESTROY] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [NOTICE] switch_core_session.c:1683 Session 84 (sofia/internal/106@66.228.34.184) Ended df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_session.c:1665 Session 84 (sofia/internal/106@66.228.34.184) Locked, Waiting on external entities df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/106@66.228.34.184) State Change CS_REPORTING -> CS_DESTROY df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/106@66.228.34.184) State REPORTING going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.734122 [DEBUG] switch_core_state_machine.c:174 sofia/internal/106@66.228.34.184 Standard REPORTING, cause: NORMAL_CLEARING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.614120 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/106@66.228.34.184) State REPORTING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.614120 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_REPORTING (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/106@66.228.34.184) State Change CS_HANGUP -> CS_REPORTING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/106@66.228.34.184) State HANGUP going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:60 sofia/internal/106@66.228.34.184 Standard HANGUP, cause: NORMAL_CLEARING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480 df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_sofia.c:438 Channel sofia/internal/106@66.228.34.184 hanging up, cause: NORMAL_CLEARING df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/106@66.228.34.184) State HANGUP df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/106@66.228.34.184) Callstate Change RINGING -> HANGUP df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_HANGUP (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/106@66.228.34.184) State EXECUTE going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/106@66.228.34.184 [CS_EXECUTE] [NORMAL_CLEARING] df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [NOTICE] switch_core_state_machine.c:385 sofia/internal/106@66.228.34.184 has executed the last dialplan instruction, hanging up. df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:328 sofia/internal/106@66.228.34.184 Standard EXECUTE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_sofia.c:198 sofia/internal/106@66.228.34.184 SOFIA EXECUTE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/106@66.228.34.184) State EXECUTE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/106@66.228.34.184) Running State Change CS_EXECUTE (Cur 1 Tot 84) df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/106@66.228.34.184) State ROUTING going to sleep df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/106@66.228.34.184) State Change CS_ROUTING -> CS_EXECUTE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1742 routes] 404 not found 173.29.209.241 df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 log([inbound routes] 404 not found 173.29.209.241) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action log([inbound routes] 404 not found ${sip_network_ip}) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1548 SET sofia/internal/106@66.228.34.184 [call_direction]=[inbound] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 set(call_direction=inbound) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action set(call_direction=inbound) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[inbound] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 export(call_direction=inbound) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action export(call_direction=inbound) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Regex (PASS) [not-found] () =~ // break=on-false df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 parsing [public->not-found] continue=false df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1548 SET sofia/internal/106@66.228.34.184 [caller_id_number]=[106] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 set(caller_id_number=106) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action set(caller_id_number=${caller_id_number}) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1548 SET sofia/internal/106@66.228.34.184 [caller_id_name]=[106] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 set(caller_id_name=106) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action set(caller_id_name=${caller_id_name}) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] mod_dptools.c:1548 SET sofia/internal/106@66.228.34.184 [caller_destination]=[106] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 set(caller_destination=106) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action set(caller_destination=${destination_number}) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[inbound] df8715a6-4bbd-4e70-8927-a105e8682a76 EXECUTE sofia/internal/106@66.228.34.184 export(call_direction=inbound) df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Action export(call_direction=inbound) INLINE df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 Regex (PASS) [caller-details] () =~ // break=never df8715a6-4bbd-4e70-8927-a105e8682a76 Dialplan: sofia/internal/106@66.228.34.184 parsing [public->caller-details] continue=true df8715a6-4bbd-4e70-8927-a105e8682a76 2018-04-17 21:20:38.594152 [INFO] mod_dialplan_xml.c:637 Processing 106 <106>->106 in context public