Outbound calls through Global Carrier

1,261 views
Skip to first unread message

Kirill Sysoev

unread,
Apr 15, 2012, 9:28:31 AM4/15/12
to 2600h...@googlegroups.com
Hi all!

Can not get Outbound calls working on one of my test installations.

As could be seen below account's "no_match" is linked to "offnet" module, but stepswitch seems to fail to find it:

Apr 15 16:59:48 w1 whistle_apps[6101]: |124d99ab...@192.168.1.238|stepswitch_outbound:45 (<0.831.0>) error attempting global resources to 78129169944
Apr 15 16:59:48 w1 whistle_apps[6101]: |124d99ab...@192.168.1.238|stepswitch_outbound:426 (<0.831.0>) no available resources
Apr 15 16:59:48 w1 whistle_apps[6101]: |124d99ab...@192.168.1.238|cf_offnet:52 (<0.825.0>) offnet request error, attempting to find failure branch for sip:404:NO_ROUTE_DESTINATION
Apr 15 16:59:48 w1 whistle_apps[6101]: |124d99ab...@192.168.1.238|cf_exe:280 (<0.803.0>) attempted undefined child NO_ROUTE_DESTINATION
Apr 15 16:59:48 w1 whistle_apps[6101]: |124d99ab...@192.168.1.238|cf_exe:280 (<0.803.0>) attempted undefined child sip:404

BTW, when I am trying to setup "no_match" callflow through freshly git-installed WinkStart I recieve "Bad request" error.

Thanks in advance,
Kirill

Account's no_match callflow defined as follows:

