Hi,

This is the log that I've got when it's trying to make a call to the PSTN. I found that the section dialplan are not evaluating all extensions that exist in the database .

2015-02-11 11:36:27.018534 [DEBUG] switch_xml.c:1995 Cache Info
Time Now: 1423676187018534
Expires: 1423676136458535
2015-02-11 11:36:27.018534 [DEBUG] switch_xml.c:1997 Cache expired for 8117396389ip@189.211.21.30, doing fresh lookup
2015-02-11 11:36:27.018534 [DEBUG] switch_xml.c:2073 caching lookup for user 8117396389ip@189.211.21.30 for 0 milliseconds
2015-02-11 11:36:38.658544 [DEBUG] switch_xml.c:1995 Cache Info
Time Now: 1423676198658544
Expires: 1423676187018534
2015-02-11 11:36:38.658544 [DEBUG] switch_xml.c:1997 Cache expired for 8117396389ip@189.211.21.30, doing fresh lookup
2015-02-11 11:36:38.658544 [DEBUG] switch_xml.c:2073 caching lookup for user 8117396389ip@189.211.21.30 for 0 milliseconds
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.478538 [NOTICE] switch_channel.c:1049 New Channel sofia/internal/8117396389ip@189.211.21.30 [96143ce4-b214-11e4-ad21-91614133b1b9]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.478538 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.478538 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.478538 [DEBUG] switch_core_state_machine.c:418 (sofia/internal/8117396389ip@189.211.21.30) Running State Change CS_NEW
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.478538 [DEBUG] sofia.c:7922 sofia/internal/8117396389ip@189.211.21.30 receiving invite from 189.211.235.196:48204 version: 1.2.24 git 5dfc7cf 2014-11-20 22:30:08Z 64bit
2015-02-11 11:37:01.478538 [DEBUG] sofia.c:8079 IP 189.211.235.196 Rejected by acl "domains". Falling back to Digest auth.
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.478538 [DEBUG] switch_core_state_machine.c:437 (sofia/internal/8117396389ip@189.211.21.30) State NEW
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.478538 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
2015-02-11 11:37:01.478538 [DEBUG] sofia.c:1792 detaching session 96143ce4-b214-11e4-ad21-91614133b1b9
2015-02-11 11:37:01.518536 [DEBUG] sofia.c:1899 Re-attaching to session 96143ce4-b214-11e4-ad21-91614133b1b9
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.518536 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.518536 [DEBUG] switch_core_session.c:1016 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.518536 [DEBUG] sofia.c:7922 sofia/internal/8117396389ip@189.211.21.30 receiving invite from 189.211.235.196:48204 version: 1.2.24 git 5dfc7cf 2014-11-20 22:30:08Z 64bit
2015-02-11 11:37:01.518536 [DEBUG] sofia.c:8079 IP 189.211.235.196 Rejected by acl "domains". Falling back to Digest auth.
2015-02-11 11:37:01.518536 [DEBUG] switch_xml.c:1995 Cache Info
Time Now: 1423676221518536
Expires: 1423676198658544
2015-02-11 11:37:01.518536 [DEBUG] switch_xml.c:1997 Cache expired for 8117396389ip@189.211.21.30, doing fresh lookup
2015-02-11 11:37:01.538537 [DEBUG] switch_xml.c:2073 caching lookup for user 8117396389ip@189.211.21.30 for 0 milliseconds
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] sofia.c:5853 Channel sofia/internal/8117396389ip@189.211.21.30 entering state [received][100]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] sofia.c:5866 Remote SDP:
96143ce4-b214-11e4-ad21-91614133b1b9 v=0
96143ce4-b214-11e4-ad21-91614133b1b9 o=Z 0 0 IN IP4 192.168.15.93
96143ce4-b214-11e4-ad21-91614133b1b9 s=Z
96143ce4-b214-11e4-ad21-91614133b1b9 c=IN IP4 192.168.15.93
96143ce4-b214-11e4-ad21-91614133b1b9 t=0 0
96143ce4-b214-11e4-ad21-91614133b1b9 m=audio 47230 RTP/AVP 3 110 98 8 0 101
96143ce4-b214-11e4-ad21-91614133b1b9 a=rtpmap:110 speex/8000
96143ce4-b214-11e4-ad21-91614133b1b9 a=rtpmap:98 iLBC/8000
96143ce4-b214-11e4-ad21-91614133b1b9 a=fmtp:98 mode=20
96143ce4-b214-11e4-ad21-91614133b1b9 a=rtpmap:101 telephone-event/8000
96143ce4-b214-11e4-ad21-91614133b1b9 a=fmtp:101 0-15
96143ce4-b214-11e4-ad21-91614133b1b9 
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] sofia.c:6099 (sofia/internal/8117396389ip@189.211.21.30) State Change CS_NEW -> CS_INIT
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:418 (sofia/internal/8117396389ip@189.211.21.30) Running State Change CS_INIT
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:458 (sofia/internal/8117396389ip@189.211.21.30) State INIT
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:87 sofia/internal/8117396389ip@189.211.21.30 SOFIA INIT
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:127 (sofia/internal/8117396389ip@189.211.21.30) State Change CS_INIT -> CS_ROUTING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:458 (sofia/internal/8117396389ip@189.211.21.30) State INIT going to sleep
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:418 (sofia/internal/8117396389ip@189.211.21.30) Running State Change CS_ROUTING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_channel.c:2165 (sofia/internal/8117396389ip@189.211.21.30) Callstate Change DOWN -> RINGING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:474 (sofia/internal/8117396389ip@189.211.21.30) State ROUTING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:150 sofia/internal/8117396389ip@189.211.21.30 SOFIA ROUTING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:117 sofia/internal/8117396389ip@189.211.21.30 Standard ROUTING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [INFO] mod_dialplan_xml.c:558 Processing 8117396389ip <8117396389ip>->030 in context public
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->unloop] continue=false
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->outside_call] continue=true
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Absolute Condition [outside_call]
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Action set(outside_call=true)
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->call_debug] continue=true
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->public_extensions] continue=false
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [public_extensions] destination_number(030) =~ /^(10[01][0-9])$/ break=on-false
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->public_did] continue=false
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [public_did] destination_number(030) =~ /^(5551212)$/ break=on-false
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->Default_Inbound] continue=false
96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [Default_Inbound] destination_number(030) =~ /^(\d{10})+ip$/ break=on-false
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/8117396389ip@189.211.21.30) State Change CS_ROUTING -> CS_EXECUTE
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:474 (sofia/internal/8117396389ip@189.211.21.30) State ROUTING going to sleep
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:418 (sofia/internal/8117396389ip@189.211.21.30) Running State Change CS_EXECUTE
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/8117396389ip@189.211.21.30) State EXECUTE
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:243 sofia/internal/8117396389ip@189.211.21.30 SOFIA EXECUTE
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:209 sofia/internal/8117396389ip@189.211.21.30 Standard EXECUTE
96143ce4-b214-11e4-ad21-91614133b1b9 EXECUTE sofia/internal/8117396389ip@189.211.21.30 set(outside_call=true)
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_dptools.c:1402 sofia/internal/8117396389ip@189.211.21.30 SET [outside_call]=[true]
96143ce4-b214-11e4-ad21-91614133b1b9 EXECUTE sofia/internal/8117396389ip@189.211.21.30 export(RFC2822_DATE=Wed, 11 Feb 2015 11:37:01 -0600)
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_channel.c:1241 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 11 Feb 2015 11:37:01 -0600]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [NOTICE] switch_core_state_machine.c:262 sofia/internal/8117396389ip@189.211.21.30 has executed the last dialplan instruction, hanging up.
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [NOTICE] switch_core_state_machine.c:264 Hangup sofia/internal/8117396389ip@189.211.21.30 [CS_EXECUTE] [NORMAL_CLEARING]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_channel.c:3189 Send signal sofia/internal/8117396389ip@189.211.21.30 [KILL]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/8117396389ip@189.211.21.30) State EXECUTE going to sleep
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:418 (sofia/internal/8117396389ip@189.211.21.30) Running State Change CS_HANGUP
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:681 (sofia/internal/8117396389ip@189.211.21.30) Callstate Change RINGING -> HANGUP
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:683 (sofia/internal/8117396389ip@189.211.21.30) State HANGUP
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:506 Channel sofia/internal/8117396389ip@189.211.21.30 hanging up, cause: NORMAL_CLEARING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:640 Responding to INVITE with: 480
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:48 sofia/internal/8117396389ip@189.211.21.30 Standard HANGUP, cause: NORMAL_CLEARING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:683 (sofia/internal/8117396389ip@189.211.21.30) State HANGUP going to sleep
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:450 (sofia/internal/8117396389ip@189.211.21.30) State Change CS_HANGUP -> CS_REPORTING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:418 (sofia/internal/8117396389ip@189.211.21.30) Running State Change CS_REPORTING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:767 (sofia/internal/8117396389ip@189.211.21.30) State REPORTING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:92 sofia/internal/8117396389ip@189.211.21.30 Standard REPORTING, cause: NORMAL_CLEARING
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:767 (sofia/internal/8117396389ip@189.211.21.30) State REPORTING going to sleep
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:444 (sofia/internal/8117396389ip@189.211.21.30) State Change CS_REPORTING -> CS_DESTROY
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_session.c:1351 Send signal sofia/internal/8117396389ip@189.211.21.30 [BREAK]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_session.c:1559 Session 8 (sofia/internal/8117396389ip@189.211.21.30) Locked, Waiting on external entities
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [NOTICE] switch_core_session.c:1577 Session 8 (sofia/internal/8117396389ip@189.211.21.30) Ended
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [NOTICE] switch_core_session.c:1581 Close Channel sofia/internal/8117396389ip@189.211.21.30 [CS_DESTROY]
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:572 (sofia/internal/8117396389ip@189.211.21.30) Running State Change CS_DESTROY
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:582 (sofia/internal/8117396389ip@189.211.21.30) State DESTROY
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:399 sofia/internal/8117396389ip@189.211.21.30 SOFIA DESTROY
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:99 sofia/internal/8117396389ip@189.211.21.30 Standard DESTROY
96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] switch_core_state_machine.c:582 (sofia/internal/8117396389ip@189.211.21.30) State DESTROY going to sleep

