Hi
so i've fusionpbx and provider is sending calls from different IP address.
he using caller ID with Extension ID
i've 2 Incoming routing (this is not set by me its maybe from provider or customer itself)
somehow i can see come lands in Fusionpbx but never reaches to extension 102
Can you buys check the logs and guide me what is the issue
© Copyright 2008 - 2020 fusionpbx.com All rights reserved.
so i've fusionpbx and provider is sending calls from different IP address.
he using caller ID with Extension ID
i've 2 Incoming routing (this is not set by me its maybe from provider or customer itself)
somehow i can see come lands in Fusionpbx but never reaches to extension 102
Can you buys check the logs and guide me what is the issue
| e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000 e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_media.c:5911 sofia/internal/016469804834@31.168.230.137 Set 2833 dtmf send payload to 101 recv payload to 101 e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] sofia.c:7760 (sofia/internal/016469804834@31.168.230.137) State Change CS_NEW -> CS_INIT e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/016469804834@31.168.230.137) State NEW e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_INIT (Cur 1 Tot 97394) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/016469804834@31.168.230.137) State INIT e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] mod_sofia.c:93 sofia/internal/016469804834@31.168.230.137 SOFIA INIT e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:40 sofia/internal/016469804834@31.168.230.137 Standard INIT e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/016469804834@31.168.230.137) State Change CS_INIT -> CS_ROUTING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/016469804834@31.168.230.137) State INIT going to sleep e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_ROUTING (Cur 1 Tot 97394) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_channel.c:2332 (sofia/internal/016469804834@31.168.230.137) Callstate Change DOWN -> RINGING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/016469804834@31.168.230.137) State ROUTING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] mod_sofia.c:154 sofia/internal/016469804834@31.168.230.137 SOFIA ROUTING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [DEBUG] switch_core_state_machine.c:236 sofia/internal/016469804834@31.168.230.137 Standard ROUTING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.077077 [INFO] mod_dialplan_xml.c:637 Processing 0733134837 <016469804834>->102 in context public 2020-09-28 21:37:12.077077 [NOTICE] mod_logfile.c:192 New log started: /var/log/freeswitch/freeswitch.log e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->caller-details] continue=true e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (PASS) [caller-details] () =~ // break=never e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Action export(call_direction=inbound) INLINE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 export(call_direction=inbound) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound] e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Action set(caller_destination=${sip_to_user}) INLINE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 set(caller_destination=102) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.137 [caller_destination]=[102] e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Action set(caller_id_name=${caller_id_name}) INLINE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 set(caller_id_name=0733134837) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.137 [caller_id_name]=[0733134837] e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Action set(caller_id_number=${caller_id_number}) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->9176312716] continue=false e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (FAIL) [9176312716] destination_number(102) =~ /^(9176312716)$/ break=on-false e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->0733134837] continue=false e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (FAIL) [0733134837] destination_number(102) =~ /^(0733134837)$/ break=on-false e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->0548459164] continue=false e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (FAIL) [0548459164] destination_number(102) =~ /^(0548459164)$/ break=on-false e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->not-found] continue=false e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (PASS) [not-found] () =~ // break=on-false e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Action export(call_direction=inbound) INLINE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 export(call_direction=inbound) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound] e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Action set(call_direction=inbound) INLINE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 set(call_direction=inbound) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.137 [call_direction]=[inbound] e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 Dialplan: sofia/internal/016469804834@31.168.230.137 Action log(WARNING [inbound routes] 404 not found ${sip_network_ip}) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/016469804834@31.168.230.137) State Change CS_ROUTING -> CS_EXECUTE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/016469804834@31.168.230.137) State ROUTING going to sleep e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_EXECUTE (Cur 1 Tot 97394) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/016469804834@31.168.230.137) State EXECUTE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] mod_sofia.c:209 sofia/internal/016469804834@31.168.230.137 SOFIA EXECUTE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_core_state_machine.c:329 sofia/internal/016469804834@31.168.230.137 Standard EXECUTE e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 set(caller_id_number=016469804834) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.137 [caller_id_number]=[016469804834] e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 log(WARNING [inbound routes] 404 not found 31.168.230.137) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [WARNING] mod_dptools.c:1866 [inbound routes] 404 not found 31.168.230.137 e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [NOTICE] switch_core_state_machine.c:386 sofia/internal/016469804834@31.168.230.137 has executed the last dialplan instruction, hanging up. e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/016469804834@31.168.230.137 [CS_EXECUTE] [NORMAL_CLEARING] e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/016469804834@31.168.230.137) State EXECUTE going to sleep e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_HANGUP (Cur 1 Tot 97394) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.105544 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/016469804834@31.168.230.137) Callstate Change RINGING -> HANGUP e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.117064 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/016469804834@31.168.230.137) State HANGUP e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.117064 [DEBUG] mod_sofia.c:453 Channel sofia/internal/016469804834@31.168.230.137 hanging up, cause: NORMAL_CLEARING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.117064 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480 e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.117064 [DEBUG] switch_core_state_machine.c:60 sofia/internal/016469804834@31.168.230.137 Standard HANGUP, cause: NORMAL_CLEARING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.117064 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/016469804834@31.168.230.137) State HANGUP going to sleep e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.117064 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/016469804834@31.168.230.137) State Change CS_HANGUP -> CS_REPORTING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.117064 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_REPORTING (Cur 1 Tot 97394) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.117064 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/016469804834@31.168.230.137) State REPORTING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] switch_core_state_machine.c:174 sofia/internal/016469804834@31.168.230.137 Standard REPORTING, cause: NORMAL_CLEARING e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/016469804834@31.168.230.137) State REPORTING going to sleep e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/016469804834@31.168.230.137) State Change CS_REPORTING -> CS_DESTROY e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] switch_core_session.c:1726 Session 97394 (sofia/internal/016469804834@31.168.230.137) Locked, Waiting on external entities e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [NOTICE] switch_core_session.c:1744 Session 97394 (sofia/internal/016469804834@31.168.230.137) Ended e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/016469804834@31.168.230.137 [CS_DESTROY] e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_DESTROY (Cur 0 Tot 97394) e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/016469804834@31.168.230.137) State DESTROY e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] mod_sofia.c:364 sofia/internal/016469804834@31.168.230.137 SOFIA DESTROY e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] switch_core_state_machine.c:181 sofia/internal/016469804834@31.168.230.137 Standard DESTROY e3009af0-b4a2-49cd-9e94-a1182d8c5bc8 2020-09-28 21:37:12.197080 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/016469804834@31.168.230.137) State DESTROY going to sleep 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/016469804834@31.168.230.137 [12a652e3-7ce2-46ea-adc4-5b51cc969688] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_NEW (Cur 1 Tot 97395) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] sofia.c:10280 sofia/internal/016469804834@31.168.230.137 receiving invite from 31.168.230.137:5060 version: 1.10.5 -release.8 64bit 2020-09-28 21:37:17.637080 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 31.168.230.137:0. 2020-09-28 21:37:17.637080 [DEBUG] sofia.c:10403 IP 31.168.230.137 Approved by acl "domains[]". Access Granted. 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] sofia.c:7326 Channel sofia/internal/016469804834@31.168.230.137 entering state [received][100] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] sofia.c:7336 Remote SDP: 12a652e3-7ce2-46ea-adc4-5b51cc969688 v=0 12a652e3-7ce2-46ea-adc4-5b51cc969688 o=root 909491928 909491928 IN IP4 31.168.230.137 12a652e3-7ce2-46ea-adc4-5b51cc969688 s=Asterisk PBX 11.18.0 12a652e3-7ce2-46ea-adc4-5b51cc969688 c=IN IP4 31.168.230.137 12a652e3-7ce2-46ea-adc4-5b51cc969688 t=0 0 12a652e3-7ce2-46ea-adc4-5b51cc969688 m=audio 15702 RTP/AVP 8 101 12a652e3-7ce2-46ea-adc4-5b51cc969688 a=rtpmap:8 PCMA/8000 12a652e3-7ce2-46ea-adc4-5b51cc969688 a=rtpmap:101 telephone-event/8000 12a652e3-7ce2-46ea-adc4-5b51cc969688 a=fmtp:101 0-16 12a652e3-7ce2-46ea-adc4-5b51cc969688 a=ptime:20 12a652e3-7ce2-46ea-adc4-5b51cc969688 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:115:32000:20:48000:1] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:107:16000:20:32000:1] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:3839 Set Codec sofia/internal/016469804834@31.168.230.137 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_codec.c:111 sofia/internal/016469804834@31.168.230.137 Original read codec set to PCMA:8 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_media.c:5911 sofia/internal/016469804834@31.168.230.137 Set 2833 dtmf send payload to 101 recv payload to 101 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] sofia.c:7760 (sofia/internal/016469804834@31.168.230.137) State Change CS_NEW -> CS_INIT 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/016469804834@31.168.230.137) State NEW 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_INIT (Cur 1 Tot 97395) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/016469804834@31.168.230.137) State INIT 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_sofia.c:93 sofia/internal/016469804834@31.168.230.137 SOFIA INIT 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:40 sofia/internal/016469804834@31.168.230.137 Standard INIT 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/016469804834@31.168.230.137) State Change CS_INIT -> CS_ROUTING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/016469804834@31.168.230.137) State INIT going to sleep 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_ROUTING (Cur 1 Tot 97395) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_channel.c:2332 (sofia/internal/016469804834@31.168.230.137) Callstate Change DOWN -> RINGING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/016469804834@31.168.230.137) State ROUTING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_sofia.c:154 sofia/internal/016469804834@31.168.230.137 SOFIA ROUTING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:236 sofia/internal/016469804834@31.168.230.137 Standard ROUTING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [INFO] mod_dialplan_xml.c:637 Processing 0733134837 <016469804834>->102 in context public 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->caller-details] continue=true 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (PASS) [caller-details] () =~ // break=never 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Action export(call_direction=inbound) INLINE 12a652e3-7ce2-46ea-adc4-5b51cc969688 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 export(call_direction=inbound) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound] 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Action set(caller_destination=${sip_to_user}) INLINE 12a652e3-7ce2-46ea-adc4-5b51cc969688 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 set(caller_destination=102) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.137 [caller_destination]=[102] 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Action set(caller_id_name=${caller_id_name}) INLINE 12a652e3-7ce2-46ea-adc4-5b51cc969688 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 set(caller_id_name=0733134837) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.137 [caller_id_name]=[0733134837] 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Action set(caller_id_number=${caller_id_number}) 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->9176312716] continue=false 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (FAIL) [9176312716] destination_number(102) =~ /^(9176312716)$/ break=on-false 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->0733134837] continue=false 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (FAIL) [0733134837] destination_number(102) =~ /^(0733134837)$/ break=on-false 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->0548459164] continue=false 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (FAIL) [0548459164] destination_number(102) =~ /^(0548459164)$/ break=on-false 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 parsing [public->not-found] continue=false 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Regex (PASS) [not-found] () =~ // break=on-false 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Action export(call_direction=inbound) INLINE 12a652e3-7ce2-46ea-adc4-5b51cc969688 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 export(call_direction=inbound) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound] 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Action set(call_direction=inbound) INLINE 12a652e3-7ce2-46ea-adc4-5b51cc969688 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 set(call_direction=inbound) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.137 [call_direction]=[inbound] 12a652e3-7ce2-46ea-adc4-5b51cc969688 Dialplan: sofia/internal/016469804834@31.168.230.137 Action log(WARNING [inbound routes] 404 not found ${sip_network_ip}) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/016469804834@31.168.230.137) State Change CS_ROUTING -> CS_EXECUTE 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/016469804834@31.168.230.137) State ROUTING going to sleep 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_EXECUTE (Cur 1 Tot 97395) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/016469804834@31.168.230.137) State EXECUTE 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_sofia.c:209 sofia/internal/016469804834@31.168.230.137 SOFIA EXECUTE 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:329 sofia/internal/016469804834@31.168.230.137 Standard EXECUTE 12a652e3-7ce2-46ea-adc4-5b51cc969688 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 set(caller_id_number=016469804834) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.137 [caller_id_number]=[016469804834] 12a652e3-7ce2-46ea-adc4-5b51cc969688 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.137 log(WARNING [inbound routes] 404 not found 31.168.230.137) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [WARNING] mod_dptools.c:1866 [inbound routes] 404 not found 31.168.230.137 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [NOTICE] switch_core_state_machine.c:386 sofia/internal/016469804834@31.168.230.137 has executed the last dialplan instruction, hanging up. 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/016469804834@31.168.230.137 [CS_EXECUTE] [NORMAL_CLEARING] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/016469804834@31.168.230.137) State EXECUTE going to sleep 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_HANGUP (Cur 1 Tot 97395) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/016469804834@31.168.230.137) Callstate Change RINGING -> HANGUP 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/016469804834@31.168.230.137) State HANGUP 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_sofia.c:453 Channel sofia/internal/016469804834@31.168.230.137 hanging up, cause: NORMAL_CLEARING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:60 sofia/internal/016469804834@31.168.230.137 Standard HANGUP, cause: NORMAL_CLEARING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/016469804834@31.168.230.137) State HANGUP going to sleep 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/016469804834@31.168.230.137) State Change CS_HANGUP -> CS_REPORTING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_REPORTING (Cur 1 Tot 97395) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.637080 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/016469804834@31.168.230.137) State REPORTING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] switch_core_state_machine.c:174 sofia/internal/016469804834@31.168.230.137 Standard REPORTING, cause: NORMAL_CLEARING 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/016469804834@31.168.230.137) State REPORTING going to sleep 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/016469804834@31.168.230.137) State Change CS_REPORTING -> CS_DESTROY 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] switch_core_session.c:1726 Session 97395 (sofia/internal/016469804834@31.168.230.137) Locked, Waiting on external entities 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [NOTICE] switch_core_session.c:1744 Session 97395 (sofia/internal/016469804834@31.168.230.137) Ended 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/016469804834@31.168.230.137 [CS_DESTROY] 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/016469804834@31.168.230.137) Running State Change CS_DESTROY (Cur 0 Tot 97395) 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/016469804834@31.168.230.137) State DESTROY 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] mod_sofia.c:364 sofia/internal/016469804834@31.168.230.137 SOFIA DESTROY 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] switch_core_state_machine.c:181 sofia/internal/016469804834@31.168.230.137 Standard DESTROY 12a652e3-7ce2-46ea-adc4-5b51cc969688 2020-09-28 21:37:17.677082 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/016469804834@31.168.230.137) State DESTROY going to sleep |
© Copyright 2008 - 2020 fusionpbx.com All rights reserved.