{
   "_id": "9434ae56ddd87cdecf4d6f68c8146371",
   "_rev": "2-ccc6b1122f3225f3f960f5244810b2de",
   "numbers": [
       "no_match"
   ],
   "flow": {
       "data": {
       },
       "module": "offnet",
       "children": {
       }
   },
   "pvt_type": "callflow",
   "pvt_vsn": "1",
   "pvt_account_id": "c8b0f6f8fc69794c095f1b2ac241a88e",
   "pvt_account_db": "account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e",
   "pvt_created": 63501711929,
   "pvt_modified": 63501711929


Offnet defined as follows:

{
   "_id": "c375da45b4942a917b1ac3b8381587c3",
   "_rev": "3-f5becfee460bdfc093a670d5a4bf8d9e",
   "name": "globaldialogic",
   "rules": [
       ".*"
   ],
   "gateways": [
       {
           "invite_format": "e164",
           "prefix": "",
           "codecs": [
               "PCMU",
               "PCMA"
           ],
           "server": "xx.xx.xx.xx;fs_path=yy.yy.yy.yy",
           "realm": "xx.xx.xx.xx"
       }
   ],
   "pvt_type": "resource"
}


Bad request error from WinkStart "no_match" setup attempt:

Request Method:PUT
Status Code:400 Bad Request
Request Headersview parsed

Darren Schreiber

unread,
Apr 16, 2012, 7:44:54 PM4/16/12
to 2600h...@googlegroups.com
What are the documents in your offnet database?

Please paste them here… remove any identifying information (confidential IP addresses or user/pass)

--
Darren Schreiber
CEO / Co-Founder


 visit: www.2600hz.com
 tel: 415-886-7901

Kirill Sysoev

unread,
Apr 17, 2012, 5:00:16 PM4/17/12
to 2600h...@googlegroups.com
Hi Darren!

Thank you for your help.

Actually there is nothing more to show. The only one document I have in offnet I already provided above.
Plus two design docs:

{
   "_id": "_design/global_resources",
   "_rev": "68-efb5ca6765817fd73e6f93a2617cf4fd",
   "language": "javascript",
   "views": {
       "crossbar_listing": {
           "map": "function(doc) { if (doc.pvt_type != 'resource' || doc.pvt_deleted) return; emit(doc._id, {'id': doc._id, 'name': doc.name, 'enabled': doc.enabled, 'weight': doc.weight_cost}); }"
       }
   }
}


{ "_id": "_design/resources", "_rev": "1-2e4b6a0741420eb999a8bd10174a4219", "language": "javascript", "views": { "listing_active_by_weigth": { "map": "function(doc) { if (doc.pvt_type != 'resource' || ! doc.enabled || doc.pvt_deleted) return; emit([doc.weight_cost, doc._id], doc.name); }" }, "listing_by_id": { "map": "function(doc) { if (doc.pvt_type != 'resource' || doc.pvt_deleted) return; emit(doc._id, doc.name); }" } } }

I have three test installations for now.
- One installation is operated by apps.2600hz.com. And outbound calls were functioning. Actually I erased databases a day ago and didn't set up all the needed documents back yet.
- Second one: accounts are created over crossbar. Latest Winkstart from github used. Everything works fine except outbound calls. The new method to choose in Winkstart which carrier to use seems to be not finished yet. And while trying to create "no_match" callflow I'am receiving an error.
- The third installation indicates the current problem with Cluster Manager. Clicking the Update link makes nothing. Actually all my three installations can not be updated at the moment because of that trouble.


Kind regards,
Kirill

Darren Schreiber

unread,
Apr 18, 2012, 1:27:48 PM4/18/12
to 2600h...@googlegroups.com
Dumb question of the day. Can you do:

/opt/whistle/whistle/utils/command_bridge/command_bridge stepswitch_maintenance reload_resources

And see if it works? Changes to the offnet DB don't take effect immediately.

Also what are you seeing wrong with the new winkstart GUI? It is done for the carriers / resources page…

--
Darren Schreiber
CEO / Co-Founder


 visit: www.2600hz.com
 tel: 415-886-7901

Kirill Sysoev

unread,
Apr 18, 2012, 5:03:06 PM4/18/12
to 2600h...@googlegroups.com
Hi Darren!

Thank you for your help!

1. This is a short movie about what is going on with WinkStart:   http://onnet.su/images/WinkStartGlobalCarrier.avi

2.
I issued "command_bridge stepswitch_maintenance reload_resources" and made some progress. Now I am receiving FORMAT ERROR:

[root@w1 ~]# cat  /var/log/2600hz-platform.log | grep xx
Apr 19 00:22:57 w1 whistle_apps[2210]: |29f91f07...@192.168.1.238|stepswitch_outbound:379 (<0.11749.0>) found resource c375da45b4942a917b1ac3b8381587c3 (sip:78129...@xx.xx.xx.xx;fs_path=yy.yy.yy.yy)
Apr 19 00:22:59 w1 whistle_apps[2210]: |29f91f07...@192.168.1.238|stepswitch_outbound:446 (<0.11749.0>) FORMAT ERROR: "error during outbound request: ~s" [[123,[34,<<"Request">>,34],58,[123,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Account-ID">>,34],58,[34,<<"c8b0f6f8fc69794c095f1b2ac241a88e">>,34],125],44,[34,<<"Dial-Endpoint-Method">>,34],58,[34,<<"single">>,34],44,[34,<<"Outgoing-Caller-ID-Number">>,34],58,[34,<<"78126433400">>,34],44,[34,<<"Outgoing-Caller-ID-Name">>,34],58,[34,<<"Grivtsova 9">>,34],44,[34,<<"Continue-On-Fail">>,34],58,[34,<<"true">>,34],44,[34,<<"Endpoints">>,34],58,[91,[123,[34,<<"Endpoint-Options">>,34],58,<<"{}">>,44,[34,<<"Endpoint-Type">>,34],58,[34,<<"sip">>,34],44,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Resource-ID">>,34],58,[34,<<"c375da45b4942a917b1ac3b8381587c3">>,34],125],44,[34,<<"Codecs">>,34],58,[91,[34,<<"PCMU">>,34],44,[34,<<"PCMA">>,34],93],44,[34,<<"Endpoint-Progress-Timeout">>,34],58,[34,<<"8">>,34],44,[34,<<"Callee-ID-Number">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Callee-ID-Name">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"To-DID">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Route">>,34],58,[34,<<"sip:78129...@xx.xx.xx.xx;fs_path=yy.yy.yy.yy">>,34],44,[34,<<"Invite-Format">>,34],58,[34,<<"e164">>,34],125],93],44,[34,<<"Call-ID">>,34],58,[34,<<"29f91f07...@192.168.1.238">>,34],44,[34,<<"Application-Name">>,34],58,[34,<<"bridge">>,34],44,[34,<<"App-Version">>,34],58,[34,<<"0.2.0">>,34],44,[34,<<"App-Name">>,34],58,[34,<<"stepswitch">>,34],44,[34,<<"Event-Name">>,34],58,[34,<<"command">>,34],44,[34,<<"Event-Category">>,34],58,[34,<<"call">>,34],44,[34,<<"Server-ID">>,34],58,[34,<<"amq.gen-mtuB/qGKzwmlRtX2iJaxpQ==">>,34],125],44,[34,<<"Error-Message">>,34],58,[34,<<"Could not execute dialplan action: bridge">>,34],44,[34,<<"Msg-ID">>,34],58,[34,<<>>,34],44,[34,<<"App-Version">>,34],58,[34,<<"0.8.0">>,34],44,[34,<<"App-Name">>,34],58,[34,<<"ecallmgr">>,34],44,[34,<<"Event-Name">>,34],58,[34,<<"dialplan">>,34],44,[34,<<"Event-Category">
 

3. Cluster Manager works again, but I have the problem with one of my deployments for now:

[Thu, 19 Apr 2012 00:50:27 +0400] DEBUG: Sending HTTP Request via GET to chef001-deploy-ord.2600hz.com:4000/search/node
[Thu, 19 Apr 2012 00:50:28 +0400] DEBUG: Signing the request as w1.onnet.su
[Thu, 19 Apr 2012 00:50:28 +0400] DEBUG: String to sign: 'Method:GET
Hashed Path:IeZMnvmFFqTLi9bpk7bV9LEtQKg=
X-Ops-Content-Hash:2jmj7l5rSw0yVb/vlWAYkK/YBwk=
X-Ops-Timestamp:2012-04-18T20:50:28Z
X-Ops-UserId:w1.onnet.su'
Header hash: {"X-Ops-Content-Hash"=>"2jmj7l5rSw0yVb/vlWAYkK/YBwk=", "X-Ops-Authorization-1"=>"ZHP/ChK8ysLxNtloIxFYybQ0ZxhWph1OD4y45dln1ENhdS9ndvwB0X5gqF5Q", "X-Ops-Userid"=>"w1.onnet.su", "X-Ops-Sign"=>"version=1.0", "X-Ops-Authorization-2"=>"19ZTSfQj4SLHMXsvzqFw4JFpIIdCl9LSfA70iT3cb6z7d65i642nulIH2wtE", "X-Ops-Authorization-3"=>"qOI6JI7yi7hpdxsWH8JqBuvcTnf5pWnazCrMjBrm4Fyzb7zpew+XyfGQmj/8", "X-Ops-Authorization-4"=>"tgCn2QlPbNTQdG9DppfIDGR6tRz4RmnbefK4m8t6VLTjWCjZCMsBaiqtSHtB", "X-Ops-Authorization-5"=>"wi/rLILBtaLk9IODmuBpkEJgmNiAsdZo+KI2c82rHUbXAj+ql09HrFzauzA/", "X-Ops-Authorization-6"=>"r2IQOpxnKIJ9sn61d1Jo6hCwk+G6zrvlaoRmQRR0PA==", "X-Ops-Timestamp"=>"2012-04-18T20:50:28Z"}
[Thu, 19 Apr 2012 00:50:28 +0400] DEBUG: Sending HTTP Request via GET to chef001-deploy-ord.2600hz.com:4000/data/accounts/a2fa5c22475aea1cbecb2bcc461df136
[Thu, 19 Apr 2012 00:50:28 +0400] ERROR: Running exception handlers
[Thu, 19 Apr 2012 00:50:28 +0400] FATAL: Saving node information to /var/chef/cache/failed-run-data.json
[Thu, 19 Apr 2012 00:50:28 +0400] ERROR: Exception handlers complete
[Thu, 19 Apr 2012 00:50:28 +0400] DEBUG: Re-raising exception: NoMethodError - undefined method `[]' for nil:NilClass
/var/chef/cache/cookbooks/hosts/recipes/clients.rb:26:in `from_file'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/recipe_definition_dsl_core.rb:69:in `instance_eval'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/recipe_definition_dsl_core.rb:69:in `method_missing'
  /var/chef/cache/cookbooks/hosts/recipes/clients.rb:20:in `from_file'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/cookbook_version.rb:578:in `load_recipe'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/language_include_recipe.rb:40:in `include_recipe'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/language_include_recipe.rb:27:in `each'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/language_include_recipe.rb:27:in `include_recipe'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/run_context.rb:72:in `load'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/run_context.rb:69:in `each'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/run_context.rb:69:in `load'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/client.rb:195:in `setup_run_context'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/client.rb:159:in `run'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/client.rb:239:in `run_application'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/client.rb:229:in `loop'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/client.rb:229:in `run_application'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application.rb:67:in `run'
  /usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/chef-client:26
  /usr/bin/chef-client:23:in `load'
  /usr/bin/chef-client:23
[Thu, 19 Apr 2012 00:50:28 +0400] FATAL: Stacktrace dumped to /var/chef/cache/chef-stacktrace.out
[Thu, 19 Apr 2012 00:50:28 +0400] DEBUG: NoMethodError: undefined method `[]' for nil:NilClass
/var/chef/cache/cookbooks/hosts/recipes/clients.rb:26:in `from_file'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/recipe_definition_dsl_core.rb:69:in `instance_eval'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/recipe_definition_dsl_core.rb:69:in `method_missing'
/var/chef/cache/cookbooks/hosts/recipes/clients.rb:20:in `from_file'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/cookbook_version.rb:578:in `load_recipe'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/language_include_recipe.rb:40:in `include_recipe'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/language_include_recipe.rb:27:in `each'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/mixin/language_include_recipe.rb:27:in `include_recipe'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/run_context.rb:72:in `load'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/run_context.rb:69:in `each'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/run_context.rb:69:in `load'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/client.rb:195:in `setup_run_context'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/client.rb:159:in `run'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/client.rb:239:in `run_application'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/client.rb:229:in `loop'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application/client.rb:229:in `run_application'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/../lib/chef/application.rb:67:in `run'
/usr/lib64/ruby/gems/1.8/gems/chef-0.10.8/bin/chef-client:26
/usr/bin/chef-client:23:in `load'
/usr/bin/chef-client:23
[Thu, 19 Apr 2012 00:50:28 +0400] FATAL: NoMethodError: undefined method `[]' for nil:NilClass
[root@w1 ~]# 
 

Best regards,
Kirill

Darren Schreiber

unread,
Apr 18, 2012, 5:06:07 PM4/18/12
to 2600h...@googlegroups.com
The format error shouldn't matter, what does your FreeSWITCH log show now?

--
Darren Schreiber
CEO / Co-Founder


 visit: www.2600hz.com
 tel: 415-886-7901
From: Kirill Sysoev <kirill...@gmail.com>
Reply-To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Date: Wednesday, April 18, 2012 2:03 PM
To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Subject: Re: Outbound calls through Global Carrier

James Aimonetti

unread,
Apr 18, 2012, 5:54:33 PM4/18/12
to 2600h...@googlegroups.com
-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

FORMAT_ERROR is ignorable; those are log lines that failed to be
formatted properly (a known bug that will be patched in the next
release). It does not affect anything though.


- --
James Aimonetti
Distributed Systems Engineer / DJ MC_

2600hz | http://2600hz.com
sip:ja...@2600hz.com
tel: 415.886.7905
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.10 (GNU/Linux)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org/

iQEcBAEBAgAGBQJPjzgZAAoJENc77s1OYoGgUOMH/2WW+k8JMReAT2YIr3Eih2Bq
fn7Xj3irFKY6+DmCKrIZpUOFbsXE+o8yiDgEbu3u2sbcJUazPYklTRY8Q27byyqb
RBId+a2nj8LGiUnzbOjM22rNzVF/CAC5JgQqTr63lpM0gigRtQhCqDJwwWzDFGCd
bRrjkPyE/ABhVmE25A6TyB8t1iQzTb+jn5OncMzTqKWurPe6X0mnhPcx1AExbnbm
+KBoJQPtSRyTzREvGsjKRFvvzTwK4h7IXssyv+CLlAyEFT2wedAw7UETehaQOYiI
VfaUq+QLwQT2WcXmUjtjm8cWAUihqpDx62+gErofSto4JPPD35vXFuAYTsjCHnQ=
=D2q4
-----END PGP SIGNATURE-----

Kirill Sysoev

unread,
Apr 23, 2012, 8:16:28 AM4/23/12
to 2600h...@googlegroups.com
Darren, James!

Thank you for your answers!

This is the output from freeswitch:
(may be this could be the answer:  2012-04-23 15:54:59.138614 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle error while building command bridge: registrar returned no endpoints)


Best Regards,
Kirill

freeswitch@internal>
2012-04-23 15:54:57.430623 [INFO] sofia.c:7566 94.125.0.113 is a proxy according to the authoritative acl
2012-04-23 15:54:57.430623 [WARNING] sofia_reg.c:1428 SIP auth challenge (INVITE) on sofia profile 'sipinterface_1' for [78129...@office.sip2.onnet.su] from ip 94.125.0.113
2012-04-23 15:54:57.466500 [INFO] sofia.c:7566 94.125.0.113 is a proxy according to the authoritative acl
2012-04-23 15:54:57.480708 [NOTICE] switch_channel.c:926 New Channel sofia/sipinterface_1/user_...@office.sip2.onnet.su [a87aeb1b...@192.168.1.201]
2012-04-23 15:54:57.480708 [INFO] mod_dialplan_xml.c:485 Processing user_mga4xs <user_mga4xs>->78129169944 in context context_2
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set effective_caller_id_number=78124906700
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set effective_caller_id_name=OnNet office
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set ecallmgr_Authorizing-ID=4a045948b6ed9e99563c86992400b6f1
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set ecallmgr_Inception=on-net
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set ecallmgr_Authorizing-Type=device
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set ecallmgr_Account-ID=c8b0f6f8fc69794c095f1b2ac241a88e
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set ecallmgr_Realm=office.sip2.onnet.su
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set ecallmgr_Username=user_mga4xs
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set local_var_clobber=true
2012-04-23 15:54:57.622512 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing set ecallmgr_Owner-ID=4a045948b6ed9e99563c86992400b46f
2012-04-23 15:54:59.138614 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle error while building command bridge: registrar returned no endpoints
2012-04-23 15:54:59.150607 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing pre_answer
2012-04-23 15:54:59.162609 [INFO] mod_dptools.c:1161 Sending early media
2012-04-23 15:54:59.175480 [NOTICE] sofia_glue.c:4017 Pre-Answer sofia/sipinterface_1/user_...@office.sip2.onnet.su!
2012-04-23 15:54:59.970669 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing playback shell_stream:///usr/local/bin/fetch_remote_audio.sh http://w2.onnet.su:24517/single/system_media/fault-can_not_be_completed_at_this_time/fault-can_not_be_completed_at_this_time.wav
2012-04-23 15:55:04.366950 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing respond 503 Normal temporary failure
2012-04-23 15:55:04.414957 [NOTICE] sofia.c:6293 Hangup sofia/sipinterface_1/user_...@office.sip2.onnet.su [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
2012-04-23 15:55:04.414957 [NOTICE] switch_core_session.c:1398 Session 36 (sofia/sipinterface_1/user_...@office.sip2.onnet.su) Ended
2012-04-23 15:55:04.414957 [NOTICE] switch_core_session.c:1400 Close Channel sofia/sipinterface_1/user_...@office.sip2.onnet.su [CS_DESTROY]
2012-04-23 15:55:04.486939 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle executing event Event-Subclass=whistle::noop,Event-Name=CUSTOM,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=noop,whistle_application_response=dc641f6344dca5371fa3e15bed3e433e
2012-04-23 15:55:04.486939 [NOTICE] mod_commands.c:5305 log|a87aeb1b...@192.168.1.201|whistle terminating call
2012-04-23 15:55:04.726955 [INFO] sofia.c:7566 94.125.0.113 is a proxy according to the authoritative acl
2012-04-23 15:55:04.726955 [WARNING] sofia_reg.c:1428 SIP auth challenge (INVITE) on sofia profile 'sipinterface_1' for [78129...@office.sip2.onnet.su] from ip 94.125.0.113
freeswitch@internal>


Kirill Sysoev

unread,
Apr 24, 2012, 5:01:40 AM4/24/12
to 2600h...@googlegroups.com
Apr 24 12:51:55 w1 whistle_apps[4913]: |4adf44a2...@192.168.1.138|cf_route_req:20 (<0.992.0>) received route request
Apr 24 12:51:55 w1 whistle_apps[4913]: |4adf44a2...@192.168.1.138|cf_util:281 (<0.992.0>) searching for callflow in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e to satisfy '78129169944'
Apr 24 12:51:55 w1 whistle_apps[4913]: |4adf44a2...@192.168.1.138|cf_util:318 (<0.992.0>) lookup callflow patterns for 78129169944 in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e
Apr 24 12:51:55 w1 whistle_apps[4913]: |4adf44a2...@192.168.1.138|cf_util:281 (<0.992.0>) searching for callflow in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e to satisfy 'no_match'
Apr 24 12:51:55 w1 whistle_apps[4913]: |4adf44a2...@192.168.1.138|cf_route_req:23 (<0.992.0>) callflow 4a045948b6ed9e99563c86992467f4f5 in c8b0f6f8fc69794c095f1b2ac241a88e satisfies request
Apr 24 12:51:55 w1 whistle_apps[4913]: |4adf44a2...@192.168.1.138|cf_route_req:79 (<0.992.0>) sent route response to park the call
Apr 24 12:51:55 w1 whistle_apps[4913]: <0.1003.0> CRASH REPORT Process <0.1003.0> with 0 neighbours crashed with reason: {badmatch,false}
Apr 24 12:51:55 w1 whistle_apps[4913]: <0.456.0> Supervisor ts_onnet_sup had child <<"onnet-onnet-4a...@192.168.1.138">> started with {ts_from_onnet,start_link,undefined} at <0.1003.0> exit with reason {badmatch,false} in context child_terminated

Darren Schreiber

unread,
Apr 24, 2012, 9:01:41 AM4/24/12
to 2600h...@googlegroups.com
Are there more logs then this?

ts_from_onnet is trunkstore – are you using trunkstore … ? If not, that crash is not erroneous. There should be another error.

--
Darren Schreiber
CEO / Co-Founder


 visit: www.2600hz.com
 tel: 415-886-7901
From: Kirill Sysoev <kirill...@gmail.com>
Reply-To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Date: Tuesday, April 24, 2012 2:01 AM
To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Subject: Re: Outbound calls through Global Carrier

Kirill Sysoev

unread,
Apr 24, 2012, 9:45:34 AM4/24/12
to 2600h...@googlegroups.com
Hi Darren!

Here it is:
 
[root@w1 ~]# cat /var/log/2600hz-platform.log 
Apr 24 17:39:22 w1 2600hz[26798]: |frees...@w6.onnet.su|ecallmgr_fs_auth:120 (<0.762.0>) received fetch request for INVITE (567b3bf2-5a58-4249-a630-8782eb8f9987) user creds from frees...@w6.onnet.su
Apr 24 17:39:22 w1 2600hz[26798]: |567b3bf2-5a58-4249-a630-8782eb8f9987|ecallmgr_fs_auth:250 (<0.1276.0>) looking up credentials of user_...@office.sip2.onnet.su for a INVITE
Apr 24 17:39:22 w1 whistle_apps[26557]: |567b3bf2-5a58-4249-a630-8782eb8f9987|reg_authn_req:24 (<0.5005.0>) received SIP authentication request
Apr 24 17:39:22 w1 whistle_apps[26557]: |567b3bf2-5a58-4249-a630-8782eb8f9987|reg_util:102 (<0.5005.0>) looking up auth creds for user_...@office.sip2.onnet.su
Apr 24 17:39:22 w1 whistle_apps[26557]: |567b3bf2-5a58-4249-a630-8782eb8f9987|wh_util:107 (<0.5005.0>) account c8b0f6f8fc69794c095f1b2ac241a88e enabled flag is true
Apr 24 17:39:22 w1 whistle_apps[26557]: |567b3bf2-5a58-4249-a630-8782eb8f9987|reg_util:124 (<0.5005.0>) pulling auth user from cache
Apr 24 17:39:22 w1 whistle_apps[26557]: |567b3bf2-5a58-4249-a630-8782eb8f9987|reg_authn_req:63 (<0.5005.0>) sending SIP authentication reply, with credentials
Apr 24 17:39:22 w1 2600hz[26798]: |567b3bf2-5a58-4249-a630-8782eb8f9987|ecallmgr_fs_auth:258 (<0.1276.0>) received authn_resp
Apr 24 17:39:22 w1 2600hz[26798]: |567b3bf2-5a58-4249-a630-8782eb8f9987|ecallmgr_fs_auth:263 (<0.1276.0>) sending XML to 'frees...@w6.onnet.su': <document type="freeswitch/xml">#012  <section name="directory">#012    <domain name="office.sip2.onnet.su">#012      <user id="user_mfwcdo">#012        <params>#012          <param name="password" value="lyh0ygfohojs"/>#012        </params>#012        <variables>#012                    <variable name="ecallmgr_Authorizing-ID" value="ea9869047a369924ef4b8de9bd23f9af" />#012          <variable name="ecallmgr_Inception" value="on-net" />#012          <variable name="ecallmgr_Authorizing-Type" value="device" />#012          <variable name="ecallmgr_Account-ID" value="c8b0f6f8fc69794c095f1b2ac241a88e" />#012          <variable name="ecallmgr_Realm" value="office.sip2.onnet.su" />#012          <variable name="ecallmgr_Username" value="user_mfwcdo" />#012#012        </variables>#012      </user>#012    </domain>#012  </section>#012</document>
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_route_req:20 (<0.5010.0>) received route request
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|ts_route_req:31 (<0.5012.0>) onnet call starting
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_util:281 (<0.5010.0>) searching for callflow in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e to satisfy '78129169944'
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.5013.0> CRASH REPORT Process <0.5013.0> with 0 neighbours crashed with reason: {badmatch,false}
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_util:318 (<0.5010.0>) lookup callflow patterns for 78129169944 in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.472.0> Supervisor ts_onnet_sup started ts_from_onnet:start_link({struct,[{<<"Custom-Channel-Vars">>,{struct,[{<<"Username">>,<<"user_mfwcdo">>},{<<"Realm">>,<<...>>},...]}},...]}) at pid <0.5013.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.472.0> Supervisor ts_onnet_sup had child <<"onnet-onnet-5f...@192.168.1.138">> started with {ts_from_onnet,start_link,undefined} at <0.5013.0> exit with reason {badmatch,false} in context child_terminated
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_util:281 (<0.5010.0>) searching for callflow in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e to satisfy 'no_match'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_route_req:23 (<0.5010.0>) callflow 4a045948b6ed9e99563c8699248e0ba2 in c8b0f6f8fc69794c095f1b2ac241a88e satisfies request
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_route_req:79 (<0.5010.0>) sent route response to park the call
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_route_win:20 (<0.5021.0>) received route win
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_route_win:23 (<0.5021.0>) bootstrapping callflow executer
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:324 (<0.5021.0>) object ea9869047a369924ef4b8de9bd23f9af is owned by 9434ae56ddd87cdecf4d6f68c813f027
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:117 (<0.5021.0>) find external caller id on ea9869047a369924ef4b8de9bd23f9af, 9434ae56ddd87cdecf4d6f68c813f027, c8b0f6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:129 (<0.5021.0>) found external caller id on ea9869047a369924ef4b8de9bd23f9af
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:385 (<0.5021.0>) find friendly name on 9434ae56ddd87cdecf4d6f68c813f027, ea9869047a369924ef4b8de9bd23f9af
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:392 (<0.5021.0>) using name 'Office Administrator' from 9434ae56ddd87cdecf4d6f68c813f027
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:142 (<0.5021.0>) attempting to prepend caller id 78124906700 'Office Administrator'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:146 (<0.5021.0>) using caller id 78124906700 'Office Administrator'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:324 (<0.5021.0>) object ea9869047a369924ef4b8de9bd23f9af is owned by 9434ae56ddd87cdecf4d6f68c813f027
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:289 (<0.5021.0>) find moh attr media_id on ea9869047a369924ef4b8de9bd23f9af, 9434ae56ddd87cdecf4d6f68c813f027, c8b0f6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:293 (<0.5021.0>) unable to find moh attribute media_id
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_route_win:52 (<0.5021.0>) call has been setup, passing control to callflow executer
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|gen_listener:198 (<0.5025.0>) starting new gen_listener proc: cf_exe
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:229 (<0.5025.0>) executing callflow 4a045948b6ed9e99563c8699248e0ba2
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:230 (<0.5025.0>) account id c8b0f6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:231 (<0.5025.0>) request 78129...@office.sip2.onnet.su
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:232 (<0.5025.0>) to 78129...@office.sip2.onnet.su
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:233 (<0.5025.0>) from user_...@office.sip2.onnet.su
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:234 (<0.5025.0>) CID Office Administrator 78124906700
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:235 (<0.5025.0>) inception on-net
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:236 (<0.5025.0>) authorizing id ea9869047a369924ef4b8de9bd23f9af
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.5028.0> Supervisor amqp_channel_sup started undefined at pid <0.5029.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.5028.0> Supervisor amqp_channel_sup started undefined at pid <0.5030.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.5028.0> Supervisor amqp_channel_sup started undefined at pid <0.5031.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:559 (<0.119.0>) Opened channel <0.5030.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:574 (<0.119.0>) Started channel <0.5030.0> for caller <0.5025.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:208 (<0.119.0>) Channel <0.5030.0> exists for proc <0.5025.0>, but we aren't consuming yet
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:466 (<0.5034.0>) sending exchange.declare to <0.126.0> (federated: false)
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:469 (<0.5034.0>) exchange declared
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:466 (<0.5036.0>) sending exchange.declare to <0.126.0> (federated: false)
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:469 (<0.5036.0>) exchange declared
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:466 (<0.5042.0>) sending exchange.declare to <0.126.0> (federated: false)
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:469 (<0.5042.0>) exchange declared
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|gen_listener:413 (<0.5025.0>) consuming from our queue
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:479 (<0.5025.0>) moving to action cf_offnet
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:117 (<0.5046.0>) find emergency caller id on ea9869047a369924ef4b8de9bd23f9af, 9434ae56ddd87cdecf4d6f68c813f027, c8b0f6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:385 (<0.5046.0>) find friendly name on 9434ae56ddd87cdecf4d6f68c813f027, ea9869047a369924ef4b8de9bd23f9af
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:392 (<0.5046.0>) using name 'Office Administrator' from 9434ae56ddd87cdecf4d6f68c813f027
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:142 (<0.5046.0>) attempting to prepend caller id 78124906700 'Office Administrator'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:146 (<0.5046.0>) using caller id 78124906700 'Office Administrator'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:117 (<0.5046.0>) find external caller id on ea9869047a369924ef4b8de9bd23f9af, 9434ae56ddd87cdecf4d6f68c813f027, c8b0f6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:129 (<0.5046.0>) found external caller id on ea9869047a369924ef4b8de9bd23f9af
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:385 (<0.5046.0>) find friendly name on 9434ae56ddd87cdecf4d6f68c813f027, ea9869047a369924ef4b8de9bd23f9af
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:392 (<0.5046.0>) using name 'Office Administrator' from 9434ae56ddd87cdecf4d6f68c813f027
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:142 (<0.5046.0>) attempting to prepend caller id 78124906700 'Office Administrator'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_attributes:146 (<0.5046.0>) using caller id 78124906700 'Office Administrator'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:35 (<0.5050.0>) received outbound request
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_config:217 (<0.5050.0>) fetched config stepswitch(default) [<<"outbound_user_field">>]: <<"Request">>
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:39 (<0.5050.0>) outbound request to 78129169944 from account c8b0f6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|wh_number_manager:401 (<0.5050.0>) attempting to lookup '78129169944' in 'numbers%2F78129'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|wh_number_manager:422 (<0.5050.0>) failed to lookup number: not_found
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_util:35 (<0.5050.0>) 78129169944 is not associated with any account, not_found
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:379 (<0.5050.0>) found resource c375da45b4942a917b1ac3b8381587c3 (sip:78129...@94.125.0.218;fs_path=94.125.0.114)
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:91 (<0.5050.0>) found resources that handle the number...to the cloud!
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.5053.0> Supervisor amqp_channel_sup started undefined at pid <0.5054.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.5053.0> Supervisor amqp_channel_sup started undefined at pid <0.5055.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: <0.5053.0> Supervisor amqp_channel_sup started undefined at pid <0.5056.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:559 (<0.119.0>) Opened channel <0.5055.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:574 (<0.119.0>) Started channel <0.5055.0> for caller <0.5050.0>
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:208 (<0.119.0>) Channel <0.5055.0> exists for proc <0.5050.0>, but we aren't consuming yet
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:466 (<0.5059.0>) sending exchange.declare to <0.126.0> (federated: false)
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:469 (<0.5059.0>) exchange declared
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:466 (<0.5061.0>) sending exchange.declare to <0.126.0> (federated: false)
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:469 (<0.5061.0>) exchange declared
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:466 (<0.5065.0>) sending exchange.declare to <0.126.0> (federated: false)
Apr 24 17:39:22 w1 whistle_apps[26557]: |00000000000|amqp_host:469 (<0.5065.0>) exchange declared
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:271 (<0.5050.0>) consuming call events
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:284 (<0.5050.0>) sending rate request
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:105 (<0.5050.0>) set outbound caller id to 78124906700 'Office Administrator'
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_config:217 (<0.5050.0>) fetched config stepswitch(default) [<<"format_from_uri">>]: false
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:116 (<0.5050.0>) setting from-uri to undefined
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_config:217 (<0.5050.0>) fetched config stepswitch(default) [<<"bridge_timeout">>]: 30000
Apr 24 17:39:22 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|hon_rater:25 (<0.5072.0>) valid rating request
Apr 24 17:39:22 w1 whistle_apps[26557]: emulator Error in process <0.5072.0> on node 'whistl...@w1.onnet.su' with exit value: {{badmatch,<<11 bytes>>},[{hon_util,candidate_rates,2},{hon_rater,get_rate_data,1},{hon_rater,handle_req,2}]}#012
Apr 24 17:39:23 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_config:217 (<0.5050.0>) fetched config stepswitch(default) [<<"max_shortdial_correction">>]: 5
Apr 24 17:39:23 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:45 (<0.5050.0>) error attempting global resources to 78129169944
Apr 24 17:39:23 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|stepswitch_outbound:446 (<0.5050.0>) FORMAT ERROR: "error during outbound request: ~s" [[123,[34,<<"Request">>,34],58,[123,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Account-ID">>,34],58,[34,<<"c8b0f6f8fc69794c095f1b2ac241a88e">>,34],125],44,[34,<<"Dial-Endpoint-Method">>,34],58,[34,<<"single">>,34],44,[34,<<"Outgoing-Caller-ID-Number">>,34],58,[34,<<"78124906700">>,34],44,[34,<<"Outgoing-Caller-ID-Name">>,34],58,[34,<<"Office Administrator">>,34],44,[34,<<"Continue-On-Fail">>,34],58,[34,<<"true">>,34],44,[34,<<"Endpoints">>,34],58,[91,[123,[34,<<"Endpoint-Options">>,34],58,<<"{}">>,44,[34,<<"Endpoint-Type">>,34],58,[34,<<"sip">>,34],44,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Resource-ID">>,34],58,[34,<<"c375da45b4942a917b1ac3b8381587c3">>,34],125],44,[34,<<"Codecs">>,34],58,[91,[34,<<"PCMU">>,34],44,[34,<<"PCMA">>,34],93],44,[34,<<"Endpoint-Progress-Timeout">>,34],58,[34,<<"8">>,34],44,[34,<<"Callee-ID-Number">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Callee-ID-Name">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"To-DID">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Route">>,34],58,[34,<<"sip:78129...@94.125.0.218;fs_path=94.125.0.114">>,34],44,[34,<<"Invite-Format">>,34],58,[34,<<"e164">>,34],125],93],44,[34,<<"Call-ID">>,34],58,[34,<<"5f463df-...@192.168.1.138">>,34],44,[34,<<"Application-Name">>,34],58,[34,<<"bridge">>,34],44,[34,<<"App-Version">>,34],58,[34,<<"0.2.0">>,34],44,[34,<<"App-Name">>,34],58,[34,<<"stepswitch">>,34],44,[34,<<"Event-Name">>,34],58,[34,<<"command">>,34],44,[34,<<"Event-Category">>,34],58,[34,<<"call">>,34],44,[34,<<"Server-ID">>,34],58,[34,<<"amq.gen-aquRNrZpts5J0pgJNZA/Rw==">>,34],125],44,[34,<<"Error-Message">>,34],58,[34,<<"Could not execute dialplan action: bridge">>,34],44,[34,<<"Msg-ID">>,34],58,[34,<<>>,34],44,[34,<<"App-Version">>,34],58,[34,<<"0.8.0">>,34],44,[34,<<"App-Name">>,34],58,[34,<<"ecallmgr">>,34],44,[34,<<"Event-Name">>,34],58,[34,<<"dialplan">>,34],44,[34,<<"Event-Cat
Apr 24 17:39:23 w1 whistle_apps[26557]: |00000000000|amqp_host:499 (<0.119.0>) recieved notification monitored process <0.5050.0>  died normal, searching for reference
Apr 24 17:39:23 w1 whistle_apps[26557]: |00000000000|amqp_host:637 (<0.119.0>) reference was for consumer <0.5050.0>, removing channel <0.5055.0>
Apr 24 17:39:23 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_offnet:52 (<0.5046.0>) offnet request error, attempting to find failure branch for sip:500:NORMAL_TEMPORARY_FAILURE
Apr 24 17:39:23 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:280 (<0.5025.0>) attempted undefined child NORMAL_TEMPORARY_FAILURE
Apr 24 17:39:23 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:280 (<0.5025.0>) attempted undefined child sip:500
Apr 24 17:39:23 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|wh_call_response:105 (<0.5046.0>) attempting to send default response for NORMAL_TEMPORARY_FAILURE
Apr 24 17:39:23 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_config:217 (<0.5046.0>) fetched config call_response(default) [<<"NORMAL_TEMPORARY_FAILURE">>]: {struct,[{<<"Code">>,<<"503">>},{<<"Message">>,<<"Normal temporary failure">>},{<<"Media">>,<<"/system_media/fault-can_not_be_completed_at_this_time">>}]}
Apr 24 17:39:23 w1 whistle_apps[26557]: <0.5055.0> Channel (<0.5055.0>): dropping method {{'basic.cancel',<<"amq.gen-aquRNrZpts5J0pgJNZA/Rw==">>,true},none} from server because channel is closing
Apr 24 17:39:24 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|media_listener:195 (<0.5126.0>) trying to find first attachment in doc fault-can_not_be_completed_at_this_time in db system_media
Apr 24 17:39:24 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|media_listener:206 (<0.5126.0>) found attachment to stream: fault-can_not_be_completed_at_this_time.wav
Apr 24 17:39:24 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_config:217 (<0.5126.0>) fetched config media_mgr(default) [<<"port">>]: 24517
Apr 24 17:39:24 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_config:217 (<0.5126.0>) fetched config media_mgr(default) [<<"use_vlc">>]: false
Apr 24 17:39:24 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|media_listener:52 (<0.5126.0>) file server at <0.4833.0> for system_media/fault-can_not_be_completed_at_this_time/fault-can_not_be_completed_at_this_time.wav
Apr 24 17:39:24 w1 whistle_apps[26557]: |5a54f719528c338c98d16b5e4da169e4|media_single:20 (<0.5130.0>) fetching system_media/fault-can_not_be_completed_at_this_time/fault-can_not_be_completed_at_this_time.wav
Apr 24 17:39:24 w1 whistle_apps[26557]: |00000000000|media_file:114 (<0.4833.0>) returning media contents
Apr 24 17:39:24 w1 whistle_apps[26557]: |5a54f719528c338c98d16b5e4da169e4|media_single:63 (<0.5130.0>) terminating
Apr 24 17:39:25 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_call_command:1122 (<0.5046.0>) channel was hungup while waiting for noop
Apr 24 17:39:25 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:317 (<0.5025.0>) continuing to child _
Apr 24 17:39:25 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:320 (<0.5025.0>) wildcard child does not exist, we are lost...
Apr 24 17:39:25 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cf_exe:446 (<0.5025.0>) callflow execution has been stopped: normal
Apr 24 17:39:25 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|gen_listener:457 (<0.5025.0>) cf_exe terminated cleanly, going down
Apr 24 17:39:25 w1 whistle_apps[26557]: |00000000000|amqp_host:499 (<0.119.0>) recieved notification monitored process <0.5025.0>  died normal, searching for reference
Apr 24 17:39:25 w1 whistle_apps[26557]: |00000000000|amqp_host:637 (<0.119.0>) reference was for consumer <0.5025.0>, removing channel <0.5030.0>
Apr 24 17:39:25 w1 whistle_apps[26557]: <0.5030.0> Channel (<0.5030.0>): dropping method {{'basic.cancel',<<"amq.gen-FvAPkGZEyHlqE6QWTxb0uQ==">>,true},none} from server because channel is closing
Apr 24 17:39:25 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|whapps_config:217 (<0.5148.0>) fetched config hangups(default) [<<"ignore_hangup_causes">>]: [<<"NO_ANSWER">>,<<"USER_BUSY">>,<<"NO_USER_RESPONSE">>,<<"LOSE_RACE">>,<<"ATTENDED_TRANSFER">>,<<"ORIGINATOR_CANCEL">>,<<"NORMAL_CLEARING">>]
Apr 24 17:39:25 w1 whistle_apps[26557]: |5f463df-...@192.168.1.138|cdr_listener:70 (<0.5147.0>) saving CDR to account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:26 w1 whistle_apps[26557]: |00000000000|cf_util:281 (<0.5154.0>) searching for callflow in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e to satisfy 'user_mfwcdo'
Apr 24 17:39:26 w1 whistle_apps[26557]: |00000000000|cf_util:318 (<0.5154.0>) lookup callflow patterns for user_mfwcdo in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e
Apr 24 17:39:26 w1 whistle_apps[26557]: |00000000000|cf_util:281 (<0.5154.0>) searching for callflow in account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e to satisfy 'no_match'
[root@w1 ~]# 



###############################################################################
#############################  FreeSWITCH logs #################################
###############################################################################

2012-04-24 17:39:28.295681 [INFO] sofia.c:7566 94.125.0.113 is a proxy according to the authoritative acl
2012-04-24 17:39:28.295681 [WARNING] sofia_reg.c:1428 SIP auth challenge (INVITE) on sofia profile 'sipinterface_1' for [78129...@office.sip2.onnet.su] from ip 94.125.0.113
2012-04-24 17:39:28.367676 [INFO] sofia.c:7566 94.125.0.113 is a proxy according to the authoritative acl
2012-04-24 17:39:28.380968 [NOTICE] switch_channel.c:926 New Channel sofia/sipinterface_1/user_...@office.sip2.onnet.su [5f463df-...@192.168.1.138]
2012-04-24 17:39:28.380968 [INFO] mod_dialplan_xml.c:485 Processing user_mfwcdo <user_mfwcdo>->78129169944 in context context_2
2012-04-24 17:39:28.667702 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set effective_caller_id_number=78124906700
2012-04-24 17:39:28.667702 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set effective_caller_id_name=Office Administrator
2012-04-24 17:39:28.667702 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set ecallmgr_Authorizing-ID=ea9869047a369924ef4b8de9bd23f9af
2012-04-24 17:39:28.680572 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set ecallmgr_Inception=on-net
2012-04-24 17:39:28.680572 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set ecallmgr_Authorizing-Type=device
2012-04-24 17:39:28.680572 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set ecallmgr_Account-ID=c8b0f6f8fc69794c095f1b2ac241a88e
2012-04-24 17:39:28.680572 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set ecallmgr_Realm=office.sip2.onnet.su
2012-04-24 17:39:28.680572 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set ecallmgr_Username=user_mfwcdo
2012-04-24 17:39:28.680572 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set local_var_clobber=true
2012-04-24 17:39:28.680572 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing set ecallmgr_Owner-ID=9434ae56ddd87cdecf4d6f68c813f027
2012-04-24 17:39:30.207809 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle error while building command bridge: registrar returned no endpoints
2012-04-24 17:39:30.219821 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing pre_answer
2012-04-24 17:39:30.231803 [INFO] mod_dptools.c:1161 Sending early media
2012-04-24 17:39:30.243803 [NOTICE] sofia_glue.c:4017 Pre-Answer sofia/sipinterface_1/user_...@office.sip2.onnet.su!
2012-04-24 17:39:30.831837 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing playback shell_stream:///usr/local/bin/fetch_remote_audio.sh http://w1.onnet.su:24517/single/system_media/fault-can_not_be_completed_at_this_time/fault-can_not_be_completed_at_this_time.wav
2012-04-24 17:39:32.139976 [NOTICE] sofia.c:6293 Hangup sofia/sipinterface_1/user_...@office.sip2.onnet.su [CS_EXECUTE] [ORIGINATOR_CANCEL]
2012-04-24 17:39:32.139976 [NOTICE] switch_core_session.c:1398 Session 95 (sofia/sipinterface_1/user_...@office.sip2.onnet.su) Ended
2012-04-24 17:39:32.139976 [NOTICE] switch_core_session.c:1400 Close Channel sofia/sipinterface_1/user_...@office.sip2.onnet.su [CS_DESTROY]
2012-04-24 17:39:32.154169 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle executing respond 503 Normal temporary failure
2012-04-24 17:39:32.163916 [NOTICE] mod_commands.c:5305 log|5f463df-...@192.168.1.138|whistle terminating call
freeswitch@internal> 
 

Kirill Sysoev

unread,
Apr 24, 2012, 11:09:24 AM4/24/12
to 2600h...@googlegroups.com
At the same time when I am switching to "User a different carrier" outbound calls are "working"
(;fs_path=opensips_ip_address doesn't work in both cases)

{
   "_id": "4a045948b6ed9e99563c8699248e0688",
   "_rev": "3-e81b1aba1cf8f0a79e3b441b617f411b",
   "weight_cost": "50",
   "enabled": true,
   "gateways": [
       
{
           "invite_format": "e164",
           "prefix": "",
           "codecs": [
               "PCMU",
               "PCMA"
           ]
,
           "progress_timeout": "6",
           "server": "94.125.0.218",
           "username": "",
           "password": "",
           "realm": "whistle.onnet.su",
           "format_from_uri": true,
           "suffix": ""
       }
   ],
   "rules": [
       ".*"
   ],
   "caller_id_options": {
       "type": "external"
   },
   "type": "local",
   "name": "gw",
   "emergency": false,
   "grace_period": 5,
   "flags": [
   ],
   "id": "4a045948b6ed9e99563c8699248e0688",
   "pvt_type": "resource",
   "pvt_vsn": "1",
   "pvt_account_id": "c8b0f6f8fc69794c095f1b2ac241a88e",
   "pvt_account_db": "account%2Fc8%2Fb0%2Ff6f8fc69794c095f1b2ac241a88e",
   "pvt_created": 63502491256,
   "pvt_modified": 63502497916
}

Darren Schreiber

unread,
Apr 24, 2012, 11:15:55 AM4/24/12
to 2600h...@googlegroups.com
it does not sound like you are configuring this in the global "offnet" database?

--
Darren Schreiber
CEO / Co-Founder


 visit: www.2600hz.com
 tel: 415-886-7901
From: Kirill Sysoev <kirill...@gmail.com>
Reply-To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Date: Tuesday, April 24, 2012 8:09 AM
To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Subject: Re: Outbound calls through Global Carrier

Kirill Sysoev

unread,
Apr 24, 2012, 11:21:55 AM4/24/12
to 2600h...@googlegroups.com
Despite _design documents I have the only one document in offnet:
( "format_from_uri": true, - was added couple minutes ago just to see whether it will help)


{
   "_id": "c375da45b4942a917b1ac3b8381587c3",
   "_rev": "10-e6b5a7780c2775da816944195bd512b5",
   "name": "globaldialogic",
   "enabled": true,
   "gateways": [
       
{
           "invite_format": "e164",
           "prefix": "",
           "codecs": [
               "PCMU",
               "PCMA"
           ],
           "progress_timeout": "6",
           "server": "94.125.0.218",
           "username": "",
           "password": "",
           "realm": "whistle.onnet.su",
           "format_from_uri": true,
           "suffix": ""
       }
   ]
,
   "rules": [
       ".*"
   ],
   "pvt_type": "resource"
}

Darren Schreiber

unread,
Apr 24, 2012, 11:25:57 AM4/24/12
to 2600h...@googlegroups.com
Now I'm really confused, all your previous posts complained that global carriers weren't working. Are you saying you never had any docs in the offnet database?!

If you just added them you will need to restart stepswitch or reload stepswitch resources. Restarting whapps might be easiest explanation for here ;-) if it's non-production

--
Darren Schreiber
CEO / Co-Founder


 visit: www.2600hz.com
 tel: 415-886-7901
From: Kirill Sysoev <kirill...@gmail.com>
Reply-To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Date: Tuesday, April 24, 2012 8:21 AM
To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Subject: Re: Outbound calls through Global Carrier

Kirill Sysoev

unread,
Apr 24, 2012, 11:36:38 AM4/24/12
to 2600h...@googlegroups.com
Stop ....  :)))