2015-02-10 15:22 GMT-06:00 Brian West <brian@freeswitch.org>:
Sounds like you might need to do some escaping in there... what does the freeswitch debug log say?

On Tue, Feb 10, 2015 at 12:09 PM, Ing. Antonyam <ing.antonyam@gmail.com> wrote:


> Hi,
>
> I have an issue in xml_curl module. If the user tries calling to PSTN, the call is not routed and the code generates an error similar when the calling plan is not set for PSTN extensions. I've set the plan in the database with "/d" but it doesn't work. If I use a static way inside the xml files works fine.
>
> My calling plan is working when it calls among extensions.
>
> I've validated that sofia status is setting with calling plan for PSTN.
>
> actions = sofia/gateway/${effective_caller_id_numer}/$1
>
>
> Do you have any idea why it's not working when I configure it in the database?
>
> Regards.

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://confluence.freeswitch.org
http://www.cluecon.com

FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org



--

Brian West
brian@freeswitch.org


Twitter: @FreeSWITCH , @briankwest
http://www.freeswitchbook.com
http://www.freeswitchcookbook.com

T:+19184209001 | F:+19184209002 | M:+1918424WEST (9378)
iNUM:+883 5100 1420 9001 | ISN:410*543 | Skype:briankwest


_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://confluence.freeswitch.org
http://www.cluecon.com

FreeSWITCH-users mailing list
FreeSWITCH-users@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
http://www.freeswitch.org