How to use a special carrier for an account?

extremerotary · August 17, 2017

Hey there,

I have a customer who relies heavily on international calling, and they want to use a specific international termination carrier for all international calls. The carrier is a premium carrier so I do not want all my accounts to be able to use this carrier. I noticed in Monster UI that there is a section for "Service Providers" under Carriers App when you have jumped into a specific account.

Can I configure this carrier in this account, and only this account will be able to use them?

I considered using flags on their devices, but then I have to add flags to every single global carrier otherwise they would only be able to make international calls and no other calls at all.

Looking for some guidance. How can I achieve this setup? The international termination carrier will be a pattern match, and it would be my expectation that if the dial string did not match this 'account-specific carrier' that stepswitch would then move up the tree and look at the global carriers.

Te Matau · August 17, 2017

Create a set of account resources:

https://docs.2600hz.com/dev/applications/crossbar/doc/resources/

Then point no_match callflow at the account resources. Something like

{
    "data": {
        "numbers": [
            "no_match"
        ],
        "flow": {
            "data": {
                "hunt_account_id": "b20cc94c158666e83c546c0d75103f19"
            },
            "module": "resources",
            "children": {}
        },

Apologies for brevity.

extremerotary · August 22, 2017

I have set that all up. i added the resource and pointed the no_match callflow to offnet, but in the offnet module, i did set it to include local resources.

                       "module": "offnet",
                       "data": {
                           "caller_id_type": "external",
                           "custom_sip_headers": {
                           },
                           "ignore_early_media": false,
                           "use_local_resources": true
                       },
                       "children": {
                       }
                   }
               }
           },

However, when dialing out, I do not see anywhere in the logs where it checks local resources. It immediately just goes to global resources and evaluates the dial string on the pattern match rules and flags. What am I missing?

mc_ · August 22, 2017

Logs! Specifically callflow's and stepswitch's - debug level.

extremerotary · August 23, 2017