I am trying to say that I just added format_from_uri option to the document ("name": "globaldialogic") that was in offnet database for a long time...

Sorry for my english.

Kirill Sysoev

unread,
Apr 24, 2012, 11:49:54 AM4/24/12
to 2600h...@googlegroups.com
Darren, 

I am getting confused....

I was tinking that exactly this document in offnet database describes "Global Carrier":

{
   "_id": "c375da45b4942a917b1ac3b8381587c3",
   "_rev": "10-e6b5a7780c2775da816944195bd512b5",
   "name": "globaldialogic",
   "enabled": true,
   "gateways": [
       
{ "invite_format": "e164", "prefix": "", "codecs": [ "PCMU", "PCMA" ], "progress_timeout": "6", "server": "94.125.0.218", "username": "", "password": "", "realm": "whistle.onnet.su", "format_from_uri": true, "suffix": "" } ]
, "rules": [ ".*" ], "pvt_type": "resource" 
}

  Rule ".*" says that it could be used as a default route and because of that, such definition of a Global Carrier let me have only one document in offnet database.

Could you please let me know whether I am right or not.

Thank you in advance.  

Kirill Sysoev

unread,
May 2, 2012, 3:31:53 PM5/2/12
to 2600h...@googlegroups.com
Hi!

Anybody?

