Outbound Route help

Status
Not open for further replies.
B

Bifur

Member
Sep 13, 2020
122
9
18
I am having the hardest time getting an outbound route to work. It seems so simple but I am getting bad requests and malformed in sngrep. I used regex101.com and everything seems to match. I am just trying to allow 10 digit dialing (with an optional 1 at the beginning), 711, 811, and 988. My 911/933 goes out a separate trunk and works properly.

Regex:

^1?(\d{10})$|^(711)$|^(811)$|^(988)$

11 and 10 digit works here. The others do not. If I move the first part to the end, 711 works but the others do not.

What am I missing?

Thanks for any help.
 
B

Bifur

Member
Sep 13, 2020
122
9
18
So I guess it's matching the 10 digits but I get bad request.

Code: 
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Regex (PASS) [Bandwidth1.1d10711811988] destination_number(5553924050) =~ /^(711)$|^(811)$|^(988)$|^1?(\d{10})$/ break=on-false
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action export(call_direction=outbound) INLINE
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 export(call_direction=outbound)
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_direction]=[outbound]
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action unset(call_timeout)
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action set(hangup_after_bridge=true)
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action set(inherit_codec=true)
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action set(ignore_display_updates=true)
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action set(callee_id_number=)
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607)
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action bridge(sofia/gateway/dbd41f5d-7561-4824-bbb6-f51d350d6d98/)
Dialplan: sofia/internal/200@pbx.mydomain.net:5060 Action bridge(sofia/gateway/06fcb3b5-4434-4924-9791-19d3f7b3c280/)
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/200@pbx.mydomain.net:5060) State Change CS_ROUTING -> CS_EXECUTE
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/200@pbx.mydomain.net:5060) State ROUTING going to sleep
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/200@pbx.mydomain.net:5060) Running State Change CS_EXECUTE (Cur 1 Tot 53)
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/200@pbx.mydomain.net:5060) State EXECUTE
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_sofia.c:213 sofia/internal/200@pbx.mydomain.net:5060 SOFIA EXECUTE
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:323 sofia/internal/200@pbx.mydomain.net:5060 Standard EXECUTE
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(caller_id_number=200)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [caller_id_number]=[200]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(RFC2822_DATE=Mon, 18 Sep 2023 11:38:17 -0400)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [RFC2822_DATE]=[Mon, 18 Sep 2023 11:38:17 -0400]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 export(origination_callee_id_name=5553924050)
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [origination_callee_id_name]=[5553924050]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 lua(app.lua is_local)
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_cpp.cpp:1209 sofia/internal/200@pbx.mydomain.net:5060 destroy/unlink session from object
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 hash(insert/pbx.mydomain.net-last_dial/200/5553924050)
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 unset(call_timeout)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1818 UNSET [call_timeout]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(hangup_after_bridge=true)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [hangup_after_bridge]=[true]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(effective_caller_id_name=Georgialina2)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [effective_caller_id_name]=[Georgialina2]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(effective_caller_id_number=5553924050)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [effective_caller_id_number]=[5553924050]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(inherit_codec=true)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [inherit_codec]=[true]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(ignore_display_updates=true)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [ignore_display_updates]=[true]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(callee_id_number=)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [callee_id_number]=[UNDEF]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607)
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_dptools.c:1673 SET sofia/internal/200@pbx.mydomain.net:5060 [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607]
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 bridge(sofia/gateway/dbd41f5d-7561-4824-bbb6-f51d350d6d98/)
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [domain_name]=[pbx.mydomain.net] to event
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [domain_uuid]=[203d60ab-14b5-481f-b786-2cce9d78022f] to event
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [origination_callee_id_name]=[5553924050] to event
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
2023-09-18 11:38:17.764467 98.90% [NOTICE] switch_channel.c:1123 New Channel sofia/external/ [c0f2b0c5-5518-447a-a0fa-0803fe238f93]
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_sofia.c:5117 (sofia/external/) State Change CS_NEW -> CS_INIT
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_INIT (Cur 2 Tot 54)
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:624 (sofia/external/) State INIT
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_sofia.c:97 sofia/external/ SOFIA INIT
2023-09-18 11:38:17.764467 98.90% [DEBUG] sofia_glue.c:1623 sip:sip.mydomain.net Setting proxy route to sofia/external/
2023-09-18 11:38:17.764467 98.90% [INFO] sofia_glue.c:1654 sofia/external/ sending invite call-id: (null)
2023-09-18 11:38:17.764467 98.90% [DEBUG] sofia_glue.c:1657 sofia/external/ sending invite version: 1.10.9 -release 64bit
Local SDP:
v=0
o=FreeSWITCH 1695030727 1695030728 IN IP4 144.201.17.11
s=FreeSWITCH
c=IN IP4 144.201.17.11
t=0 0
m=audio 20770 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:40 sofia/external/ Standard INIT
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:48 (sofia/external/) State Change CS_INIT -> CS_ROUTING
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:624 (sofia/external/) State INIT going to sleep
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_ROUTING (Cur 2 Tot 54)
2023-09-18 11:38:17.764467 98.90% [DEBUG] sofia.c:7487 Channel sofia/external/ entering state [calling][0]
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:640 (sofia/external/) State ROUTING
2023-09-18 11:38:17.764467 98.90% [DEBUG] mod_sofia.c:158 sofia/external/ SOFIA ROUTING
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_ivr_originate.c:67 (sofia/external/) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:640 (sofia/external/) State ROUTING going to sleep
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 54)
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:659 (sofia/external/) State CONSUME_MEDIA
2023-09-18 11:38:17.764467 98.90% [DEBUG] switch_core_state_machine.c:659 (sofia/external/) State CONSUME_MEDIA going to sleep
2023-09-18 11:38:17.864469 98.90% [DEBUG] sofia.c:7487 Channel sofia/external/ entering state [terminated][400]
2023-09-18 11:38:17.864469 98.90% [NOTICE] sofia.c:8729 Hangup sofia/external/ [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_HANGUP (Cur 2 Tot 54)
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:844 (sofia/external/) Callstate Change DOWN -> HANGUP
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:846 (sofia/external/) State HANGUP
2023-09-18 11:38:17.864469 98.90% [DEBUG] mod_sofia.c:468 Channel sofia/external/ hanging up, cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:59 sofia/external/ Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:846 (sofia/external/) State HANGUP going to sleep
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:616 (sofia/external/) State Change CS_HANGUP -> CS_REPORTING
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_REPORTING (Cur 2 Tot 54)
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:932 (sofia/external/) State REPORTING
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:168 sofia/external/ Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:932 (sofia/external/) State REPORTING going to sleep
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:607 (sofia/external/) State Change CS_REPORTING -> CS_DESTROY
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_session.c:1743 Session 54 (sofia/external/) Locked, Waiting on external entities
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_ivr_originate.c:4045 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2023-09-18 11:38:17.864469 98.90% [NOTICE] switch_core_session.c:1761 Session 54 (sofia/external/) Ended
2023-09-18 11:38:17.864469 98.90% [NOTICE] switch_core_session.c:1765 Close Channel sofia/external/ [CS_DESTROY]
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:735 (sofia/external/) Running State Change CS_DESTROY (Cur 1 Tot 54)
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:745 (sofia/external/) State DESTROY
2023-09-18 11:38:17.864469 98.90% [DEBUG] mod_sofia.c:379 sofia/external/ SOFIA DESTROY
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:175 sofia/external/ Standard DESTROY
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_core_state_machine.c:745 (sofia/external/) State DESTROY going to sleep
2023-09-18 11:38:17.864469 98.90% [INFO] mod_dptools.c:3637 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.864469 98.90% [DEBUG] switch_channel.c:4924 Continue on fail [1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607]:  Cause: NORMAL_TEMPORARY_FAILURE
EXECUTE [depth=0] sofia/internal/200@pbx.mydomain.net:5060 bridge(sofia/gateway/06fcb3b5-4434-4924-9791-19d3f7b3c280/)
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [domain_name]=[pbx.mydomain.net] to event
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [domain_uuid]=[203d60ab-14b5-481f-b786-2cce9d78022f] to event
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_channel.c:1269 sofia/internal/200@pbx.mydomain.net:5060 EXPORTING[export_vars] [origination_callee_id_name]=[5553924050] to event
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
2023-09-18 11:38:17.884465 98.90% [NOTICE] switch_channel.c:1123 New Channel sofia/external/ [74e4adbb-adf3-4462-8ba8-9290800bffac]
2023-09-18 11:38:17.884465 98.90% [DEBUG] mod_sofia.c:5117 (sofia/external/) State Change CS_NEW -> CS_INIT
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_INIT (Cur 2 Tot 55)
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:624 (sofia/external/) State INIT
2023-09-18 11:38:17.884465 98.90% [DEBUG] mod_sofia.c:97 sofia/external/ SOFIA INIT
2023-09-18 11:38:17.884465 98.90% [DEBUG] sofia_glue.c:1623 sip:sip.mydomain.net Setting proxy route to sofia/external/
2023-09-18 11:38:17.884465 98.90% [INFO] sofia_glue.c:1654 sofia/external/ sending invite call-id: (null)
2023-09-18 11:38:17.884465 98.90% [DEBUG] sofia_glue.c:1657 sofia/external/ sending invite version: 1.10.9 -release 64bit
Local SDP:
v=0
o=FreeSWITCH 1695031387 1695031388 IN IP4 144.201.17.11
s=FreeSWITCH
c=IN IP4 144.201.17.11
t=0 0
m=audio 20110 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:40 sofia/external/ Standard INIT
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:48 (sofia/external/) State Change CS_INIT -> CS_ROUTING
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:624 (sofia/external/) State INIT going to sleep
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_ROUTING (Cur 2 Tot 55)
2023-09-18 11:38:17.884465 98.90% [DEBUG] sofia.c:7487 Channel sofia/external/ entering state [calling][0]
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:640 (sofia/external/) State ROUTING
2023-09-18 11:38:17.884465 98.90% [DEBUG] mod_sofia.c:158 sofia/external/ SOFIA ROUTING
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_ivr_originate.c:67 (sofia/external/) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:640 (sofia/external/) State ROUTING going to sleep
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 55)
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:659 (sofia/external/) State CONSUME_MEDIA
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:659 (sofia/external/) State CONSUME_MEDIA going to sleep
2023-09-18 11:38:17.884465 98.90% [DEBUG] sofia.c:7487 Channel sofia/external/ entering state [terminated][400]
2023-09-18 11:38:17.884465 98.90% [NOTICE] sofia.c:8729 Hangup sofia/external/ [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_HANGUP (Cur 2 Tot 55)
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:844 (sofia/external/) Callstate Change DOWN -> HANGUP
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:846 (sofia/external/) State HANGUP
2023-09-18 11:38:17.884465 98.90% [DEBUG] mod_sofia.c:468 Channel sofia/external/ hanging up, cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:59 sofia/external/ Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:846 (sofia/external/) State HANGUP going to sleep
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:616 (sofia/external/) State Change CS_HANGUP -> CS_REPORTING
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/external/) Running State Change CS_REPORTING (Cur 2 Tot 55)
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:932 (sofia/external/) State REPORTING
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:168 sofia/external/ Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:932 (sofia/external/) State REPORTING going to sleep
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_state_machine.c:607 (sofia/external/) State Change CS_REPORTING -> CS_DESTROY
2023-09-18 11:38:17.884465 98.90% [DEBUG] switch_core_session.c:1743 Session 55 (sofia/external/) Locked, Waiting on external entities
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_ivr_originate.c:4045 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
2023-09-18 11:38:17.904478 98.90% [NOTICE] switch_core_session.c:1761 Session 55 (sofia/external/) Ended
2023-09-18 11:38:17.904478 98.90% [NOTICE] switch_core_session.c:1765 Close Channel sofia/external/ [CS_DESTROY]
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:735 (sofia/external/) Running State Change CS_DESTROY (Cur 1 Tot 55)
2023-09-18 11:38:17.904478 98.90% [INFO] mod_dptools.c:3637 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:745 (sofia/external/) State DESTROY
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_channel.c:4924 Continue on fail [1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607]:  Cause: NORMAL_TEMPORARY_FAILURE
2023-09-18 11:38:17.904478 98.90% [DEBUG] mod_sofia.c:379 sofia/external/ SOFIA DESTROY
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:175 sofia/external/ Standard DESTROY
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:745 (sofia/external/) State DESTROY going to sleep
2023-09-18 11:38:17.904478 98.90% [NOTICE] switch_core_state_machine.c:382 sofia/internal/200@pbx.mydomain.net:5060 has executed the last dialplan instruction, hanging up.
2023-09-18 11:38:17.904478 98.90% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/internal/200@pbx.mydomain.net:5060 [CS_EXECUTE] [NORMAL_CLEARING]
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/200@pbx.mydomain.net:5060) State EXECUTE going to sleep
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/200@pbx.mydomain.net:5060) Running State Change CS_HANGUP (Cur 1 Tot 55)
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/200@pbx.mydomain.net:5060) Callstate Change RINGING -> HANGUP
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/200@pbx.mydomain.net:5060) State HANGUP
2023-09-18 11:38:17.904478 98.90% [DEBUG] mod_sofia.c:462 sofia/internal/200@pbx.mydomain.net:5060 Overriding SIP cause 480 with 400 from the other leg
2023-09-18 11:38:17.904478 98.90% [DEBUG] mod_sofia.c:468 Channel sofia/internal/200@pbx.mydomain.net:5060 hanging up, cause: NORMAL_CLEARING
2023-09-18 11:38:17.904478 98.90% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 400
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:59 sofia/internal/200@pbx.mydomain.net:5060 Standard HANGUP, cause: NORMAL_CLEARING
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/200@pbx.mydomain.net:5060) State HANGUP going to sleep
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/200@pbx.mydomain.net:5060) State Change CS_HANGUP -> CS_REPORTING
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/200@pbx.mydomain.net:5060) Running State Change CS_REPORTING (Cur 1 Tot 55)
2023-09-18 11:38:17.904478 98.90% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/200@pbx.mydomain.net:5060) State REPORTING
2023-09-18 11:38:17.984476 98.90% [DEBUG] switch_core_state_machine.c:168 sofia/internal/200@pbx.mydomain.net:5060 Standard REPORTING, cause: NORMAL_CLEARING
2023-09-18 11:38:17.984476 98.90% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/200@pbx.mydomain.net:5060) State REPORTING going to sleep
2023-09-18 11:38:17.984476 98.90% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/200@pbx.mydomain.net:5060) State Change CS_REPORTING -> CS_DESTROY
2023-09-18 11:38:17.984476 98.90% [DEBUG] switch_core_session.c:1743 Session 53 (sofia/internal/200@pbx.mydomain.net:5060) Locked, Waiting on external entities
2023-09-18 11:38:17.984476 98.90% [NOTICE] switch_core_session.c:1761 Session 53 (sofia/internal/200@pbx.mydomain.net:5060) Ended
2023-09-18 11:38:17.984476 98.90% [NOTICE] switch_core_session.c:1765 Close Channel sofia/internal/200@pbx.mydomain.net:5060 [CS_DESTROY]
2023-09-18 11:38:17.984476 98.90% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/200@pbx.mydomain.net:5060) Running State Change CS_DESTROY (Cur 0 Tot 55)
2023-09-18 11:38:17.984476 98.90% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/200@pbx.mydomain.net:5060) State DESTROY
2023-09-18 11:38:17.984476 98.90% [DEBUG] mod_sofia.c:379 sofia/internal/200@pbx.mydomain.net:5060 SOFIA DESTROY
2023-09-18 11:38:17.984476 98.90% [DEBUG] switch_core_state_machine.c:175 sofia/internal/200@pbx.mydomain.net:5060 Standard DESTROY
2023-09-18 11:38:17.984476 98.90% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/200@pbx.mydomain.net:5060) State DESTROY going to sleep

Looking at the invite header it is only showing:

Code: 
To: <sip:@216.82.xxx.xxx>

Thoughts?
 
Last edited:
B

Bifur

Member
Sep 13, 2020
122
9
18
Disregard. I just made a 10 digit outbound route and then one for the N11 services. Its working now.
 
Status
Not open for further replies.
Top Bottom