Unable to attach logs as the site is now insecure :-(

So... I'm pasting them below the local carrier doc.

Local Carrier Doc:

{
   "_id": "c559e887b19b56a828b770a9d884138b",
   "_rev": "3-4d0d61a656b4a113328a1aa1d63e784c",
   "classifiers": {
       "international": {
           "enabled": true,
           "prefix": "<<prefix>>",
           "suffix": "",
           "regex": "^\\+([2-9]\\d{7,})$"
       }
   },
   "flags": [
   ],
   "gateways": [
       {
           "realm": "X.X.X.X",
           "progress_timeout": 10,
           "privacy_mode": "sip",
           "server": "X.X.X.X",
           "channel_selection": "ascending",
           "custom_sip_headers": {
           },
           "enabled": true,
           "endpoint_type": "sip",
           "force_port": false,
           "invite_format": "route",
           "port": 5060,
           "skype_rr": true
       }
   ],
   "media": {
       "audio": {
           "codecs": [
               "PCMA",
               "PCMU"
           ]
       },
       "video": {
           "codecs": [
           ]
       },
       "fax_option": false
   },
   "template_name": "",
   "name": "International Term",
   "require_flags": false,
   "format_from_uri": false,
   "notes": "",
   "ui_metadata": {
       "version": "4.1-36",
       "ui": "monster-ui",
       "origin": "carriers"
   },
   "emergency": false,
   "enabled": true,
   "grace_period": 5,
   "rules": [
   ],
   "weight_cost": 50,
   "id": "c559e887b19b56a828b770a9d884138b",
   "pvt_is_authenticated": true,
   "pvt_auth_account_id": "e18327f1b39e9914722804cd1ecb1ea1",
   "pvt_auth_user_id": "39a30c045072018ed36557b7fafa2581",
   "pvt_request_id": "54ab6c8881411dc999c7dd1f134d6eaa",
   "pvt_modified": 63670637022,
   "pvt_created": 63670636510,
   "pvt_account_db": "account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb",
   "pvt_account_id": "4e3e467b873b84dae6ddab7bde09cacb",
   "pvt_vsn": "1",
   "pvt_type": "resource"
}

Here is a carrier document that was a match for the dialed string (to use as a comparison)

{
   "_id": "22d3e5d839f302b8f398058c629e8303",
   "_rev": "1-4cf289e295454fac317849a10f5ee97c",
   "weight_cost": 50,
   "enabled": true,
   "gateways": [
       {
           "prefix": "<<prefix>>",
           "codecs": [
               "PCMU",
               "PCMA"
           ],
           "progress_timeout": "30",
           "server": "X.X.X.X",
           "username": "",
           "password": "",
           "realm": "X.X.X.X",
           "format_from_uri": false,
           "suffix": "",
           "enabled": true,
           "custom_sip_headers": {
           },
           "invite_format": "route",
           "endpoint_type": "sip",
           "channel_selection": "ascending",
           "skype_rr": true,
           "emergency": false
       }
   ],
   "rules": [
       "^\\+([2-9]\\d{7,})$"
   ],
   "caller_id_options": {
       "type": "external"
   },
   "type": "global",
   "name": "Global International Carrier",
   "peer": false,
   "ui_metadata": {
       "ui": "kazoo-ui",
       "version": "v3.16-2"
   },
   "emergency": false,
   "grace_period": 5,
   "flags": [
   ],
   "media": {
       "audio": {
           "codecs": [
               "PCMU"
           ]
       },
       "video": {
           "codecs": [
           ]
       }
   },
   "id": "22d3e5d839f302b8f398058c629e8303",
   "pvt_modified": 63587955477,
   "pvt_created": 63585536644,
   "pvt_account_db": "offnet",
   "pvt_account_id": "e18327f1b39e9914722804cd1ecb1ea1",
   "pvt_vsn": "1",
   "pvt_type": "resource"
}

Outbound Call Logs: (I intentionally called a 011 number that wasn't real; I was just checking if the local resource would be used/a match)

Aug 22 16:29:05 server1 2600hz[9338]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channel:666 (<0.16958.8>) not publishing channel state
Aug 22 16:29:05 server1 2600hz[9338]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_event_stream:391 (<0.16957.8>) channel added to cache
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:35 (<0.2494.0>) received request 03a90252-8757-11e7-940d-fd88a23bf553 asking if callflows can route this call
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_flow:74 (<0.2494.0>) searching for callflow in account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb to satisfy '+35800659'
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kzs_plan:288 (<0.2494.0>) creating new dataplan <<"4e3e467b873b84dae6ddab7bde09cacb">>
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|milliwatt_route_req:37 (<0.2495.0>) milliwatt does not know what to do with this!
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:65 (<0.2494.0>) ignore preflow, not set
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:93 (<0.2494.0>) callflow 07c33b14ca3451a20c231c1942fa2ce1 in 4e3e467b873b84dae6ddab7bde09cacb satisfies request
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:196 (<0.2494.0>) callflows knows how to route the call! sending park response
Aug 22 16:29:05 server1 2600hz[25736]: |03a90252-8757-11e7-940d-fd88a23bf553|kz_amqp_worker:586 (<0.964.0>) published request with msg id 977067d6-c959fc39-9ad840f8@10.20.10.56 for <0.2494.0>
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:745 (<0.964.0>) response for msg id 977067d6-c959fc39-9ad840f8@10.20.10.56 took 214100 micro to return
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:219 (<0.2494.0>) callflow has received a route win, taking control of the call
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_win:111 (<0.2494.0>) capture group 35800659 being used instead of request +35800659
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|knm_converters:267 (<0.2494.0>) number '+35800659' is classified as international
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_win:100 (<0.2494.0>) classified number +35800659 as international, testing for call restrictions
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_win:37 (<0.2494.0>) setting initial information about the call
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_attributes:426 (<0.2494.0>) initiating endpoint is owned by 7145b996f7ee0d40b314114a9869b7fe
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_win:239 (<0.2494.0>) setting language 'en-us' for this call
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_attributes:142 (<0.2494.0>) endpoint configured with internal caller id <Some Guy> 150
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_attributes:226 (<0.2494.0>) determined internal caller id is <Some Guy> 150
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_win:264 (<0.2494.0>) bootstrapping with caller id type internal: "Some Guy" 150
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.2494.0>) published to callctl(amqp://guest:guest@10.11.1.61:5672) exchange (routing key ecallmgr@wap002.zone.domain.com-<0.14087.8>-4431c273) via <0.1453.0>
Aug 22 16:29:05 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_win:334 (<0.2494.0>) call has been setup, beginning to process the call
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:778 (<0.2504.0>) executing callflow 07c33b14ca3451a20c231c1942fa2ce1
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_win:291 (<0.2494.0>) looking up endpoint for 940e247b8b18fbac609cfcae31f6137c
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:779 (<0.2504.0>) account id 4e3e467b873b84dae6ddab7bde09cacb
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_win:294 (<0.2494.0>) trying to send metaflow for a-leg endpoint 940e247b8b18fbac609cfcae31f6137c
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:780 (<0.2504.0>) request 01135800659@realm.domain.com
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:781 (<0.2504.0>) to 01135800659@realm.domain.com
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:782 (<0.2504.0>) from 150@realm.domain.com
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:783 (<0.2504.0>) CID Some Guy 150
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:785 (<0.2504.0>) inception on-net: using attributes for an internal call
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:788 (<0.2504.0>) authorizing id 940e247b8b18fbac609cfcae31f6137c
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1147 (<0.2504.0>) channel started, let's connect
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:310 (<0.2504.0>) declared queue kazoo_apps@server1.zone.domain.com-<0.2504.0>-a3dd5634 via channel <0.2116.0>
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:336 (<0.2504.0>) created consumer amq.ctag-jtCBOMFXNVwQhB58HB74ZQ via channel <0.2116.0>
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:889 (<0.2504.0>) queue started: kazoo_apps@server1.zone.domain.com-<0.2504.0>-a3dd5634
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1038 (<0.2504.0>) creating new binding: 'call'
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.2504.0>) bound kazoo_apps@server1.zone.domain.com-<0.2504.0>-a3dd5634 to callevt exchange (routing key call.*.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via channel <0.2116.0>
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1038 (<0.2504.0>) creating new binding: 'self'
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.2504.0>) bound kazoo_apps@server1.zone.domain.com-<0.2504.0>-a3dd5634 to targeted exchange (routing key kazoo_apps@server1.zone.domain.com-<0.2504.0>-a3dd5634) via channel <0.2116.0>
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:499 (<0.2504.0>) ready to recv events, launching the callflow
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:689 (<0.2504.0>) moving to action 'cf_menu'
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_menu:523 (<0.2512.0>) loaded menu route 5e1834b5add341de05cc587589bce61d
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.2512.0>) published to callctl(amqp://guest:guest@10.11.1.61:5672) exchange (routing key ecallmgr@wap002.zone.domain.com-<0.14087.8>-4431c273) via <0.2116.0>
Aug 22 16:29:06 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.2512.0>) published to callctl(amqp://guest:guest@10.11.1.61:5672) exchange (routing key ecallmgr@wap002.zone.domain.com-<0.14087.8>-4431c273) via <0.2116.0>
Aug 22 16:29:06 server1 2600hz[9338]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channel:666 (<0.16983.8>) not publishing channel state
Aug 22 16:29:08 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.2512.0>) published to callctl(amqp://guest:guest@10.11.1.61:5672) exchange (routing key ecallmgr@wap002.zone.domain.com-<0.14087.8>-4431c273) via <0.2116.0>
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_call_command:2233 (<0.2512.0>) collected digits ('9651') from caller, terminated with #
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_menu:156 (<0.2512.0>) trying to match digits 9651
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:365 (<0.2504.0>) branching to attempted child 9651
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:689 (<0.2504.0>) moving to action 'cf_offnet'
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_menu:174 (<0.2512.0>) selection is a callflow child
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_menu:114 (<0.2512.0>) hunt callflow found
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:550 (<0.2504.0>) cf module cf_menu down normally
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.2557.0>) published to callctl(amqp://guest:guest@10.11.1.61:5672) exchange (routing key ecallmgr@wap002.zone.domain.com-<0.14087.8>-4431c273) via <0.2116.0>
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_attributes:142 (<0.2557.0>) endpoint configured with emergency caller id <Rob s Account> +14842067999
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_attributes:216 (<0.2557.0>) determined emergency caller id is <Rob s Account> +14842067999
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_attributes:142 (<0.2557.0>) endpoint configured with external caller id <Rob s Account> +14842067999
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_attributes:222 (<0.2557.0>) determined external caller id is <Rob s Account> +14842067999
Aug 22 16:29:10 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.2557.0>) published to resource(amqp://guest:guest@10.11.1.61:5672) exchange (routing key offnet.resource.req) via <0.2116.0>
Aug 22 16:29:15 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_resources:64 (<0.2557.0>) offnet request error, attempting to find failure branch for sip:404:UNALLOCATED_NUMBER
Aug 22 16:29:15 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_util:513 (<0.2557.0>) attempting to find failure branch for sip:404:UNALLOCATED_NUMBER
Aug 22 16:29:15 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:361 (<0.2504.0>) attempted 'undefined' child UNALLOCATED_NUMBER
Aug 22 16:29:15 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:361 (<0.2504.0>) attempted 'undefined' child sip:404
Aug 22 16:29:15 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_call_response:144 (<0.2557.0>) sending default response for 'UNALLOCATED_NUMBER': {[{<<"Code">>,<<"404">>},{<<"Message">>,<<"No route to destination">>},{<<"Media">>,<<"en%2Ffault-can_not_be_completed_as_dialed">>}]}
Aug 22 16:29:15 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_media_util:491 (<0.2557.0>) account overrides not enabled; ignoring account prompt for en%2Ffault-can_not_be_completed_as_dialed
Aug 22 16:29:15 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.964.0>) published to callctl(amqp://guest:guest@10.11.1.61:5672) exchange (routing key ecallmgr@wap002.zone.domain.com-<0.14087.8>-4431c273) via <0.1115.0>
Aug 22 16:29:15 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.964.0>) published message 71ac2620e5be for <0.2557.0>
Aug 22 16:29:15 server1 2600hz[9338]: |ecallmgr_interaction_cache|kz_cache:817 (<0.1394.0>) storing {channel,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>} for 3600s
Aug 22 16:29:15 server1 2600hz[9338]: |ecallmgr_interaction_cache|kz_cache:819 (<0.1394.0>) inserted {channel,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>}
Aug 22 16:29:15 server1 2600hz[9338]: |ecallmgr_interaction_cache|kz_cache:821 (<0.1394.0>) inserted origin pointers for {channel,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>}
Aug 22 16:29:15 server1 2600hz[9338]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channel:666 (<0.17068.8>) not publishing channel state
Aug 22 16:29:15 server1 2600hz[9338]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channels:445 (<0.1451.0>) removed 1 channel(s) with id 977067d6-c959fc39-9ad840f8@10.20.10.56 on freeswitch@fsn002.zone.domain.com
Aug 22 16:29:16 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_call_command:2332 (<0.2557.0>) channel execution error while waiting for noop: {"Call-ID":"977067d6-c959fc39-9ad840f8@10.20.10.56","Request":{"App-Name":"call_response","App-Version":"0.1.0","Event-Category":"call","Event-Name":"command","Msg-ID":"665b1c8b3c059d24827d061f406640bd","Call-ID":"977067d6-c959fc39-9ad840f8@10.20.10.56","Node":"kazoo_apps@server1.zone.domain.com","Server-ID":"","Application-Name":"noop"},"Error-Message":"Could not execute dialplan action: noop","Server-ID":"","Node":"ecallmgr@wap002.zone.domain.com","Msg-ID":"665b1c8b3c059d24827d061f406640bd","Event-Name":"dialplan","Event-Category":"error","App-Version":"4.0.0","App-Name":"ecallmgr"}
Aug 22 16:29:16 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.964.0>) published to callctl(amqp://guest:guest@10.11.1.61:5672) exchange (routing key ecallmgr@wap002.zone.domain.com-<0.14087.8>-4431c273) via <0.1115.0>
Aug 22 16:29:16 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.964.0>) published message 5156577b96fe86b9 for <0.2504.0>
Aug 22 16:29:16 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_exe:629 (<0.2504.0>) callflow execution has been stopped: normal
Aug 22 16:29:16 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:95 (<0.2504.0>) release consumer <0.2504.0> channel assignment
Aug 22 16:29:16 server1 2600hz[25736]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:724 (<0.2504.0>) cf_exe terminated cleanly, going down