Darren Schreiber

unread,
May 2, 2012, 3:58:45 PM5/2/12
to 2600h...@googlegroups.com
Hi Kirill,
They tried to write you back several times… per Richard:


Yes- I received this on multiple occasions:

 

Your message did not reach some or all of the intended recipients.

 

      Subject:    2600hz Feature Sponsorship

      Sent: 4/24/2012 8:55 PM

 

The following recipient(s) cannot be reached:

 

      'i...@onnet.su' on 4/24/2012 10:35 PM

            None of your e-mail accounts could send to this recipient.



Please just call into our office – it'll be easier. 415-886-7900.


--
Darren Schreiber
CEO / Co-Founder


 visit: www.2600hz.com
 tel: 415-886-7901
From: Kirill Sysoev <kirill...@gmail.com>
Reply-To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Date: Wednesday, May 2, 2012 12:31 PM
To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Subject: Re: Outbound calls through Global Carrier

Hi!

Anybody?

Kirill Sysoev

unread,
May 2, 2012, 4:30:15 PM5/2/12
to 2600h...@googlegroups.com
Hm...! 

Cant even imagine what could be the reason of such delivery failure, because I use this email in every day business process and truly believed that it is fully functional... :(
Anyway. I just got an answer form Richard to my gmail account and confirmed that we could move forward. You are in CC. Thank you for your help and proposal.

Regards,
Kirill

Darren Schreiber

unread,
May 2, 2012, 4:36:30 PM5/2/12
to 2600h...@googlegroups.com
Indeed, look forward to it! :-)

