On Wed, Feb 11, 2015 at 10:03 AM, antony aguirre <djdevil8@gmail.com> wrote:
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 InfoTime Now: 1423676187018534Expires: 14236761364585352015-02-11 11:36:27.018534 [DEBUG] switch_xml.c:1997 Cache expired for 8117396389ip@189.211.21.30, doing fresh lookup2015-02-11 11:36:27.018534 [DEBUG] switch_xml.c:2073 caching lookup for user 8117396389ip@189.211.21.30 for 0 milliseconds2015-02-11 11:36:38.658544 [DEBUG] switch_xml.c:1995 Cache InfoTime Now: 1423676198658544Expires: 14236761870185342015-02-11 11:36:38.658544 [DEBUG] switch_xml.c:1997 Cache expired for 8117396389ip@189.211.21.30, doing fresh lookup2015-02-11 11:36:38.658544 [DEBUG] switch_xml.c:2073 caching lookup for user 8117396389ip@189.211.21.30 for 0 milliseconds96143ce4-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_NEW96143ce4-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 64bit2015-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 NEW96143ce4-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-91614133b1b92015-02-11 11:37:01.518536 [DEBUG] sofia.c:1899 Re-attaching to session 96143ce4-b214-11e4-ad21-91614133b1b996143ce4-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 64bit2015-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 InfoTime Now: 1423676221518536Expires: 14236761986585442015-02-11 11:37:01.518536 [DEBUG] switch_xml.c:1997 Cache expired for 8117396389ip@189.211.21.30, doing fresh lookup2015-02-11 11:37:01.538537 [DEBUG] switch_xml.c:2073 caching lookup for user 8117396389ip@189.211.21.30 for 0 milliseconds96143ce4-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=096143ce4-b214-11e4-ad21-91614133b1b9 o=Z 0 0 IN IP4 192.168.15.9396143ce4-b214-11e4-ad21-91614133b1b9 s=Z96143ce4-b214-11e4-ad21-91614133b1b9 c=IN IP4 192.168.15.9396143ce4-b214-11e4-ad21-91614133b1b9 t=0 096143ce4-b214-11e4-ad21-91614133b1b9 m=audio 47230 RTP/AVP 3 110 98 8 0 10196143ce4-b214-11e4-ad21-91614133b1b9 a=rtpmap:110 speex/800096143ce4-b214-11e4-ad21-91614133b1b9 a=rtpmap:98 iLBC/800096143ce4-b214-11e4-ad21-91614133b1b9 a=fmtp:98 mode=2096143ce4-b214-11e4-ad21-91614133b1b9 a=rtpmap:101 telephone-event/800096143ce4-b214-11e4-ad21-91614133b1b9 a=fmtp:101 0-1596143ce4-b214-11e4-ad21-91614133b1b996143ce4-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_INIT96143ce4-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_INIT96143ce4-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 INIT96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:87 sofia/internal/8117396389ip@189.211.21.30 SOFIA INIT96143ce4-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_ROUTING96143ce4-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 sleep96143ce4-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_ROUTING96143ce4-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 -> RINGING96143ce4-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 ROUTING96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:150 sofia/internal/8117396389ip@189.211.21.30 SOFIA ROUTING96143ce4-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 ROUTING96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [INFO] mod_dialplan_xml.c:558 Processing 8117396389ip <8117396389ip>->030 in context public96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->unloop] continue=false96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->outside_call] continue=true96143ce4-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=true96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->public_extensions] continue=false96143ce4-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-false96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->public_did] continue=false96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [public_did] destination_number(030) =~ /^(5551212)$/ break=on-false96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 parsing [public->Default_Inbound] continue=false96143ce4-b214-11e4-ad21-91614133b1b9 Dialplan: sofia/internal/8117396389ip@189.211.21.30 Regex (FAIL) [Default_Inbound] destination_number(030) =~ /^(\d{10})+ip$/ break=on-false96143ce4-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_EXECUTE96143ce4-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 sleep96143ce4-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_EXECUTE96143ce4-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 EXECUTE96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:243 sofia/internal/8117396389ip@189.211.21.30 SOFIA EXECUTE96143ce4-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 EXECUTE96143ce4-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 sleep96143ce4-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_HANGUP96143ce4-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 -> HANGUP96143ce4-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 HANGUP96143ce4-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_CLEARING96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:640 Responding to INVITE with: 48096143ce4-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_CLEARING96143ce4-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 sleep96143ce4-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_REPORTING96143ce4-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_REPORTING96143ce4-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 REPORTING96143ce4-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_CLEARING96143ce4-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 sleep96143ce4-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_DESTROY96143ce4-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 entities96143ce4-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) Ended96143ce4-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_DESTROY96143ce4-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 DESTROY96143ce4-b214-11e4-ad21-91614133b1b9 2015-02-11 11:37:01.538537 [DEBUG] mod_sofia.c:399 sofia/internal/8117396389ip@189.211.21.30 SOFIA DESTROY96143ce4-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 DESTROY96143ce4-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 sleep2015-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.comT:+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
_________________________________________________________________________
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