-------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------

Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channel:666 (<0.14064.8>) not publishing channel state
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_event_stream:391 (<0.14063.8>) channel added to cache
Aug 22 16:29:05 server2 2600hz[2361]: |freeswitch@fsn002.zone.domain.com|ecallmgr_fs_router_call:130 (<0.1606.0>) process route request for fetch id 03a90252-8757-11e7-940d-fd88a23bf553 (uuid 977067d6-c959fc39-9ad840f8@10.20.10.56)
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_router_util:34 (<0.14068.8>) authz worker in {<0.14069.8>,#Ref<0.0.1048577.168505>}
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kzs_plan:288 (<0.14068.8>) creating new dataplan <<"4e3e467b873b84dae6ddab7bde09cacb">>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_authz:86 (<0.14069.8>) is destination number 'conference': 01135800659
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_authz:145 (<0.14069.8>) inbound channel is authorized because it is not consuming a resource
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_authz:245 (<0.14069.8>) channel authorization succeeded, allowing call
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_authz:258 (<0.14069.8>) channel is authorized (with channel vars)
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_authz:36 (<0.14069.8>) channel is authorized
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_json:1023 (<0.14068.8>) read fixture for kapp kazoo_data from JSON file: /opt/kazoo/lib/kazoo_data-4.0.63/priv/defaults/system.json
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kzs_plan:288 (<0.14068.8>) creating new dataplan <<"e18327f1b39e9914722804cd1ecb1ea1">>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callmgr(amqp://guest:guest@10.11.1.61:5672) exchange (routing key route.req.audio.4e3e467b873b84dae6ddab7bde09cacb) via <0.5506.0>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:586 (<0.966.0>) published request with msg id 03a90252-8757-11e7-940d-fd88a23bf553 for <0.14068.8>
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ts_route_req:26 (<0.2619.0>) received request 03a90252-8757-11e7-940d-fd88a23bf553 asking if trunkstore can route this call
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ts_route_req:38 (<0.2619.0>) call with fetch-id 03a90252-8757-11e7-940d-fd88a23bf553 began on the network
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ts_callflow:54 (<0.2625.0>) request is not for a trunkstore account
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kzs_plan:288 (<0.2622.0>) creating new dataplan <<"4e3e467b873b84dae6ddab7bde09cacb">>
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:35 (<0.2623.0>) received request 03a90252-8757-11e7-940d-fd88a23bf553 asking if callflows can route this call
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_flow:74 (<0.2623.0>) searching for callflow in account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb to satisfy '+35800659'
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kzs_plan:288 (<0.2623.0>) creating new dataplan <<"4e3e467b873b84dae6ddab7bde09cacb">>
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|milliwatt_route_req:37 (<0.2620.0>) milliwatt does not know what to do with this!
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_router_util:80 (<0.14068.8>) waiting for authz reply from worker <0.14069.8>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_router_util:112 (<0.14068.8>) received affirmative route response for request 03a90252-8757-11e7-940d-fd88a23bf553
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:65 (<0.2623.0>) ignore preflow, not set
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:93 (<0.2623.0>) callflow 07c33b14ca3451a20c231c1942fa2ce1 in 4e3e467b873b84dae6ddab7bde09cacb satisfies request
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_router_util:114 (<0.14068.8>) sending XML to freeswitch@fsn002.zone.domain.com: <document type="freeswitch/xml"><section name="dialplan" description="Route Park Response"><context name="context_2"><extension name="park"><condition><action application="log" data="NOTICE log|${uuid}|ecallmgr@server2.zone.domain.com won call control"/><action application="export" data="ecallmgr_Ecallmgr-Node=ecallmgr@server2.zone.domain.com"/><condition field="variable_ecallmgr_Bridge-ID" expression="^$"><action application="export" data="ecallmgr_Bridge-ID=${UUID}" inline="true"/></condition><action application="set" data="ringback=%(2000,4000,440,480)"/><action application="set" data="transfer_ringback=%(2000,4000,440,480)"/><action application="kz_multiset" data="^^|ecallmgr_Account-ID=4e3e467b873b84dae6ddab7bde09cacb|ecallmgr_Global-Resource=false|ecallmgr_Channel-Authorized=true|ecallmgr_CallFlow-ID=07c33b14ca3451a20c231c1942fa2ce1|ecallmgr_Caller-Privacy-Hide-Number=false|ecallmgr_Caller-Privacy-Hide-Name=false|ecallmgr_Caller-Screen-Bit=true|ecallmgr_Username=f76126aeb2|ecallmgr_Realm=realm.domain.com|presence_id=150@realm.domain.com|ecallmgr_Owner-ID=7145b996f7ee0d40b314114a9869b7fe|ecallmgr_Authorizing-Type=device|ecallmgr_Authorizing-ID=940e247b8b18fbac609cfcae31f6137c|ecallmgr_Account-Realm=realm.domain.com|ecallmgr_Account-Name=Rob's Account rob|ecallmgr_Fetch-ID=03a90252-8757-11e7-940d-fd88a23bf553|ecallmgr_Call-Interaction-ID=63670638545-b8d58883"/><action application="multiunset" data="^^;sip_h_X-AUTH-IP;sip_h_X-AUTH-PORT;sip_h_X-ecallmgr_Account-ID;sip_h_X-ecallmgr_Authorizing-Type;sip_h_X-ecallmgr_Authorizing-ID;sip_h_X-ecallmgr_Username;sip_h_X-ecallmgr_Realm;sip_h_X-ecallmgr_Account-Realm;sip_h_X-ecallmgr_Account-Name;sip_h_X-ecallmgr_Presence-ID;sip_h_X-ecallmgr_Owner-ID"/><action application="park"/></condition></extension></context></section></document>
Aug 22 16:29:05 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:196 (<0.2623.0>) callflows knows how to route the call! sending park response
Aug 22 16:29:05 server2 2600hz[17731]: |03a90252-8757-11e7-940d-fd88a23bf553|kz_amqp_worker:586 (<0.932.0>) published request with msg id 977067d6-c959fc39-9ad840f8@10.20.10.56 for <0.2623.0>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_router_util:118 (<0.14068.8>) node freeswitch@fsn002.zone.domain.com accepted dialplan route response for request 03a90252-8757-11e7-940d-fd88a23bf553
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_router_call:204 (<0.14068.8>) route response recv, attempting to start call handling
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_event_sup:38 (<0.14068.8>) starting event handler for freeswitch@fsn002.zone.domain.com
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:189 (<0.14083.8>) started call event publisher
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:199 (<0.14087.8>) starting call control listener
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.14083.8>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.publisher_usurp.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.32078.6>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1147 (<0.14083.8>) channel started, let's connect
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1178 (<0.14087.8>) binding to call 977067d6-c959fc39-9ad840f8@10.20.10.56 events on node freeswitch@fsn002.zone.domain.com
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1147 (<0.14087.8>) channel started, let's connect
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_router_call:228 (<0.14068.8>) started event {ok,<0.14083.8>} and control {ok,<0.14087.8>} processes
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:310 (<0.14083.8>) declared queue ecallmgr@server2.zone.domain.com-<0.14083.8>-1dd497f0 via channel <0.32078.6>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:310 (<0.14087.8>) declared queue ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273 via channel <0.7343.8>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:336 (<0.14083.8>) created consumer amq.ctag-HOKV5x-pera4Mi9WKNXu4w via channel <0.32078.6>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:889 (<0.14083.8>) queue started: ecallmgr@server2.zone.domain.com-<0.14083.8>-1dd497f0
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1038 (<0.14083.8>) creating new binding: 'call'
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:336 (<0.14087.8>) created consumer amq.ctag-VaWH3a8-DQ1dbPH8Pg9feA via channel <0.7343.8>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:889 (<0.14087.8>) queue started: ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1038 (<0.14087.8>) creating new binding: 'call'
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.14083.8>) bound ecallmgr@server2.zone.domain.com-<0.14083.8>-1dd497f0 to callevt exchange (routing key call.publisher_usurp.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via channel <0.32078.6>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.14087.8>) bound ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273 to callevt exchange (routing key call.usurp_control.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via channel <0.7343.8>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1038 (<0.14087.8>) creating new binding: 'dialplan'
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.14087.8>) bound ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273 to callctl exchange (routing key ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273) via channel <0.7343.8>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1038 (<0.14087.8>) creating new binding: 'self'
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.14087.8>) bound ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273 to targeted exchange (routing key ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273) via channel <0.7343.8>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:465 (<0.14087.8>) sending route_win to kazoo_apps@wap001.zone.domain.com-<0.964.0>-0d8ba841
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.14087.8>) published to targeted(amqp://guest:guest@10.11.1.61:5672) exchange (routing key kazoo_apps@wap001.zone.domain.com-<0.964.0>-0d8ba841) via <0.7343.8>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:473 (<0.14087.8>) sending control usurp for 03a90252-8757-11e7-940d-fd88a23bf553
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.14087.8>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.usurp_control.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.7343.8>
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:884 (<0.14087.8>) recv and executing set now!
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'set' 17a9db934f1f979e589d78bca8f94f6e
Aug 22 16:29:05 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_router_call:231 (<0.14068.8>) xml fetch dialplan 03a90252-8757-11e7-940d-fd88a23bf553 finished with success
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:943 (<0.14087.8>) inserting at the tail of the control queue call command answer
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'answer' 5d563c5291d03bb375d56261a90c14ce
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:706 (<0.14083.8>) publishing call event channel_progress_media
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_PROGRESS_MEDIA.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419346204507 for <0.14083.8>
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channel:666 (<0.14106.8>) not publishing channel state
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'answer()' result: _none_
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419346204507 for <0.14083.8>
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) answer(): ok
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:943 (<0.14087.8>) inserting at the tail of the control queue call command play
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:940 (<0.14087.8>) inserting at the tail of the control queue call command noop(53f057f95f7977a920781d7713892ac8)
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:582 (<0.14087.8>) answer execute complete, advancing control queue
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'play' b57dceee5a1a5c4b8f6b152aab771825
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:1170 (<0.14087.8>) media /account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb/5168655d35008614063ce38e75af5948 exists in playback cache as http://127.0.0.1:15984/account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb/5168655d35008614063ce38e75af5948/uploaded_file_63660531451.mp3?rev=6-ceebbea90ec3c91660bed54e802c41d5
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:1063 (<0.14087.8>) found path http://127.0.0.1:15984/account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb/5168655d35008614063ce38e75af5948/uploaded_file_63660531451.mp3?rev=6-ceebbea90ec3c91660bed54e802c41d5 for /account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb/5168655d35008614063ce38e75af5948
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:1144 (<0.14087.8>) media is streamed via http_cache, using http://127.0.0.1:15984/account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb/5168655d35008614063ce38e75af5948/uploaded_file_63660531451.mp3?rev=6-ceebbea90ec3c91660bed54e802c41d5
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channel:160 (<0.14087.8>) channel is not bridged
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) kz_multiset(playback_terminators=#*0123456789): ok
Aug 22 16:29:06 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) playback(${http_get http://127.0.0.1:15984/account%2F4e%2F3e%2F467b873b84dae6ddab7bde09cacb/5168655d35008614063ce38e75af5948/uploaded_file_63660531451.mp3?rev=6-ceebbea90ec3c91660bed54e802c41d5}): ok
Aug 22 16:29:08 server2 2600hz[17731]: |03a90252-8757-11e7-940d-fd88a23bf553|kz_amqp_worker:890 (<0.932.0>) request timeout exceeded for msg id: 977067d6-c959fc39-9ad840f8@10.20.10.56 and client: <0.2623.0>
Aug 22 16:29:08 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|cf_route_req:222 (<0.2623.0>) callflow didn't received a route win, exiting : timeout
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:698 (<0.14083.8>) publishing received DTMF digit 9
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.DTMF.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:582 (<0.14087.8>) play execute complete, advancing control queue
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419348714487 for <0.14083.8>
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'noop' 53f057f95f7977a920781d7713892ac8
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'play(/usr/share/kazoo-freeswitch/sounds/http_cache/cc/15748c-8752-11e7-93c6-fd88a23bf553.mp3)' result: file played
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419348714487 for <0.14083.8>
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'noop()' result: 53f057f95f7977a920781d7713892ac8
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419348744439 for <0.14083.8>
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:698 (<0.14083.8>) publishing received DTMF digit 6
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.DTMF.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419348854440 for <0.14083.8>
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) event(Event-Subclass=kazoo::noop,Event-Name=CUSTOM,kazoo_event_name=CHANNEL_EXECUTE_COMPLETE,kazoo_application_name=noop,kazoo_application_response=53f057f95f7977a920781d7713892ac8): ok
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:564 (<0.14087.8>) noop execution complete for 53f057f95f7977a920781d7713892ac8, advancing control queue
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:614 (<0.14087.8>) no call commands remain queued, hibernating
Aug 22 16:29:08 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:889 (<0.14087.8>) received control queue flush command, clearing all waiting commands
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:698 (<0.14083.8>) publishing received DTMF digit 5
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.DTMF.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419349014487 for <0.14083.8>
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:940 (<0.14087.8>) inserting at the tail of the control queue call command noop(af149ddaefc312d7123fb4336c06c72c)
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'noop' af149ddaefc312d7123fb4336c06c72c
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'noop()' result: af149ddaefc312d7123fb4336c06c72c
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419349134441 for <0.14083.8>
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) event(Event-Subclass=kazoo::noop,Event-Name=CUSTOM,kazoo_event_name=CHANNEL_EXECUTE_COMPLETE,kazoo_application_name=noop,kazoo_application_response=af149ddaefc312d7123fb4336c06c72c): ok
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:518 (<0.14087.8>) received control queue unconditional advance, skipping wait for command completion of 'noop'
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:528 (<0.14087.8>) no call commands remain queued, hibernating
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:564 (<0.14087.8>) noop execution complete for af149ddaefc312d7123fb4336c06c72c, advancing control queue
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:614 (<0.14087.8>) no call commands remain queued, hibernating
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:698 (<0.14083.8>) publishing received DTMF digit 1
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.DTMF.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:09 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419349854475 for <0.14083.8>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:698 (<0.14083.8>) publishing received DTMF digit #
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.DTMF.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419350014471 for <0.14083.8>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:884 (<0.14087.8>) recv and executing set now!
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'set' 826dbbb627825844bd05896f6d3bf355
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_outbound:46 (<0.2676.0>) received outbound audio resource request for +35800659: {[{<<"Presence-ID">>,<<"150@realm.domain.com">>},{<<"Outbound-Caller-ID-Number">>,<<"+14842067999">>},{<<"Outbound-Caller-ID-Name">>,<<"Rob s Account">>},{<<"Ignore-Early-Media">>,<<"false">>},{<<"From-URI-Realm">>,<<"realm.domain.com">>},{<<"Format-From-URI">>,false},{<<"Fax-T38-Enabled">>,false},{<<"Emergency-Caller-ID-Number">>,<<"+14842067999">>},{<<"Emergency-Caller-ID-Name">>,<<"Rob s Account">>},{<<"Custom-Channel-Vars">>,{[{<<"Caller-Screen-Bit">>,true}]}},{<<"Control-Queue">>,<<"ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273">>},{<<"Call-ID">>,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>},{<<"Bypass-E164">>,false},{<<"B-Leg-Events">>,[<<"DTMF">>]},{<<"Account-Realm">>,<<"realm.domain.com">>},{<<"Account-ID">>,<<"4e3e467b873b84dae6ddab7bde09cacb">>},{<<"To-DID">>,<<"01135800659">>},{<<"Resource-Type">>,<<"audio">>},{<<"Application-Name">>,<<"bridge">>},{<<"Server-ID">>,<<"kazoo_apps@wap001.zone.domain.com-<0.2504.0>-a3dd5634">>},{<<"Node">>,<<"kazoo_apps@wap001.zone.domain.com">>},{<<"Msg-ID">>,<<"c5befe975565">>},{<<"Event-Name">>,<<"offnet_req">>},{<<"Event-Category">>,<<"resource">>},{<<"App-Version">>,<<"4.0.0">>},{<<"App-Name">>,<<"callflow">>}]}
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|knm_phone_number:263 (<0.2676.0>) failed to open +35800659 in numbers%2F%2B3580: not_found
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|knm_port_request:108 (<0.2676.0>) failed to query for port number '+35800659': not_found
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:245 (<0.2676.0>) attempting to find global resources
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:769 (<0.2676.0>) global resource cache miss, fetching from db
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 1de637fa107bfc4cbb1edeb78d8df024
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 1de637fa107bfc4cbb1edeb78d8df024
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 1fe68bd38b2d9954a6ec0304c2330eef
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 1fe68bd38b2d9954a6ec0304c2330eef
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 22d3e5d839f302b8f398058c629e8303
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 22d3e5d839f302b8f398058c629e8303
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 2f02ec97111f1725f551c07899bb1475
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 2f02ec97111f1725f551c07899bb1475
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 4510c05c3778096e619553b37aa3f646
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 4510c05c3778096e619553b37aa3f646
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 5e8adbeb2bd33638207434e85d4ebe17
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 5e8adbeb2bd33638207434e85d4ebe17
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 6888108f1f6dc7feb4d1edb761c1c021
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 6888108f1f6dc7feb4d1edb761c1c021
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 6ba22414d32437fadbb0ccb6337f5268
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 6ba22414d32437fadbb0ccb6337f5268
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 9b81be049131746d8fc10371e42af7f9
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 9b81be049131746d8fc10371e42af7f9
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 9b81be049131746d8fc10371e42b1bc2
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 9b81be049131746d8fc10371e42b1bc2
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / 9ee33e70edaf7f1221a3a51bf2fdfc60
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / 9ee33e70edaf7f1221a3a51bf2fdfc60
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / c600a0aa165ab9d1f92873d69f640c13
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / c600a0aa165ab9d1f92873d69f640c13
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / db1571a8c262a908ff0ee6d91327b060
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / db1571a8c262a908ff0ee6d91327b060
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:982 (<0.2676.0>) compiling resource rules for offnet / db1571a8c262a908ff0ee6d91327b067
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1000 (<0.2676.0>) compiling resource rules for offnet / db1571a8c262a908ff0ee6d91327b067
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_formatters">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kapps_config:386 (<0.2676.0>) setting default for stepswitch [<<"default_route">>]: undefined
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:403 (<0.2676.0>) no flags provided, filtering resources that require flags
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource db1571a8c262a908ff0ee6d91327b067 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource c600a0aa165ab9d1f92873d69f640c13 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 9ee33e70edaf7f1221a3a51bf2fdfc60 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 9b81be049131746d8fc10371e42b1bc2 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 9b81be049131746d8fc10371e42af7f9 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 6ba22414d32437fadbb0ccb6337f5268 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 6888108f1f6dc7feb4d1edb761c1c021 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 5e8adbeb2bd33638207434e85d4ebe17 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:539 (<0.2676.0>) resource 4510c05c3778096e619553b37aa3f646 matches number '+35800659' with regex match '35800659'
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:477 (<0.2676.0>) building resource 4510c05c3778096e619553b37aa3f646 endpoints
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1157 (<0.2676.0>) created gateway route sip:59302240#35800659@216.115.69.144
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 2f02ec97111f1725f551c07899bb1475 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:539 (<0.2676.0>) resource 22d3e5d839f302b8f398058c629e8303 matches number '+35800659' with regex match '35800659'
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:477 (<0.2676.0>) building resource 22d3e5d839f302b8f398058c629e8303 endpoints
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:1157 (<0.2676.0>) created gateway route sip:300035800659@72.15.219.140
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 1fe68bd38b2d9954a6ec0304c2330eef does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_resources:527 (<0.2676.0>) resource 1de637fa107bfc4cbb1edeb78d8df024 does not match request, skipping
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1147 (<0.2682.0>) channel started, let's connect
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:310 (<0.2682.0>) declared queue kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36 via channel <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:336 (<0.2682.0>) created consumer amq.ctag-4RZ5yC5D4eVLgtFfFwKsWg via channel <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:889 (<0.2682.0>) queue started: kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1038 (<0.2682.0>) creating new binding: 'call'
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.2682.0>) bound kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36 to callevt exchange (routing key call.CHANNEL_DESTROY.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via channel <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.2682.0>) bound kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36 to callevt exchange (routing key call.CHANNEL_REPLACED.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via channel <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.2682.0>) bound kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36 to callevt exchange (routing key call.CHANNEL_TRANSFEROR.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via channel <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.2682.0>) bound kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36 to callevt exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via channel <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.2682.0>) bound kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36 to callevt exchange (routing key call.CHANNEL_BRIDGE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via channel <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:1038 (<0.2682.0>) creating new binding: 'self'
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:329 (<0.2682.0>) bound kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36 to targeted exchange (routing key kazoo_apps@server2.zone.domain.com-<0.2682.0>-4d8b9e36) via channel <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_bridge:374 (<0.2682.0>) set outbound caller id to +14842067999 'Rob s Account'
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.2682.0>) published to callctl(amqp://guest:guest@10.11.1.61:5672) exchange (routing key ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273) via <0.2158.0>
Aug 22 16:29:10 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_bridge:291 (<0.2682.0>) sent bridge command to ecallmgr@server2.zone.domain.com-<0.14087.8>-4431c273
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:943 (<0.14087.8>) inserting at the tail of the control queue call command bridge
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'bridge' 42af89b83ff4801d5ec0d5061d776fd9
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_bridge:33 (<0.14087.8>) executing bridge on channel <<"977067d6-c959fc39-9ad840f8@10.20.10.56">>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:116 (<0.14087.8>) sent msg to 977067d6-c959fc39-9ad840f8@10.20.10.56 to bind for b leg events [<<"DTMF">>]
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_bridge:50 (<0.14087.8>) creating bridge dialplan
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:283 (<0.14083.8>) will start event listener for b-leg if encountered
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:653 (<0.14087.8>) building bridge endpoint: [<<"route">>,undefined,undefined,<<"35800659">>,<<"sip:300035800659@72.15.219.140">>,undefined,undefined]
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:653 (<0.14087.8>) building bridge endpoint: [<<"route">>,undefined,undefined,<<"35800659">>,<<"sip:59302240#35800659@216.115.69.144">>,undefined,undefined]
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: kz_multiset ^^;ignore_display_updates=true;ecallmgr_Account-ID=4e3e467b873b84dae6ddab7bde09cacb;ecallmgr_Realm=realm.domain.com;ecallmgr_Reseller-ID=e18327f1b39e9914722804cd1ecb1ea1;ecallmgr_Caller-Screen-Bit=true
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: set continue_on_fail=true
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: export sip_redirect_context=context_2
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: set hangup_after_bridge=true
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: export ecallmgr_Inception=${ecallmgr_Inception}
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: export ecallmgr_Call-Interaction-ID=${ecallmgr_Call-Interaction-ID}
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: bridge {origination_caller_id_number='+14842067999',origination_caller_id_name='Rob s Account',ignore_early_media='false',ignore_display_updates='true',ecallmgr_Account-ID='4e3e467b873b84dae6ddab7bde09cacb',ecallmgr_Realm='realm.domain.com',ecallmgr_Reseller-ID='e18327f1b39e9914722804cd1ecb1ea1',ecallmgr_Caller-Screen-Bit='true',effective_caller_id_number='+14842067999',effective_caller_id_name='Rob s Account',local_var_clobber='true'}[leg_progress_timeout='30',ecallmgr_Global-Resource='true',ecallmgr_Resource-ID='22d3e5d839f302b8f398058c629e8303',ecallmgr_E164-Destination='+35800659',ecallmgr_Original-Number='01135800659',ecallmgr_Matched-Number='35800659',absolute_codec_string='^^:PCMU:PCMA',effective_callee_id_number='35800659',effective_callee_id_name='35800659']sofia/sipinterface_1/300035800659@72.15.219.140|[leg_progress_timeout='30',ecallmgr_Global-Resource='true',ecallmgr_Resource-ID='4510c05c3778096e619553b37aa3f646',ecallmgr_E164-Destination='+35800659',ecallmgr_Original-Number='01135800659',ecallmgr_Matched-Number='35800659',absolute_codec_string='^^:PCMU:PCMA',effective_callee_id_number='35800659',effective_callee_id_name='35800659',sip_auth_username='admin',sip_auth_password='admin']sofia/sipinterface_1/59302240#35800659@216.115.69.144
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: event Event-Name=CUSTOM,Event-Subclass=kazoo::masquerade,kazoo_event_name=CHANNEL_EXECUTE_COMPLETE,kazoo_application_name=bridge
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:97 (<0.14087.8>) building xferext on node freeswitch@fsn002.zone.domain.com: park
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'set(sip_redirect_context=context_2)' result: _none_
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419350264486 for <0.14083.8>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'set(ecallmgr_Inception=)' result: _none_
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419350264486 for <0.14083.8>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'set(ecallmgr_Call-Interaction-ID=63670638545-b8d58883)' result: _none_
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419350264486 for <0.14083.8>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:691 (<0.14087.8>) added leg 06b74ec2-8757-11e7-9424-fd88a23bf553 to call
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:706 (<0.14154.8>) publishing call event leg_created
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.973.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.LEG_CREATED.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5518.0>
Aug 22 16:29:10 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.973.0>) published message 1503419350264486 for <0.14154.8>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:780 (<0.14087.8>) removed leg 06b74ec2-8757-11e7-9424-fd88a23bf553 from call
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:706 (<0.14177.8>) publishing call event leg_destroyed
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:691 (<0.14087.8>) added leg 09bac4a0-8757-11e7-9429-fd88a23bf553 to call
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:706 (<0.14179.8>) publishing call event leg_created
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.LEG_DESTROYED.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419355314486 for <0.14177.8>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.973.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.LEG_CREATED.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5518.0>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.973.0>) published message 1503419355314486 for <0.14179.8>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:780 (<0.14087.8>) removed leg 09bac4a0-8757-11e7-9429-fd88a23bf553 from call
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:706 (<0.14191.8>) publishing call event leg_destroyed
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:913 (<0.14083.8>) suppressing bridge execute complete in favour the kazoo masquerade of this event
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.LEG_DESTROYED.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:582 (<0.14087.8>) bridge execute complete, advancing control queue
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419355454439 for <0.14191.8>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:614 (<0.14087.8>) no call commands remain queued, hibernating
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'bridge()' result: unallocated_number
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419355454439 for <0.14083.8>
Aug 22 16:29:15 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_bridge:232 (<0.2682.0>) channel execute complete for bridge
Aug 22 16:29:15 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_bridge:553 (<0.2682.0>) resources for outbound request failed: UNALLOCATED_NUMBER
Aug 22 16:29:15 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.2682.0>) published to targeted(amqp://guest:guest@10.11.1.61:5672) exchange (routing key kazoo_apps@wap001.zone.domain.com-<0.2504.0>-a3dd5634) via <0.2158.0>
Aug 22 16:29:15 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|stepswitch_bridge:260 (<0.2682.0>) listener terminating: normal
Aug 22 16:29:15 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:95 (<0.2682.0>) release consumer <0.2682.0> channel assignment
Aug 22 16:29:15 server2 2600hz[17731]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:724 (<0.2682.0>) stepswitch_bridge terminated cleanly, going down
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:943 (<0.14087.8>) inserting at the tail of the control queue call command progress
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:943 (<0.14087.8>) inserting at the tail of the control queue call command play
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:943 (<0.14087.8>) inserting at the tail of the control queue call command respond
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:940 (<0.14087.8>) inserting at the tail of the control queue call command noop(665b1c8b3c059d24827d061f406640bd)
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'progress' 665b1c8b3c059d24827d061f406640bd
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'progress()' result: _none_
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419355494439 for <0.14083.8>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) pre_answer(): ok
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:582 (<0.14087.8>) progress execute complete, advancing control queue
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'play' 665b1c8b3c059d24827d061f406640bd
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:1170 (<0.14087.8>) media prompt://system_media/en%2Ffault-can_not_be_completed_as_dialed/en-us exists in playback cache as
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:1063 (<0.14087.8>) found path for prompt://system_media/en%2Ffault-can_not_be_completed_as_dialed/en-us
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:1144 (<0.14087.8>) media is streamed via http_cache, using
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channel:160 (<0.14087.8>) channel is not bridged
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'play(USAGE: {param=val}<url>#012)' result: file not found
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419355694485 for <0.14083.8>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) playback(${http_get }): ok
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:582 (<0.14087.8>) play execute complete, advancing control queue
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'respond' 665b1c8b3c059d24827d061f406640bd
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:710 (<0.14083.8>) publishing call event channel_execute_complete 'respond(404 No route to destination)' result: _none_
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.966.0>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.5506.0>
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_worker:663 (<0.966.0>) published message 1503419355894435 for <0.14083.8>
Aug 22 16:29:15 server2 2600hz[2361]: |ecallmgr_interaction_cache|kz_cache:817 (<0.1396.0>) storing {channel,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>} for 3600s
Aug 22 16:29:15 server2 2600hz[2361]: |ecallmgr_interaction_cache|kz_cache:819 (<0.1396.0>) inserted {channel,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>}
Aug 22 16:29:15 server2 2600hz[2361]: |ecallmgr_interaction_cache|kz_cache:821 (<0.1396.0>) inserted origin pointers for {channel,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>}
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:267 (<0.14083.8>) call event listener on node freeswitch@fsn002.zone.domain.com received graceful shutdown request
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channel:666 (<0.14202.8>) not publishing channel state
Aug 22 16:29:15 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_fs_channels:445 (<0.1455.0>) removed 1 channel(s) with id 977067d6-c959fc39-9ad840f8@10.20.10.56 on freeswitch@fsn002.zone.domain.com
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) respond(404 No route to destination): ok
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:582 (<0.14087.8>) respond execute complete, advancing control queue
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1033 (<0.14087.8>) executing call command 'noop' 665b1c8b3c059d24827d061f406640bd
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_util:146 (<0.14087.8>) execute on node freeswitch@fsn002.zone.domain.com(977067d6-c959fc39-9ad840f8@10.20.10.56) event(Event-Subclass=kazoo::noop,Event-Name=CUSTOM,kazoo_event_name=CHANNEL_EXECUTE_COMPLETE,kazoo_application_name=noop,kazoo_application_response=665b1c8b3c059d24827d061f406640bd): {error,baduuid}
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:630 (<0.14087.8>) checking interaction cache for 977067d6-c959fc39-9ad840f8@10.20.10.56
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:637 (<0.14087.8>) interaction cache for 977067d6-c959fc39-9ad840f8@10.20.10.56 not found
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:488 (<0.14087.8>) our channel has been destroyed, executing any post-hangup commands
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1137 (<0.14087.8>) sending execution error: [{<<"Msg-ID">>,<<"665b1c8b3c059d24827d061f406640bd">>},{<<"Error-Message">>,<<"Could not execute dialplan action: noop">>},{<<"Request">>,{[{<<"App-Name">>,<<"call_response">>},{<<"App-Version">>,<<"0.1.0">>},{<<"Event-Category">>,<<"call">>},{<<"Event-Name">>,<<"command">>},{<<"Msg-ID">>,<<"665b1c8b3c059d24827d061f406640bd">>},{<<"Call-ID">>,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>},{<<"Node">>,<<"kazoo_apps@wap001.zone.domain.com">>},{<<"Server-ID">>,<<>>},{<<"Application-Name">>,<<"noop">>}]}},{<<"Call-ID">>,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>},{<<"Custom-Channel-Vars">>,undefined},{<<"Server-ID">>,<<>>},{<<"Event-Category">>,<<"error">>},{<<"Event-Name">>,<<"dialplan">>},{<<"App-Name">>,<<"ecallmgr">>},{<<"App-Version">>,<<"4.0.0">>},{<<"Node">>,<<"ecallmgr@server2.zone.domain.com">>}]
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.14087.8>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.diaplan.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.7343.8>
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1156 (<0.14087.8>) started post hangup keep alive timer for 120000ms
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:630 (<0.14087.8>) checking interaction cache for 977067d6-c959fc39-9ad840f8@10.20.10.56
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:637 (<0.14087.8>) interaction cache for 977067d6-c959fc39-9ad840f8@10.20.10.56 not found
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:488 (<0.14087.8>) our channel has been destroyed, executing any post-hangup commands
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1137 (<0.14087.8>) sending execution error: [{<<"Msg-ID">>,<<"665b1c8b3c059d24827d061f406640bd">>},{<<"Error-Message">>,<<"Could not execute dialplan action: noop">>},{<<"Request">>,{[{<<"App-Name">>,<<"call_response">>},{<<"App-Version">>,<<"0.1.0">>},{<<"Event-Category">>,<<"call">>},{<<"Event-Name">>,<<"command">>},{<<"Msg-ID">>,<<"665b1c8b3c059d24827d061f406640bd">>},{<<"Call-ID">>,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>},{<<"Node">>,<<"kazoo_apps@wap001.zone.domain.com">>},{<<"Server-ID">>,<<>>},{<<"Application-Name">>,<<"noop">>}]}},{<<"Call-ID">>,<<"977067d6-c959fc39-9ad840f8@10.20.10.56">>},{<<"Custom-Channel-Vars">>,undefined},{<<"Server-ID">>,<<>>},{<<"Event-Category">>,<<"error">>},{<<"Event-Name">>,<<"dialplan">>},{<<"App-Name">>,<<"ecallmgr">>},{<<"App-Version">>,<<"4.0.0">>},{<<"Node">>,<<"ecallmgr@server2.zone.domain.com">>}]
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:162 (<0.14087.8>) published to callevt(amqp://guest:guest@10.11.1.61:5672) exchange (routing key call.diaplan.977067d6-c959fc39-9ad840f8%4010%2E20%2E10%2E56) via <0.7343.8>
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1167 (<0.14087.8>) reset post hangup keep alive timer
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:518 (<0.14087.8>) received control queue unconditional advance, skipping wait for command completion of 'noop'
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:528 (<0.14087.8>) no call commands remain queued, hibernating
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:518 (<0.14087.8>) received control queue unconditional advance, skipping wait for command completion of 'undefined'
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:528 (<0.14087.8>) no call commands remain queued, hibernating
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:943 (<0.14087.8>) inserting at the tail of the control queue call command hangup
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:831 (<0.14087.8>) command 'hangup' is not valid after hangup, ignoring
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:1167 (<0.14087.8>) reset post hangup keep alive timer
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:518 (<0.14087.8>) received control queue unconditional advance, skipping wait for command completion of 'hangup'
Aug 22 16:29:16 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_control:528 (<0.14087.8>) no call commands remain queued, hibernating
Aug 22 16:29:20 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|ecallmgr_call_events:546 (<0.14083.8>) goodbye and thanks for all the fish: normal
Aug 22 16:29:20 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|kz_amqp_channel:95 (<0.14083.8>) release consumer <0.14083.8> channel assignment
Aug 22 16:29:20 server2 2600hz[2361]: |977067d6-c959fc39-9ad840f8@10.20.10.56|gen_listener:724 (<0.14083.8>) ecallmgr_call_events terminated cleanly, going down

mc_ · August 23, 2017

Ah, I see the issue. The 'offnet' callflow action is specifically for global carrier usage (and considered deprecated). It predates BYOC and local resource usage; it is kept for backwards compatibility only (and explicitly sets use_local_resources to false).

Change 'offnet' to 'resources' in the callflow action and the local resource(s) should be used.

https://docs.2600hz.com/dev/applications/callflow/doc/resources/

extremerotary · August 23, 2017

That worked. Knew it was something simple I was missing, thanks!

Sign In

How to use a special carrier for an account?

Recommended Posts

extremerotary

Te Matau

extremerotary

mc_

extremerotary

mc_

extremerotary

Community Forums

Products

Support