--
Darren Schreiber
CEO / Co-Founder


 visit: www.2600hz.com
 tel: 415-886-7901
From: Kirill Sysoev <kirill...@gmail.com>
Reply-To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Date: Wednesday, May 2, 2012 1:30 PM
To: "2600h...@googlegroups.com" <2600h...@googlegroups.com>
Subject: Re: Outbound calls through Global Carrier

Kirill Sysoev

unread,
May 23, 2012, 4:45:08 PM5/23/12
to 2600h...@googlegroups.com
Hi Darren!

Still stuck with the question abouOutbound calls through Global Carrier.
(At the same time, when I use "local carrier" I can easily place outbound calls using the same gw)
May be you can take a look what could be the problem?

Why could such error occurs: registrar returned no endpoints

[root@w1 ~]# service whapps status
Searching for running whapps on 'whistl...@w1.onnet.su'
[hotornot,jonny5,sysconf,conference,cdr,trunkstore,registrar,hangups,notify,
 media_mgr,crossbar,callflow,stepswitch]

FS cli: 
2012-05-23 20:16:50.271500 [NOTICE] mod_commands.c:5305 log|828a2d76...@192.168.1.201|whistle error while building command bridge: registrar returned no endpoints

2600hz-platform.log:
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|stepswitch_util:35 (<0.11586.2>) 78129169944 is not associated with any account, not_found
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|stepswitch_outbound:495 (<0.11586.2>) found resource c375da45b4942a917b1ac3b8381587c3 (sip:78129...@94.125.0.218)
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|stepswitch_outbound:112 (<0.11586.2>) found resources that bridge the number...to the cloud!
May 23 20:16:48 w1 2600hz[7516]: <0.11588.2> Supervisor amqp_channel_sup started undefined at pid <0.11589.2>
May 23 20:16:48 w1 2600hz[7516]: <0.11588.2> Supervisor amqp_channel_sup started undefined at pid <0.11590.2>
May 23 20:16:48 w1 2600hz[7516]: <0.11588.2> Supervisor amqp_channel_sup started undefined at pid <0.11591.2>
May 23 20:16:48 w1 2600hz[7516]: |00000000000|wh_amqp_connection:275 (<0.105.0>) started new AMQP channel <0.11590.2> for process <0.11586.2>
May 23 20:16:48 w1 2600hz[7516]: |00000000000|wh_amqp_connection:320 (<0.105.0>) updating tag for <0.11586.2> from <<>> to <<"amq.gen-nnGOGMxiqmAZDEP0jEhk0Q==">>
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|wh_amqp_connection:185 (<0.11586.2>) attempting to create new topic exchange 'callevt' via channel <0.121.0>
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|wh_amqp_connection:185 (<0.11586.2>) attempting to create new topic exchange 'callmgr' via channel <0.121.0>
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|wh_amqp_connection:185 (<0.11586.2>) attempting to create new direct exchange 'targeted' via channel <0.121.0>
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|stepswitch_outbound:399 (<0.11586.2>) sending rate request
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|wh_amqp_connection:93 (<0.11586.2>) publish to exchange 'callmgr' with routing key 'call.rating.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.11590.2>
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|stepswitch_outbound:126 (<0.11586.2>) set outbound caller id to 78123634500 'Sweet Home'
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|whapps_config:217 (<0.11586.2>) fetched config stepswitch(default) [<<"format_from_uri">>]: false
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|stepswitch_outbound:137 (<0.11586.2>) setting from-uri to undefined
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|wh_amqp_connection:93 (<0.11586.2>) publish to exchange 'callctl' with routing key 'amq.gen-hE1coUDx7VRc+KDdKVHhkw==' via channel <0.11590.2>
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|whapps_config:217 (<0.11586.2>) fetched config stepswitch(default) [<<"bridge_timeout">>]: 30000
May 23 20:16:48 w1 2600hz[7516]: |828a2d76...@192.168.1.201|hon_rater:25 (<0.11595.2>) valid rating request
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:650 (<0.7504.0>) inserting at the tail of the control queue call command 'bridge'
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:668 (<0.7504.0>) executing call command 'bridge' <<>>
May 23 20:16:48 w1 2600hz[8234]: |undefined|ecallmgr_registrar:174 (<0.7525.0>) looking up registration information for undefined@undefined
May 23 20:16:48 w1 2600hz[8234]: |undefined|ecallmgr_registrar:184 (<0.7525.0>) valid cached registration not found, querying whapps
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_worker:185 (<0.1104.0>) published request with msg id 9c50403f8f86598c for <0.7525.0>
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_api:353 (<0.7526.0>) failed to find key 'Realm' on API message
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_api:211 (<0.7526.0>) failing API JSON: {"Event-Name":"reg_query","Event-Category":"directory","Server-ID":"amq.gen-hBOg1qgjKIBsD5yOdu47Zg==","Call-ID":"00000000000","Msg-ID":"9c50403f8f86598c","Server-ID":"","App-Name":"ecallmgr","App-Version":"0.8.0","Node":"ecal...@w1.onnet.su"}
May 23 20:16:48 w1 2600hz[8234]: <0.7526.0> CRASH REPORT Process <0.7526.0> with 0 neighbours crashed with reason: {badmatch,{error,"Proplist failed validation for reg_query"}}
May 23 20:16:48 w1 2600hz[7516]: emulator Error in process <0.11595.2> on node 'whistl...@w1.onnet.su' with exit value: {{badmatch,<<11 bytes>>},[{hon_util,find_candidate_rates,2},{hon_rater,get_rate_data,1},{hon_rater,handle_req,2}]}#012
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(effective_caller_id_name=Sweet Home)'
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.1109.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6374.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(effective_caller_id_name=Sweet Home)' result: _none_
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.1114.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7037.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Authorizing-ID=4a045948b6ed9e99563c86992400b6f1)'
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.119.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7169.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Authorizing-ID=4a045948b6ed9e99563c86992400b6f1)' result: _none_
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.124.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6936.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Inception=on-net)'
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.129.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6559.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Inception=on-net)' result: _none_
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.139.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6902.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Authorizing-Type=device)'
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.144.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6535.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Authorizing-Type=device)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.149.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7137.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Account-ID=c8b0f6f8fc69794c095f1b2ac241a88e)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.154.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6877.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Account-ID=c8b0f6f8fc69794c095f1b2ac241a88e)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.164.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7121.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Realm=office.sip2.onnet.su)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.169.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6818.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Realm=office.sip2.onnet.su)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.159.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6511.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Username=user_mga4xs)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.174.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6478.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Username=user_mga4xs)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.179.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7109.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(local_var_clobber=true)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.184.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6787.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(local_var_clobber=true)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.189.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6454.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Owner-ID=4a045948b6ed9e99563c86992400b46f)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.194.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7097.0>
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Owner-ID=4a045948b6ed9e99563c86992400b46f)' result: _none_
May 23 20:16:50 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.199.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6752.0>
May 23 20:16:50 w1 2600hz[8234]: |00000000000|wh_amqp_worker:290 (<0.1104.0>) timeout after 2003
May 23 20:16:50 w1 2600hz[8234]: |00000000000|wh_amqp_worker:291 (<0.1104.0>) request timeout exceeded for msg id: 9c50403f8f86598c
May 23 20:16:50 w1 2600hz[8234]: |undefined|ecallmgr_registrar:195 (<0.7525.0>) did not receive registrar response: timeout
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:703 (<0.7504.0>) error while executing command bridge: <<"registrar returned no endpoints">>
May 23 20:16:50 w1 2600hz[8234]: |undefined|ecallmgr_fs_xml:149 (<0.7524.0>) failed to lookup user undefined@undefined in the registrar
May 23 20:16:50 w1 2600hz[8234]: |undefined|ecallmgr_util:214 (<0.7524.0>) unable to build route to endpoint
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:729 (<0.7504.0>) FORMAT ERROR: "sending execution error: ~s" [[123,[34,<<"Request">>,34],58,[123,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Account-ID">>,34],58,[34,<<"c8b0f6f8fc69794c095f1b2ac241a88e">>,34],125],44,[34,<<"Dial-Endpoint-Method">>,34],58,[34,<<"single">>,34],44,[34,<<"Outgoing-Caller-ID-Number">>,34],58,[34,<<"78123634500">>,34],44,[34,<<"Outgoing-Caller-ID-Name">>,34],58,[34,<<"Sweet Home">>,34],44,[34,<<"Continue-On-Fail">>,34],58,[34,<<"true">>,34],44,[34,<<"Endpoints">>,34],58,[91,[123,[34,<<"Endpoint-Options">>,34],58,<<"{}">>,44,[34,<<"Endpoint-Type">>,34],58,[34,<<"sip">>,34],44,[34,<<"Auth-Password">>,34],58,[34,<<>>,34],44,[34,<<"Auth-User">>,34],58,[34,<<>>,34],44,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Resource-ID">>,34],58,[34,<<"c375da45b4942a917b1ac3b8381587c3">>,34],125],44,[34,<<"Codecs">>,34],58,[91,[34,<<"PCMU">>,34],44,[34,<<"PCMA">>,34],93],44,[34,<<"Endpoint-Progress-Timeout">>,34],58,[34,<<"8">>,34],44,[34,<<"Callee-ID-Number">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Callee-ID-Name">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"To-DID">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Route">>,34],58,[34,<<"sip:78129...@94.125.0.218">>,34],44,[34,<<"Invite-Format">>,34],58,[34,<<"e164">>,34],125],93],44,[34,<<"Call-ID">>,34],58,[34,<<"828a2d76...@192.168.1.201">>,34],44,[34,<<"Application-Name">>,34],58,[34,<<"bridge">>,34],44,[34,<<"Node">>,34],58,[34,<<"whistl...@w1.onnet.su">>,34],44,[34,<<"App-Version">>,34],58,[34,<<"0.2.0">>,34],44,[34,<<"App-Name">>,34],58,[34,<<"stepswitch">>,34],44,[34,<<"Event-Name">>,34],58,[34,<<"command">>,34],44,[34,<<"Event-Category">>,34],58,[34,<<"call">>,34],44,[34,<<"Server-ID">>,34],58,[34,<<"amq.gen-nnGOGMxiqmAZDEP0jEhk0Q==">>,34],125],44,[34,<<"Error-Message">>,34],58,[34,<<"Could not execute dialplan action: bridge">>,34],44,[34,<<"Msg-ID">>,34],58,[34,<<>>,34],44,[34,<<"Node">>,34],58,[34,<<"ecal...@w1.onnet.su">>,34],44,
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|wh_amqp_connection:93 (<0.7504.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7507.0>
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:215 (<0.7530.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:215 (<0.7534.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:215 (<0.7538.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[7516]: |828a2d76...@192.168.1.201|whapps_config:217 (<0.11586.2>) fetched config stepswitch(default) [<<"max_shortdial_correction">>]: 5
May 23 20:16:50 w1 2600hz[7516]: |828a2d76...@192.168.1.201|stepswitch_outbound:572 (<0.11586.2>) error during outbound request: {"Request":{"Custom-Channel-Vars":{"Account-ID":"c8b0f6f8fc69794c095f1b2ac241a88e"},"Dial-Endpoint-Method":"single","Outgoing-Caller-ID-Number":"78123634500","Outgoing-Caller-ID-Name":"Sweet Home","Continue-On-Fail":"true","Endpoints":[{"Endpoint-Options":{},"Endpoint-Type":"sip","Auth-Password":"","Auth-User":"","Custom-Channel-Vars":{"Resource-ID":"c375da45b4942a917b1ac3b8381587c3"},"Codecs":["PCMU","PCMA"],"Endpoint-Progress-Timeout":"8","Callee-ID-Number":"78129169944","Callee-ID-Name":"78129169944","To-DID":"78129169944","Route":"sip:78129...@94.125.0.218","Invite-Format":"e164"}],"Call-ID":"828a2d76...@192.168.1.201","Application-Name":"bridge","Node":"whistl...@w1.onnet.su","App-Version":"0.2.0","App-Name":"stepswitch","Event-Name":"command","Event-Category":"call","Server-ID":"amq.gen-nnGOGMxiqmAZDEP0jEhk0Q=="},"Error-Message":"Could not execute dialplan action: bridge","Msg-ID":"","Node":"ecal...@w1.onnet.su","App-Version":"0.8.0","App-Name":"ecallmgr","Event-Name":"dialplan","Event-Category":"error","Server-ID":""}
May 23 20:16:50 w1 2600hz[7516]: |828a2d76...@192.168.1.201|wh_amqp_connection:93 (<0.11586.2>) publish to exchange 'targeted' with routing key 'amq.gen-8O6ukmn+cJf3dJ9h7ya6KA==' via channel <0.11590.2>
May 23 20:16:50 w1 2600hz[7516]: |00000000000|wh_amqp_connection:344 (<0.105.0>) recieved notification monitored process <0.11586.2>  died normal, searching for reference
May 23 20:16:50 w1 2600hz[7516]: |00000000000|wh_amqp_connection:490 (<0.105.0>) reference was for consumer <0.11586.2>, removing channel <0.11590.2>
May 23 20:16:50 w1 2600hz[7516]: |828a2d76...@192.168.1.201|cf_offnet:52 (<0.11579.2>) offnet request error, attempting to find failure branch for sip:500:NORMAL_TEMPORARY_FAILURE
May 23 20:16:50 w1 2600hz[7516]: |828a2d76...@192.168.1.201|cf_exe:280 (<0.11572.2>) attempted undefined child NORMAL_TEMPORARY_FAILURE

Regards,
Kirill 



вторник, 24 апреля 2012 г., 19:25:57 UTC+4 пользователь Darren Schreiber написал:

Darren Schreiber

unread,
May 23, 2012, 5:18:27 PM5/23/12
to 2600h...@googlegroups.com
what version of whistle is this?

Kirill Sysoev

unread,
May 23, 2012, 5:42:21 PM5/23/12
to 2600h...@googlegroups.com
Hi Darren!

[root@w1 ~]# rpm -qa | grep -i whistle
whistle-v1.52.9-1

Regards,

четверг, 24 мая 2012 г., 1:18:27 UTC+4 пользователь Darren Schreiber написал:
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:729 (<0.7504.0>) FORMAT ERROR: "sending execution error: ~s" [[123,[34,<<"Request">>,34],58,[123,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Account-ID">>,34],58,[34,<<"c8b0f6f8fc69794c095f1b2ac241a88e">>,34],125],44,[34,<<"Dial-Endpoint-Method">>,34],58,[34,<<"single">>,34],44,[34,<<"Outgoing-Caller-ID-Number">>,34],58,[34,<<"78123634500">>,34],44,[34,<<"Outgoing-Caller-ID-Name">>,34],58,[34,<<"Sweet Home">>,34],44,[34,<<"Continue-On-Fail">>,34],58,[34,<<"true">>,34],44,[34,<<"Endpoints">>,34],58,[91,[123,[34,<<"Endpoint-Options">>,34],58,<<"{}">>,44,[34,<<"Endpoint-Type">>,34],58,[34,<<"sip">>,34],44,[34,<<"Auth-Password">>,34],58,[34,<<>>,34],44,[34,<<"Auth-User">>,34],58,[34,<<>>,34],44,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Resource-ID">>,34],58,[34,<<"c375da45b4942a917b1ac3b8381587c3">>,34],125],44,[34,<<"Codecs">>,34],58,[91,[34,<<"PCMU">>,34],44,[34,<<"PCMA">>,34],93],44,[34,<<"Endpoint-Progress-Timeout">>,34],58,[34,<<"8">>,34],44,[34,<<"Callee-ID-Number">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Callee-ID-Name">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"To-DID">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Route">>,34],58,[34,<<"sip:78129...@94.125.0.218">>,34],44,[34,<<"Invite-Format">>,34],58,[34,<<"e164">>,34],125],93],44,[34,<<"Call-ID">>,34],58,[34,<<"828a2d76-3d5d...@192.168.1.201">>,34],44,[34,<<"Application-Name">>,34],58,[34,<<"bridge">>,34],44,[34,<<"Node">>,34],58,[34,<<"whistl...@w1.onnet.su">>,34],44,[34,<<"App-Version">>,34],58,[34,<<"0.2.0">>,34],44,[34,<<"App-Name">>,34],58,[34,<<"stepswitch">>,34],44,[34,<<"Event-Name">>,34],58,[34,<<"command">>,34],44,[34,<<"Event-Category">>,34],58,[34,<<"call">>,34],44,[34,<<"Server-ID">>,34],58,[34,<<"amq.gen-nnGOGMxiqmAZDEP0jEhk0Q==">>,34],125],44,[34,<<"Error-Message">>,34],58,[34,<<"Could not execute dialplan action: bridge">>,34],44,[34,<<"Msg-ID">>,34],58,[34,<<>>,34],44,[34,<<"Node">>,34],58,[34,<<"ecall...@w1.onnet.su">>,34],44,
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|wh_amqp_connection:93 (<0.7504.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7507.0>
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:215 (<0.7530.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:215 (<0.7534.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[8234]: |828a2d76...@192.168.1.201|ecallmgr_call_control:215 (<0.7538.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[7516]: |828a2d76...@192.168.1.201|whapps_config:217 (<0.11586.2>) fetched config stepswitch(default) [<<"max_shortdial_correction">>]: 5
May 23 20:16:50 w1 2600hz[7516]: |828a2d76...@192.168.1.201|stepswitch_outbound:572 (<0.11586.2>) error during outbound request: {"Request":{"Custom-Channel-Vars":{"Account-ID":"c8b0f6f8fc69794c095f1b2ac241a88e"},"Dial-Endpoint-Method":"single","Outgoing-Caller-ID-Number":"78123634500","Outgoing-Caller-ID-Name":"Sweet Home","Continue-On-Fail":"true","Endpoints":[{"Endpoint-Options":{},"Endpoint-Type":"sip","Auth-Password":"","Auth-User":"","Custom-Channel-Vars":{"Resource-ID":"c375da45b4942a917b1ac3b8381587c3"},"Codecs":["PCMU","PCMA"],"Endpoint-Progress-Timeout":"8","Callee-ID-Number":"78129169944","Callee-ID-Name":"78129169944","To-DID":"78129169944","Route":"sip:7812916...@94.125.0.218","Invite-Format":"e164"}],"Call-ID":"828a2d76-...@192.168.1.201","Application-Name":"bridge","Node":"whistl...@w1.onnet.su","App-Version":"0.2.0","App-Name":"stepswitch","Event-Name":"command","Event-Category":"call","Server-ID":"amq.gen-nnGOGMxiqmAZDEP0jEhk0Q=="},"Error-Message":"Could not execute dialplan action: bridge","Msg-ID":"","Node":"ecall...@w1.onnet.su","App-Version":"0.8.0","App-Name":"ecallmgr","Event-Name":"dialplan","Event-Category":"error","Server-ID":""}

srg

unread,
Jan 31, 2013, 2:26:01 AM1/31/13
to 2600h...@googlegroups.com
Hi,

I know I'm late to this conversation, but I had a similar problem. Changing the invite_format from "e164" to "route" in the offnet database record helped me.

Stephen

Why could such error occurs: registrar returned no endpoints

[root@w1 ~]# service whapps status
Searching for running whapps on 'whist...@w1.onnet.su'
[hotornot,jonny5,sysconf,conference,cdr,trunkstore,registrar,hangups,notify,
 media_mgr,crossbar,callflow,stepswitch]

FS cli: 
2012-05-23 20:16:50.271500 [NOTICE] mod_commands.c:5305 log|828a2d...@192.168.1.201|whistle error while building command bridge: registrar returned no endpoints

2600hz-platform.log:
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|stepswitch_util:35 (<0.11586.2>) 78129169944 is not associated with any account, not_found
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|stepswitch_outbound:495 (<0.11586.2>) found resource c375da45b4942a917b1ac3b8381587c3 (sip:78129...@94.125.0.218)
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|stepswitch_outbound:112 (<0.11586.2>) found resources that bridge the number...to the cloud!
May 23 20:16:48 w1 2600hz[7516]: <0.11588.2> Supervisor amqp_channel_sup started undefined at pid <0.11589.2>
May 23 20:16:48 w1 2600hz[7516]: <0.11588.2> Supervisor amqp_channel_sup started undefined at pid <0.11590.2>
May 23 20:16:48 w1 2600hz[7516]: <0.11588.2> Supervisor amqp_channel_sup started undefined at pid <0.11591.2>
May 23 20:16:48 w1 2600hz[7516]: |00000000000|wh_amqp_connection:275 (<0.105.0>) started new AMQP channel <0.11590.2> for process <0.11586.2>
May 23 20:16:48 w1 2600hz[7516]: |00000000000|wh_amqp_connection:320 (<0.105.0>) updating tag for <0.11586.2> from <<>> to <<"amq.gen-nnGOGMxiqmAZDEP0jEhk0Q==">>
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|wh_amqp_connection:185 (<0.11586.2>) attempting to create new topic exchange 'callevt' via channel <0.121.0>
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|wh_amqp_connection:185 (<0.11586.2>) attempting to create new topic exchange 'callmgr' via channel <0.121.0>
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|wh_amqp_connection:185 (<0.11586.2>) attempting to create new direct exchange 'targeted' via channel <0.121.0>
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|stepswitch_outbound:399 (<0.11586.2>) sending rate request
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|wh_amqp_connection:93 (<0.11586.2>) publish to exchange 'callmgr' with routing key 'call.rating.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.11590.2>
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|stepswitch_outbound:126 (<0.11586.2>) set outbound caller id to 78123634500 'Sweet Home'
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|whapps_config:217 (<0.11586.2>) fetched config stepswitch(default) [<<"format_from_uri">>]: false
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|stepswitch_outbound:137 (<0.11586.2>) setting from-uri to undefined
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|wh_amqp_connection:93 (<0.11586.2>) publish to exchange 'callctl' with routing key 'amq.gen-hE1coUDx7VRc+KDdKVHhkw==' via channel <0.11590.2>
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|whapps_config:217 (<0.11586.2>) fetched config stepswitch(default) [<<"bridge_timeout">>]: 30000
May 23 20:16:48 w1 2600hz[7516]: |828a2d7...@192.168.1.201|hon_rater:25 (<0.11595.2>) valid rating request
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_control:650 (<0.7504.0>) inserting at the tail of the control queue call command 'bridge'
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_control:668 (<0.7504.0>) executing call command 'bridge' <<>>
May 23 20:16:48 w1 2600hz[8234]: |undefined|ecallmgr_registrar:174 (<0.7525.0>) looking up registration information for undefined@undefined
May 23 20:16:48 w1 2600hz[8234]: |undefined|ecallmgr_registrar:184 (<0.7525.0>) valid cached registration not found, querying whapps
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_worker:185 (<0.1104.0>) published request with msg id 9c50403f8f86598c for <0.7525.0>
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_api:353 (<0.7526.0>) failed to find key 'Realm' on API message
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_api:211 (<0.7526.0>) failing API JSON: {"Event-Name":"reg_query","Event-Category":"directory","Server-ID":"amq.gen-hBOg1qgjKIBsD5yOdu47Zg==","Call-ID":"00000000000","Msg-ID":"9c50403f8f86598c","Server-ID":"","App-Name":"ecallmgr","App-Version":"0.8.0","Node":"ecal...@w1.onnet.su"}
May 23 20:16:48 w1 2600hz[8234]: <0.7526.0> CRASH REPORT Process <0.7526.0> with 0 neighbours crashed with reason: {badmatch,{error,"Proplist failed validation for reg_query"}}
May 23 20:16:48 w1 2600hz[7516]: emulator Error in process <0.11595.2> on node 'whist...@w1.onnet.su' with exit value: {{badmatch,<<11 bytes>>},[{hon_util,find_candidate_rates,2},{hon_rater,get_rate_data,1},{hon_rater,handle_req,2}]}#012
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(effective_caller_id_name=Sweet Home)'
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.1109.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6374.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(effective_caller_id_name=Sweet Home)' result: _none_
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.1114.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7037.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Authorizing-ID=4a045948b6ed9e99563c86992400b6f1)'
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.119.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7169.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Authorizing-ID=4a045948b6ed9e99563c86992400b6f1)' result: _none_
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.124.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6936.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Inception=on-net)'
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.129.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6559.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Inception=on-net)' result: _none_
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.139.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6902.0>
May 23 20:16:48 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Authorizing-Type=device)'
May 23 20:16:48 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.144.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6535.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Authorizing-Type=device)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.149.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7137.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Account-ID=c8b0f6f8fc69794c095f1b2ac241a88e)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.154.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6877.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Account-ID=c8b0f6f8fc69794c095f1b2ac241a88e)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.164.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7121.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Realm=office.sip2.onnet.su)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.169.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6818.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Realm=office.sip2.onnet.su)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.159.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6511.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Username=user_mga4xs)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.174.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6478.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Username=user_mga4xs)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.179.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7109.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(local_var_clobber=true)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.184.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6787.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(local_var_clobber=true)' result: _none_
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.189.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6454.0>
May 23 20:16:49 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:357 (<0.7509.0>) publishing call event channel_execute 'set(ecallmgr_Owner-ID=4a045948b6ed9e99563c86992400b46f)'
May 23 20:16:49 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.194.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7097.0>
May 23 20:16:50 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_events:354 (<0.7509.0>) publishing call event channel_execute_complete 'set(ecallmgr_Owner-ID=4a045948b6ed9e99563c86992400b46f)' result: _none_
May 23 20:16:50 w1 2600hz[8234]: |00000000000|wh_amqp_connection:93 (<0.199.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.6752.0>
May 23 20:16:50 w1 2600hz[8234]: |00000000000|wh_amqp_worker:290 (<0.1104.0>) timeout after 2003
May 23 20:16:50 w1 2600hz[8234]: |00000000000|wh_amqp_worker:291 (<0.1104.0>) request timeout exceeded for msg id: 9c50403f8f86598c
May 23 20:16:50 w1 2600hz[8234]: |undefined|ecallmgr_registrar:195 (<0.7525.0>) did not receive registrar response: timeout
May 23 20:16:50 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_control:703 (<0.7504.0>) error while executing command bridge: <<"registrar returned no endpoints">>
May 23 20:16:50 w1 2600hz[8234]: |undefined|ecallmgr_fs_xml:149 (<0.7524.0>) failed to lookup user undefined@undefined in the registrar
May 23 20:16:50 w1 2600hz[8234]: |undefined|ecallmgr_util:214 (<0.7524.0>) unable to build route to endpoint
May 23 20:16:50 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_control:729 (<0.7504.0>) FORMAT ERROR: "sending execution error: ~s" [[123,[34,<<"Request">>,34],58,[123,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Account-ID">>,34],58,[34,<<"c8b0f6f8fc69794c095f1b2ac241a88e">>,34],125],44,[34,<<"Dial-Endpoint-Method">>,34],58,[34,<<"single">>,34],44,[34,<<"Outgoing-Caller-ID-Number">>,34],58,[34,<<"78123634500">>,34],44,[34,<<"Outgoing-Caller-ID-Name">>,34],58,[34,<<"Sweet Home">>,34],44,[34,<<"Continue-On-Fail">>,34],58,[34,<<"true">>,34],44,[34,<<"Endpoints">>,34],58,[91,[123,[34,<<"Endpoint-Options">>,34],58,<<"{}">>,44,[34,<<"Endpoint-Type">>,34],58,[34,<<"sip">>,34],44,[34,<<"Auth-Password">>,34],58,[34,<<>>,34],44,[34,<<"Auth-User">>,34],58,[34,<<>>,34],44,[34,<<"Custom-Channel-Vars">>,34],58,[123,[34,<<"Resource-ID">>,34],58,[34,<<"c375da45b4942a917b1ac3b8381587c3">>,34],125],44,[34,<<"Codecs">>,34],58,[91,[34,<<"PCMU">>,34],44,[34,<<"PCMA">>,34],93],44,[34,<<"Endpoint-Progress-Timeout">>,34],58,[34,<<"8">>,34],44,[34,<<"Callee-ID-Number">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Callee-ID-Name">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"To-DID">>,34],58,[34,<<"78129169944">>,34],44,[34,<<"Route">>,34],58,[34,<<"sip:78129...@94.125.0.218">>,34],44,[34,<<"Invite-Format">>,34],58,[34,<<"e164">>,34],125],93],44,[34,<<"Call-ID">>,34],58,[34,<<"828a2d76-3d5d...@192.168.1.201">>,34],44,[34,<<"Application-Name">>,34],58,[34,<<"bridge">>,34],44,[34,<<"Node">>,34],58,[34,<<"whistl...@w1.onnet.su">>,34],44,[34,<<"App-Version">>,34],58,[34,<<"0.2.0">>,34],44,[34,<<"App-Name">>,34],58,[34,<<"stepswitch">>,34],44,[34,<<"Event-Name">>,34],58,[34,<<"command">>,34],44,[34,<<"Event-Category">>,34],58,[34,<<"call">>,34],44,[34,<<"Server-ID">>,34],58,[34,<<"amq.gen-nnGOGMxiqmAZDEP0jEhk0Q==">>,34],125],44,[34,<<"Error-Message">>,34],58,[34,<<"Could not execute dialplan action: bridge">>,34],44,[34,<<"Msg-ID">>,34],58,[34,<<>>,34],44,[34,<<"Node">>,34],58,[34,<<"ecall...@w1.onnet.su">>,34],44,
May 23 20:16:50 w1 2600hz[8234]: |828a2d7...@192.168.1.201|wh_amqp_connection:93 (<0.7504.0>) publish to exchange 'callevt' with routing key 'call.event.828a2d76-3d5d9c35%40192%2E168%2E1%2E201' via channel <0.7507.0>
May 23 20:16:50 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_control:215 (<0.7530.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_control:215 (<0.7534.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[8234]: |828a2d7...@192.168.1.201|ecallmgr_call_control:215 (<0.7538.0>) control queue <0.7504.0> channel execute completion for 'set'
May 23 20:16:50 w1 2600hz[7516]: |828a2d7...@192.168.1.201|whapps_config:217 (<0.11586.2>) fetched config stepswitch(default) [<<"max_shortdial_correction">>]: 5
May 23 20:16:50 w1 2600hz[7516]: |828a2d7...@192.168.1.201|stepswitch_outbound:572 (<0.11586.2>) error during outbound request: {"Request":{"Custom-Channel-Vars":{"Account-ID":"c8b0f6f8fc69794c095f1b2ac241a88e"},"Dial-Endpoint-Method":"single","Outgoing-Caller-ID-Number":"78123634500","Outgoing-Caller-ID-Name":"Sweet Home","Continue-On-Fail":"true","Endpoints":[{"Endpoint-Options":{},"Endpoint-Type":"sip","Auth-Password":"","Auth-User":"","Custom-Channel-Vars":{"Resource-ID":"c375da45b4942a917b1ac3b8381587c3"},"Codecs":["PCMU","PCMA"],"Endpoint-Progress-Timeout":"8","Callee-ID-Number":"78129169944","Callee-ID-Name":"78129169944","To-DID":"78129169944","Route":"sip:7812916...@94.125.0.218","Invite-Format":"e164"}],"Call-ID":"828a2d76-...@192.168.1.201","Application-Name":"bridge","Node":"whistl...@w1.onnet.su","App-Version":"0.2.0","App-Name":"stepswitch","Event-Name":"command","Event-Category":"call","Server-ID":"amq.gen-nnGOGMxiqmAZDEP0jEhk0Q=="},"Error-Message":"Could not execute dialplan action: bridge","Msg-ID":"","Node":"ecall...@w1.onnet.su","App-Version":"0.8.0","App-Name":"ecallmgr","Event-Name":"dialplan","Event-Category":"error","Server-ID":""}
May 23 20:16:50 w1 2600hz[7516]: |828a2d7...@192.168.1.201|wh_amqp_connection:93 (<0.11586.2>) publish to exchange 'targeted' with routing key 'amq.gen-8O6ukmn+cJf3dJ9h7ya6KA==' via channel <0.11590.2>
May 23 20:16:50 w1 2600hz[7516]: |00000000000|wh_amqp_connection:344 (<0.105.0>) recieved notification monitored process <0.11586.2>  died normal, searching for reference
May 23 20:16:50 w1 2600hz[7516]: |00000000000|wh_amqp_connection:490 (<0.105.0>) reference was for consumer <0.11586.2>, removing channel <0.11590.2>
May 23 20:16:50 w1 2600hz[7516]: |828a2d7...@192.168.1.201|cf_offnet:52 (<0.11579.2>) offnet request error, attempting to find failure branch for sip:500:NORMAL_TEMPORARY_FAILURE
May 23 20:16:50 w1 2600hz[7516]: |828a2d7...@192.168.1.201|cf_exe:280 (<0.11572.2>) attempted undefined child NORMAL_TEMPORARY_FAILURE

Regards,
Kirill 



Reply all
Reply to author
Forward
0 new messages