SOLVED outbound

Status
Not open for further replies.
Andyd358

Andyd358

Member
Aug 23, 2018
245
8
18
55
UK
Had a customer report today that they can't ring a few numbers, When we try from the same install either on their domein or on ours we get internal server error. This has been happening for a while but they never reported it untill today.

Logs for the number:

d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.537367 [DEBUG] switch_core_state_machine.c:749 (sofia/external/01388455200) State DESTROY going to sleep
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.537367 [DEBUG] switch_core_state_machine.c:181 sofia/external/01388455200 Standard DESTROY
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.537367 [DEBUG] mod_sofia.c:364 sofia/external/01388455200 SOFIA DESTROY
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.537367 [DEBUG] switch_core_state_machine.c:749 (sofia/external/01388455200) State DESTROY
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.537367 [DEBUG] switch_core_state_machine.c:739 (sofia/external/01388455200) Running State Change CS_DESTROY (Cur 28 Tot 77833)
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.537367 [NOTICE] switch_core_session.c:1758 Close Channel sofia/external/01388455200 [CS_DESTROY]
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.537367 [NOTICE] switch_core_session.c:1754 Session 77833 (sofia/external/01388455200) Ended
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_session.c:1736 Session 77833 (sofia/external/01388455200) Locked, Waiting on external entities
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:611 (sofia/external/01388455200) State Change CS_REPORTING -> CS_DESTROY
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:936 (sofia/external/01388455200) State REPORTING going to sleep
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:174 sofia/external/01388455200 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:936 (sofia/external/01388455200) State REPORTING
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:585 (sofia/external/01388455200) Running State Change CS_REPORTING (Cur 29 Tot 77833)
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:620 (sofia/external/01388455200) State Change CS_HANGUP -> CS_REPORTING
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:850 (sofia/external/01388455200) State HANGUP going to sleep
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:60 sofia/external/01388455200 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] mod_sofia.c:453 Channel sofia/external/01388455200 hanging up, cause: NORMAL_TEMPORARY_FAILURE
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:850 (sofia/external/01388455200) State HANGUP
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:848 (sofia/external/01388455200) Callstate Change RINGING -> HANGUP
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] switch_core_state_machine.c:585 (sofia/external/01388455200) Running State Change CS_HANGUP (Cur 29 Tot 77833)
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [NOTICE] sofia.c:8641 Hangup sofia/external/01388455200 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] sofia.c:7406 Channel sofia/external/01388455200 entering state [terminating][500]
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:53.517407 [DEBUG] sofia.c:7406 Channel sofia/external/01388455200 entering state [completing][200]
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.517386 [DEBUG] switch_channel.c:3437 (sofia/external/01388455200) Callstate Change DOWN -> RINGING
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.517386 [NOTICE] sofia.c:7517 Ring-Ready sofia/external/01388455200!
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.517386 [DEBUG] sofia.c:7406 Channel sofia/external/01388455200 entering state [proceeding][180]
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:663 (sofia/external/01388455200) State CONSUME_MEDIA going to sleep
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:663 (sofia/external/01388455200) State CONSUME_MEDIA
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] sofia.c:7406 Channel sofia/external/01388455200 entering state [calling][0]
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:585 (sofia/external/01388455200) Running State Change CS_CONSUME_MEDIA (Cur 29 Tot 77833)
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:644 (sofia/external/01388455200) State ROUTING going to sleep
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_ivr_originate.c:67 (sofia/external/01388455200) State Change CS_ROUTING -> CS_CONSUME_MEDIA
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] mod_sofia.c:154 sofia/external/01388455200 SOFIA ROUTING
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:644 (sofia/external/01388455200) State ROUTING
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:585 (sofia/external/01388455200) Running State Change CS_ROUTING (Cur 29 Tot 77833)
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:628 (sofia/external/01388455200) State INIT going to sleep
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:48 (sofia/external/01388455200) State Change CS_INIT -> CS_ROUTING
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:40 sofia/external/01388455200 Standard INIT
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] sofia_glue.c:1624 sofia/external/01388455200 sending invite version: 1.10.6 -release-18-1ff9d0a60e 64bit
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [INFO] sofia_glue.c:1621 sofia/external/01388455200 sending invite call-id: (null)
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] mod_sofia.c:93 sofia/external/01388455200 SOFIA INIT
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:628 (sofia/external/01388455200) State INIT
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] switch_core_state_machine.c:585 (sofia/external/01388455200) Running State Change CS_INIT (Cur 29 Tot 77833)
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [DEBUG] mod_sofia.c:5102 (sofia/external/01388455200) State Change CS_NEW -> CS_INIT
d5c54077-df54-46de-9579-73e94d82ad47 2021-08-03 14:03:51.117363 [NOTICE] switch_channel.c:1118 New Channel sofia/external/01388455200 [d5c54077-df54-46de-9579-73e94d82ad47]
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 2021-08-03 14:03:51.117363 [DEBUG] switch_channel.c:1264 sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk EXPORTING[export_vars] [origination_callee_id_name]=[01388455200] to event
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 EXECUTE [depth=0] sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk bridge(sofia/gateway/46b13b42-fe42-432e-a2b7-1f5325682168/01388455200)
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 2021-08-03 14:03:51.117363 [DEBUG] mod_dptools.c:1685 SET sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk [callee_id_number]=[01388455200]
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 EXECUTE [depth=0] sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk set(callee_id_number=01388455200)
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 EXECUTE [depth=0] sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk hash(insert/manorcare.voip.XXXXXX-it.co.uk-last_dial/455/01388455200)
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 2021-08-03 14:03:51.117363 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [origination_callee_id_name]=[01388455200]
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 EXECUTE [depth=0] sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk export(origination_callee_id_name=01388455200)
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 Dialplan: sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk Action bridge(sofia/gateway/46b13b42-fe42-432e-a2b7-1f5325682168/01388455200)
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 Dialplan: sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk Action set(callee_id_number=01388455200)
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 Dialplan: sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk Regex (PASS) [Manorcare Gamma.11d] destination_number(01388455200) =~ /^\+?(\d{11})$/ break=on-false
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 Dialplan: sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk Regex (FAIL) [Manorcare Gamma.9.11d] destination_number(01388455200) =~ /^9(\d{11})$/ break=on-false
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 Dialplan: sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk Regex (FAIL) [Manorcare Gamma.999111101119] destination_number(01388455200) =~ /^(999|111|101|119)$/ break=on-false
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 Dialplan: sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk Regex (FAIL) [Manorcare Gamma.International] destination_number(01388455200) =~ /^(\d{12,20})$/ break=on-false
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 Dialplan: sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk Regex (FAIL) [speed_dial] destination_number(01388455200) =~ /^\*0(.*)$/ break=on-false
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 Dialplan: sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk Regex (FAIL) [redial] destination_number(01388455200) =~ /^(redial|\*870)$/ break=on-true
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 2021-08-03 14:03:51.117363 [DEBUG] mod_dptools.c:1685 SET sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk [caller_destination]=[01388455200]
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 EXECUTE [depth=0] sofia/internal/455@manorcare.voip.XXXXXX-it.co.uk set(caller_destination=01388455200)
3b3ef06c-bdbc-42d1-8ac0-b0fc32697201 2021-08-03 14:03:51.097392 [INFO] mod_dialplan_xml.c:637 Processing 455 <455>->01388455200 in context manorcare.voip.XXXXXX-it.co.uk
2021-08-03 14:03:51.077407 [WARNING] sofia_reg.c:1795 SIP auth challenge (INVITE) on sofia profile 'internal' for [01388455200@manorcare.voip.XXXXXX-it.co.uk] from ip 51.219.83.202
 
DavidDec

DavidDec

Member
Jan 30, 2020
32
3
8
37
United Kingdom
Seems this was down to the domains gateway being external instead of internal, only effected one local area code and weirdly not a setting that had been manually changed.
 
Status
Not open for further replies.
Top Bottom