9PgJPg3Q

1[root@newitsp ~]# /etc/init.d/kz-whistle_apps status
2Node          : whistle_apps@newitsp.local
3Version       : 3.21-36 - R15B03
4Memory Usage  : 51.91MB
5Processes     : 1735
6Ports         : 32
7Zone          : local
8Broker        : amqp://127.0.0.1:5672
9WhApps        : callflow(3d9h37m27s)     camper(3d9h37m24s)       cdr(3d9h37m26s)          conference(3d9h37m25s)
10                crossbar(3d9h37m26s)     doodle(3d9h37m24s)       fax(3d9h37m25s)          frontier
11                hangups(3d9h37m25s)      hotornot(3d9h37m24s)     jonny5(3d9h37m24s)       media_mgr(3d9h37m27s)
12                milliwatt(3d9h37m24s)    notify(3d9h37m26s)       omnipresence(3d9h37m25s) pivot(3d9h37m24s)
13                registrar(3d9h37m27s)    reorder(3d9h37m27s)      stepswitch(3d9h37m27s)   sysconf(3d9h37m27s)
14                trunkstore(3d9h37m25s)
15
16Node          : kamailio@newitsp.local
17Version       : 4.2.3
18Memory Usage  : 13.66MB
19Processes     : 0
20Ports         : 0
21Zone          : local
22Broker        : amqp://127.0.0.1:5672
23WhApps        : kamailio(1d23h8m15s)
24Registrations : 2
25
26Node          : ecallmgr@newitsp.local
27Version       : 3.21-36 - R15B03
28Memory Usage  : 26.33MB
29Processes     : 1004
30Ports         : 47
31Zone          : local
32Broker        : amqp://127.0.0.1:5672
33WhApps        : ecallmgr(3d9h37m21s)
34Channels      : 0
35Registrations : 2
36Media Servers : freeswitch@newitsp.local (3d9h37m21s)
37
38
39[root@newitsp ~]# tail -f /var/log/2600hz/kazoo.log
40Dec 20 23:57:23 newitsp 2600hz[2616]: |undefined|wh_amqp_channel:154 (<0.532.0>) published to targeted(amqp://guest:guest@127.0.0.1:5672) exchange (routing key statistics) via <0.1110.0>
41Dec 20 23:57:24 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:671 (<0.1091.0>) checking if we should compact account/40/97/c20a6440a6bc1f2b19f7b8456d30-201510 on bigcouch@newitsp.local
42Dec 20 23:57:24 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1647 (<0.1091.0>) getting db info attempt 1
43Dec 20 23:57:24 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1677 (<0.1091.0>) data size 59586 is under min_data_size threshold 131072
44Dec 20 23:57:24 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:678 (<0.1091.0>) db account/40/97/c20a6440a6bc1f2b19f7b8456d30-201510 not found on bigcouch@newitsp.local OR heuristic not met
45Dec 20 23:57:27 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:671 (<0.1091.0>) checking if we should compact sip_auth on bigcouch@newitsp.local
46Dec 20 23:57:27 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1647 (<0.1091.0>) getting db info attempt 1
47Dec 20 23:57:27 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1677 (<0.1091.0>) data size 3813 is under min_data_size threshold 131072
48Dec 20 23:57:27 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:678 (<0.1091.0>) db sip_auth not found on bigcouch@newitsp.local OR heuristic not met
49Dec 20 23:57:27 newitsp 2600hz[2563]: |wh_nodes|wh_amqp_channel:154 (<0.1112.0>) published to nodes(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ) via <0.1060.0>
50Dec 20 23:57:30 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:671 (<0.1091.0>) checking if we should compact system_schemas on bigcouch@newitsp.local
51Dec 20 23:57:30 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1647 (<0.1091.0>) getting db info attempt 1
52Dec 20 23:57:30 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1674 (<0.1091.0>) data size 165264 is larger than minimum 131072
53Dec 20 23:57:30 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1687 (<0.1091.0>) ratio 0.5210632684674218 (86113/165264) is under min threshold 1.2
54Dec 20 23:57:30 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:678 (<0.1091.0>) db system_schemas not found on bigcouch@newitsp.local OR heuristic not met
55Dec 20 23:57:30 newitsp 2600hz[2616]: |wh_nodes|wh_amqp_channel:154 (<0.534.0>) published to nodes(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ) via <0.1050.0>
56Dec 20 23:57:33 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:671 (<0.1091.0>) checking if we should compact anonymous_cdrs on bigcouch@newitsp.local
57Dec 20 23:57:33 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1647 (<0.1091.0>) getting db info attempt 1
58Dec 20 23:57:33 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1677 (<0.1091.0>) data size 530 is under min_data_size threshold 131072
59Dec 20 23:57:33 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:678 (<0.1091.0>) db anonymous_cdrs not found on bigcouch@newitsp.local OR heuristic not met
60Dec 20 23:57:33 newitsp 2600hz[2616]: |undefined|wh_amqp_channel:154 (<0.532.0>) published to targeted(amqp://guest:guest@127.0.0.1:5672) exchange (routing key statistics) via <0.1110.0>
61Dec 20 23:57:33 newitsp 2600hz[2563]: |wh_port_request_crawler|wh_port_request:238 (<0.30327.29>) sent requests
62Dec 20 23:57:33 newitsp 2600hz[2616]: |7005f1b2-a775-11e5-b7cc-ed92e00b2474|ecallmgr_fs_authn:172 (<0.13844.16>) received fetch request for INVITE (7005f1b2-a775-11e5-b7cc-ed92e00b2474) user creds from freeswitch@newitsp.local
63Dec 20 23:57:33 newitsp 2600hz[2616]: |7005f1b2-a775-11e5-b7cc-ed92e00b2474|ecallmgr_fs_authn:238 (<0.13844.16>) building authn resp for admin@spoon8.local
64Dec 20 23:57:33 newitsp 2600hz[2616]: |7005f1b2-a775-11e5-b7cc-ed92e00b2474|ecallmgr_fs_authn:194 (<0.13844.16>) sending authn XML to 'freeswitch@newitsp.local': <document type="freeswitch/xml"><section name="directory"><domain name="spoon8.local"><user id="admin" cacheable="3600000"><variables><variable name="ecallmgr_Username" value="admin"/><variable name="ecallmgr_Realm" value="spoon8.local"/><variable name="ecallmgr_Account-ID" value="38bda41d624cf9cac14440c621d53fb8"/><variable name="ecallmgr_Authorizing-ID" value="34fffb06739bdcadfe4f14b9af16bf3b"/><variable name="ecallmgr_Authorizing-Type" value="sys_info"/><variable name="ecallmgr_Account-Realm" value="spoon8.local"/><variable name="ecallmgr_Account-Name" value="spoon8.local"/><variable name="ecallmgr_Suppress-Unregister-Notifications" value="false"/><variable name="ecallmgr_Register-Overwrite-Notify" value="false"/></variables><params><param name="password" value="Abc1234"/></params></user></domain></section></document>
65Dec 20 23:57:33 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:659 (<0.13846.16>) publishing call event channel_create
66Dec 20 23:57:33 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13846.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_CREATE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1115.0>
67Dec 20 23:57:33 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_presence:193 (<0.30335.29>) received channel create, checking for presence subscribers
68Dec 20 23:57:33 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_presence:313 (<0.30335.29>) no presence subscriptions for admin@spoon8.local
69Dec 20 23:57:33 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_dialog:179 (<0.30336.29>) received channel create, checking for dialog subscribers
70Dec 20 23:57:33 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_dialog:345 (<0.30336.29>) no dialog subscriptions for admin@spoon8.local
71Dec 20 23:57:34 newitsp 2600hz[2616]: |freeswitch@newitsp.local|ecallmgr_fs_route:142 (<0.1143.0>) processing dialplan fetch request 701db608-a775-11e5-b7ce-ed92e00b2474 (call 3222b297-2219-1234-6aaf-000c29af03db) from freeswitch@newitsp.local
72Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_authz:140 (<0.13848.16>) inbound channel is not consuming a resource
73Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_authz:225 (<0.13848.16>) channel authorization succeeded, allowing call
74Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_authz:29 (<0.13848.16>) channel is authorized
75Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.348.0>) published to callmgr(amqp://guest:guest@127.0.0.1:5672) exchange (routing key route.req.spoon8%2Elocal.admin) via <0.602.0>
76Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_worker:496 (<0.348.0>) published request with msg id 701db608-a775-11e5-b7ce-ed92e00b2474 for <0.13847.16>
77Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_route_req:24 (<0.30349.29>) received a request asking if trunkstore can route this call
78Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_route_req:35 (<0.30349.29>) call began on the network
79Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|milliwatt_route_req:39 (<0.30352.29>) milliwatt does not know what to do with this!
80Dec 20 23:57:34 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:468 (<0.95.0>) assigned existing channel <0.2182.0> on amqp://guest:guest@127.0.0.1:5672 to new consumer <0.30354.29>
81Dec 20 23:57:34 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:872 (<0.95.0>) registered handlers for channel <0.2182.0> to <0.30354.29>
82Dec 20 23:57:34 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.1602.0>) Supervisor ts_onnet_sup started ts_from_onnet:start_link({[{<<"From-Tag">>,<<"QS6aU82HvD0pQ">>},{<<"SIP-Request-Host">>,<<"192.168.1.247">>},{<<"Switch-U...">>,...},...]}) at pid <0.30354.29>
83Dec 20 23:57:34 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.30356.29>) Supervisor {<0.30356.29>,amqp_channel_sup} started amqp_gen_consumer:start_link(amqp_selective_consumer, [], {<<"client 127.0.0.1:53018 -> 127.0.0.1:5672">>,261}) at pid <0.30357.29>
84Dec 20 23:57:34 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.30356.29>) Supervisor {<0.30356.29>,amqp_channel_sup} started amqp_channel:start_link(network, <0.106.0>, 261, <0.30357.29>, {<<"client 127.0.0.1:53018 -> 127.0.0.1:5672">>,261}) at pid <0.30358.29>
85Dec 20 23:57:34 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.30356.29>) Supervisor {<0.30356.29>,amqp_channel_sup} started rabbit_writer:start_link(#Port<0.8760>, 261, 131072, rabbit_framing_amqp_0_9_1, <0.30358.29>, {<<"client 127.0.0.1:53018 -> 127.0.0.1:5672">>,261}) at pid <0.30359.29>
86Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:288 (<0.30354.29>) declared queue whistle_apps@newitsp.local-<0.30354.29>-afb81b17 via channel <0.2182.0>
87Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.30354.29>) bound whistle_apps@newitsp.local-<0.30354.29>-afb81b17 to targeted exchange (routing key whistle_apps@newitsp.local-<0.30354.29>-afb81b17) via channel <0.2182.0>
88Dec 20 23:57:34 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:593 (<0.95.0>) added new channel <0.30358.29> on amqp://guest:guest@127.0.0.1:5672 to available pool
89Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:314 (<0.30354.29>) created consumer amq.ctag-0Trs2leZtc356PQwMwQIvw via channel <0.2182.0>
90Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:71 (<0.30354.29>) started AMQP with queue whistle_apps@newitsp.local-<0.30354.29>-afb81b17
91Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_from_onnet:39 (<0.30354.29>) on-net request from 102(38bda41d624cf9cac14440c621d53fb8) to +16135551111
92Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_util:113 (<0.30354.29>) cache miss for 102, no results
93Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_util:181 (<0.30354.29>) cache hit for admin@spoon8.local
94Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wnm_util:261 (<0.30354.29>) number '+16135551111' can be reconciled, proceeding
95Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_from_onnet:146 (<0.30354.29>) we know how to route this call, sending park route response
96Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:86 (<0.30354.29>) trunkstore knows how to route this call, sending park route response
97Dec 20 23:57:34 newitsp 2600hz[2616]: |701db608-a775-11e5-b7ce-ed92e00b2474|wh_amqp_worker:663 (<0.348.0>) response for msg id 701db608-a775-11e5-b7ce-ed92e00b2474 took 12951 micro to return
98Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_cache:327 (<0.13847.16>) waiting for message with ref #Ref<0.0.4.119840>
99Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_route:308 (<0.13847.16>) received affirmative route response for request 701db608-a775-11e5-b7ce-ed92e00b2474
100Dec 20 23:57:34 newitsp 2600hz[2616]: |ecallmgr_util_cache|wh_cache:447 (<0.588.0>) removed 1 objects for {authz_response,<<"3222b297-2219-1234-6aaf-000c29af03db">>}
101Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_route:310 (<0.13847.16>) sending XML to freeswitch@newitsp.local: <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@newitsp.local won call control"/><action application="export" data="ecallmgr_Ecallmgr-Node=ecallmgr@newitsp.local"/><condition field="variable_ecallmgr_Bridge-ID" expression="^$"><action application="export" data="ecallmgr_Bridge-ID=${UUID}"/></condition><action application="set" data="ringback=%(2000,4000,440,480)"/><action application="set" data="transfer_ringback=%(2000,4000,440,480)"/><action application="ring_ready"/><action application="set" data="ecallmgr_Fetch-ID=701db608-a775-11e5-b7ce-ed92e00b2474"/><action application="set" data="ecallmgr_Register-Overwrite-Notify=false"/><action application="set" data="ecallmgr_Suppress-Unregister-Notifications=false"/><action application="set" data="ecallmgr_Account-Name=spoon8.local"/><action application="set" data="ecallmgr_Account-Realm=spoon8.local"/><action application="set" data="ecallmgr_Authorizing-Type=sys_info"/><action application="set" data="ecallmgr_Authorizing-ID=34fffb06739bdcadfe4f14b9af16bf3b"/><action application="set" data="ecallmgr_Realm=spoon8.local"/><action application="set" data="ecallmgr_Username=admin"/><action application="set" data="ecallmgr_Channel-Authorized=true"/><action application="set" data="ecallmgr_Global-Resource=false"/><action application="set" data="ecallmgr_Account-ID=38bda41d624cf9cac14440c621d53fb8"/><action application="park"/></condition></extension></context></section></document>
102Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.30354.29>) published to targeted(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ecallmgr@newitsp.local-<0.348.0>-7c70d11f) via <0.2182.0>
103Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_route:314 (<0.13847.16>) node freeswitch@newitsp.local accepted dialplan route response for request 701db608-a775-11e5-b7ce-ed92e00b2474
104Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_event_sup:39 (<0.13847.16>) no registrations for freeswitch@newitsp.local
105Dec 20 23:57:34 newitsp 2600hz[2616]: |ecallmgr_call_events|gen_listener:385 (<0.13916.16>) starting new gen_listener proc
106Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:191 (<0.13916.16>) started call event publisher
107Dec 20 23:57:34 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_assignments:468 (<0.98.0>) assigned existing channel <0.1150.0> on amqp://guest:guest@127.0.0.1:5672 to new consumer <0.13916.16>
108Dec 20 23:57:34 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_assignments:872 (<0.98.0>) registered handlers for channel <0.1150.0> to <0.13916.16>
109Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.publisher_usurp.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
110Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:1086 (<0.13916.16>) channel started, let's connect
111Dec 20 23:57:34 newitsp 2600hz[2616]: |ecallmgr_call_control|gen_listener:385 (<0.13918.16>) starting new gen_listener proc
112Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:245 (<0.13918.16>) starting call control listener
113Dec 20 23:57:34 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_assignments:468 (<0.98.0>) assigned existing channel <0.1156.0> on amqp://guest:guest@127.0.0.1:5672 to new consumer <0.13918.16>
114Dec 20 23:57:34 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_assignments:872 (<0.98.0>) registered handlers for channel <0.1156.0> to <0.13918.16>
115Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:1207 (<0.13918.16>) binding to call 3222b297-2219-1234-6aaf-000c29af03db events on node freeswitch@newitsp.local
116Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:1086 (<0.13918.16>) channel started, let's connect
117Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_command:32 (<0.13847.16>) freeswitch@newitsp.local api uuid_setvar_mulit 3222b297-2219-1234-6aaf-000c29af03db ecallmgr_Application-Name=trunkstore;ecallmgr_Application-Node=whistle_apps@newitsp.local;ecallmgr_Fetch-ID=701db608-a775-11e5-b7ce-ed92e00b2474;ecallmgr_Register-Overwrite-Notify=false;ecallmgr_Suppress-Unregister-Notifications=false;ecallmgr_Account-Name=spoon8.local;ecallmgr_Account-Realm=spoon8.local;ecallmgr_Authorizing-Type=sys_info;ecallmgr_Authorizing-ID=34fffb06739bdcadfe4f14b9af16bf3b;ecallmgr_Realm=spoon8.local;ecallmgr_Username=admin;ecallmgr_Channel-Authorized=true;ecallmgr_Account-ID=38bda41d624cf9cac14440c621d53fb8
118Dec 20 23:57:34 newitsp 2600hz[2616]: |0000000000|Undefined:Undefined (<0.13919.16>) Supervisor {<0.13919.16>,amqp_channel_sup} started amqp_gen_consumer:start_link(amqp_selective_consumer, [], {<<"client 127.0.0.1:41868 -> 127.0.0.1:5672">>,180}) at pid <0.13920.16>
119Dec 20 23:57:34 newitsp 2600hz[2616]: |0000000000|Undefined:Undefined (<0.13919.16>) Supervisor {<0.13919.16>,amqp_channel_sup} started amqp_channel:start_link(network, <0.109.0>, 180, <0.13920.16>, {<<"client 127.0.0.1:41868 -> 127.0.0.1:5672">>,180}) at pid <0.13923.16>
120Dec 20 23:57:34 newitsp 2600hz[2616]: |0000000000|Undefined:Undefined (<0.13919.16>) Supervisor {<0.13919.16>,amqp_channel_sup} started rabbit_writer:start_link(#Port<0.8753>, 180, 131072, rabbit_framing_amqp_0_9_1, <0.13923.16>, {<<"client 127.0.0.1:41868 -> 127.0.0.1:5672">>,180}) at pid <0.13924.16>
121Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:288 (<0.13916.16>) declared queue ecallmgr@newitsp.local-<0.13916.16>-743eefee via channel <0.1150.0>
122Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:288 (<0.13918.16>) declared queue ecallmgr@newitsp.local-<0.13918.16>-09186f50 via channel <0.1156.0>
123Dec 20 23:57:34 newitsp 2600hz[2616]: |0000000000|Undefined:Undefined (<0.13925.16>) Supervisor {<0.13925.16>,amqp_channel_sup} started amqp_gen_consumer:start_link(amqp_selective_consumer, [], {<<"client 127.0.0.1:41868 -> 127.0.0.1:5672">>,181}) at pid <0.13926.16>
124Dec 20 23:57:34 newitsp 2600hz[2616]: |0000000000|Undefined:Undefined (<0.13925.16>) Supervisor {<0.13925.16>,amqp_channel_sup} started amqp_channel:start_link(network, <0.109.0>, 181, <0.13926.16>, {<<"client 127.0.0.1:41868 -> 127.0.0.1:5672">>,181}) at pid <0.13927.16>
125Dec 20 23:57:34 newitsp 2600hz[2616]: |0000000000|Undefined:Undefined (<0.13925.16>) Supervisor {<0.13925.16>,amqp_channel_sup} started rabbit_writer:start_link(#Port<0.8753>, 181, 131072, rabbit_framing_amqp_0_9_1, <0.13927.16>, {<<"client 127.0.0.1:41868 -> 127.0.0.1:5672">>,181}) at pid <0.13928.16>
126Dec 20 23:57:34 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_assignments:593 (<0.98.0>) added new channel <0.13923.16> on amqp://guest:guest@127.0.0.1:5672 to available pool
127Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:314 (<0.13916.16>) created consumer amq.ctag-bLS2OKbHgtdQKTNrzdB_8A via channel <0.1150.0>
128Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:857 (<0.13916.16>) queue started: ecallmgr@newitsp.local-<0.13916.16>-743eefee
129Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:994 (<0.13916.16>) creating new binding: 'call'
130Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:314 (<0.13918.16>) created consumer amq.ctag-EJps2K8dZMgxAciJoVfz2A via channel <0.1156.0>
131Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:857 (<0.13918.16>) queue started: ecallmgr@newitsp.local-<0.13918.16>-09186f50
132Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:994 (<0.13918.16>) creating new binding: 'call'
133Dec 20 23:57:34 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_assignments:593 (<0.98.0>) added new channel <0.13927.16> on amqp://guest:guest@127.0.0.1:5672 to available pool
134Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.13916.16>) bound ecallmgr@newitsp.local-<0.13916.16>-743eefee to callevt exchange (routing key call.publisher_usurp.3222b297-2219-1234-6aaf-000c29af03db) via channel <0.1150.0>
135Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|listener_utils:85 (<0.13916.16>) ignoring init for responder ecallmgr_call_events: handle_publisher_usurp
136Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_DATA/undefined
137Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:572 (<0.13916.16>) not publishing CHANNEL_DATA(undefined): undefined
138Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.13918.16>) bound ecallmgr@newitsp.local-<0.13918.16>-09186f50 to callevt exchange (routing key call.usurp_control.3222b297-2219-1234-6aaf-000c29af03db) via channel <0.1156.0>
139Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:994 (<0.13918.16>) creating new binding: 'dialplan'
140Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.13918.16>) bound ecallmgr@newitsp.local-<0.13918.16>-09186f50 to callctl exchange (routing key ecallmgr@newitsp.local-<0.13918.16>-09186f50) via channel <0.1156.0>
141Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:994 (<0.13918.16>) creating new binding: 'self'
142Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.13918.16>) bound ecallmgr@newitsp.local-<0.13918.16>-09186f50 to targeted exchange (routing key ecallmgr@newitsp.local-<0.13918.16>-09186f50) via channel <0.1156.0>
143Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|listener_utils:85 (<0.13918.16>) ignoring init for responder ecallmgr_call_control: handle_call_command
144Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|listener_utils:85 (<0.13918.16>) ignoring init for responder ecallmgr_call_control: handle_conference_command
145Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|listener_utils:85 (<0.13918.16>) ignoring init for responder ecallmgr_call_control: handle_call_events
146Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:536 (<0.13918.16>) sending route_win to whistle_apps@newitsp.local-<0.30354.29>-afb81b17
147Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:101 (<0.30354.29>) callflow has received a route win, taking control of the call
148Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.30354.29>) bound whistle_apps@newitsp.local-<0.30354.29>-afb81b17 to callevt exchange (routing key call.*.3222b297-2219-1234-6aaf-000c29af03db) via channel <0.2182.0>
149Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wnm_util:232 (<0.30354.29>) number '+16135551111' is classified as did_us
150Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_util:364 (<0.30354.29>) Trunkstore classified number as <<"did_us">>
151Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_util:181 (<0.30354.29>) cache hit for admin@spoon8.local
152Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_util:368 (<0.30354.29>) Trunkstore lookup_user_flag results: {[{<<"server">>,{[{<<"DIDs">>,{[]}},{<<"options">>,{[{<<"enabled">>,true},{<<"inbound_format">>,<<"e164">>},{<<"international">>,false},{<<"caller_id">>,{[]}},{<<"e911_info">>,{[]}},{<<"failover">>,{[]}},{<<"media_handling">>,<<"bypass">>}]}},{<<"permissions">>,{[{<<"users">>,[]}]}},{<<"monitor">>,{[{<<"monitor_enabled">>,false}]}},{<<"server_name">>,<<"PBX new">>},{<<"auth">>,{[{<<"auth_method">>,<<"Password">>},{<<"ip">>,<<"192.168.1.241">>},{<<"auth_user">>,<<"admin">>},{<<"auth_password">>,<<"Abc1234">>},{<<"auth_realm">>,<<"spoon8.local">>}]}},{<<"server_type">>,<<"Cisco">>},{<<"cfg">>,{[]}}]}},{<<"account">>,{[{<<"credits">>,{[{<<"prepay">>,<<"0.00">>}]}},{<<"trunks">>,0},{<<"inbound_trunks">>,0},{<<"auth_realm">>,<<"spoon8.local">>}]}},{<<"id">>,<<"34fffb06739bdcadfe4f14b9af16bf3b">>}]}
153Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.30354.29>) published to resource(amqp://guest:guest@127.0.0.1:5672) exchange (routing key offnet.resource.req) via <0.2182.0>
154Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:121 (<0.30354.29>) unexpected msg: {'$gen_cast',{wh_amqp_assignment,{new_channel,false}}}
155Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_outbound:45 (<0.30364.29>) received outbound audio resource request for +16135551111: {[{<<"Account-ID">>,<<"38bda41d624cf9cac14440c621d53fb8">>},{<<"Call-ID">>,<<"3222b297-2219-1234-6aaf-000c29af03db">>},{<<"Control-Queue">>,<<"ecallmgr@newitsp.local-<0.13918.16>-09186f50">>},{<<"Custom-Channel-Vars">>,{[{<<"Account-ID">>,<<"38bda41d624cf9cac14440c621d53fb8">>}]}},{<<"Outbound-Caller-ID-Number">>,<<"102">>},{<<"Outbound-Caller-ID-Name">>,<<"102">>},{<<"Media">>,<<"bypass">>},{<<"Application-Name">>,<<"bridge">>},{<<"To-DID">>,<<"+16135551111">>},{<<"Resource-Type">>,<<"audio">>},{<<"Server-ID">>,<<"whistle_apps@newitsp.local-<0.30354.29>-afb81b17">>},{<<"Node">>,<<"whistle_apps@newitsp.local">>},{<<"Msg-ID">>,<<"a5b9e8d6537692903d40173815919f80">>},{<<"App-Version">>,<<"0.9.0">>},{<<"App-Name">>,<<"trunkstore">>},{<<"Event-Name">>,<<"offnet_req">>},{<<"Event-Category">>,<<"resource">>}]}
156Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wnm_util:261 (<0.30364.29>) number '+16135551111' can be reconciled, proceeding
157Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wnm_number:185 (<0.30364.29>) unable to find number numbers%2F%2B1613/+16135551111
158Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_number_manager:174 (<0.30364.29>) no port for +16135551111: {error,not_found}
159Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_util:103 (<0.30364.29>) +16135551111 is not associated with any account, not_found
160Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_resources:172 (<0.30364.29>) attempting to find global resources
161Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_resources:330 (<0.30364.29>) no flags provided, filtering resources that require flags
162Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_resources:451 (<0.30364.29>) resource 3f1bb890510a945db5cf728a8606cd77 matches number '+16135551111' with regex match '6135551111'
163Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_resources:401 (<0.30364.29>) building resource 3f1bb890510a945db5cf728a8606cd77 endpoints
164Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_resources:552 (<0.30364.29>) using gateway from_uri_realm in From: undefined
165Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_resources:1053 (<0.30364.29>) created gateway route sip:+16135551111@192.168.1.243
166Dec 20 23:57:34 newitsp 2600hz[2563]: |stepswitch_bridge|gen_listener:385 (<0.30367.29>) starting new gen_listener proc
167Dec 20 23:57:34 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:468 (<0.95.0>) assigned existing channel <0.2198.0> on amqp://guest:guest@127.0.0.1:5672 to new consumer <0.30367.29>
168Dec 20 23:57:34 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:872 (<0.95.0>) registered handlers for channel <0.2198.0> to <0.30367.29>
169Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:1086 (<0.30367.29>) channel started, let's connect
170Dec 20 23:57:34 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.1216.0>) Supervisor stepswitch_request_sup started stepswitch_bridge:start_link([{[{<<"Route">>,<<"sip:+16135551111@192.168.1.243">>},{<<"Callee-ID-Name">>,<<"6135551111">>},...]}], {[{<<"Account-ID">>,<<"38bda41d624cf9cac14440c621d53fb8">>},{<<"Call-ID">>,<<"3222b297-2219-1234-...">>},...]}) at pid <0.30367.29>
171Dec 20 23:57:34 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.30369.29>) Supervisor {<0.30369.29>,amqp_channel_sup} started amqp_gen_consumer:start_link(amqp_selective_consumer, [], {<<"client 127.0.0.1:53018 -> 127.0.0.1:5672">>,262}) at pid <0.30370.29>
172Dec 20 23:57:34 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.30369.29>) Supervisor {<0.30369.29>,amqp_channel_sup} started amqp_channel:start_link(network, <0.106.0>, 262, <0.30370.29>, {<<"client 127.0.0.1:53018 -> 127.0.0.1:5672">>,262}) at pid <0.30371.29>
173Dec 20 23:57:34 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.30369.29>) Supervisor {<0.30369.29>,amqp_channel_sup} started rabbit_writer:start_link(#Port<0.8760>, 262, 131072, rabbit_framing_amqp_0_9_1, <0.30371.29>, {<<"client 127.0.0.1:53018 -> 127.0.0.1:5672">>,262}) at pid <0.30372.29>
174Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13918.16>) published to targeted(amqp://guest:guest@127.0.0.1:5672) exchange (routing key whistle_apps@newitsp.local-<0.30354.29>-afb81b17) via <0.1156.0>
175Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:544 (<0.13918.16>) sending control usurp for 701db608-a775-11e5-b7ce-ed92e00b2474
176Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13918.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.usurp_control.3222b297-2219-1234-6aaf-000c29af03db) via <0.1156.0>
177Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:288 (<0.30367.29>) declared queue whistle_apps@newitsp.local-<0.30367.29>-d2cf6283 via channel <0.2198.0>
178Dec 20 23:57:34 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:593 (<0.95.0>) added new channel <0.30371.29> on amqp://guest:guest@127.0.0.1:5672 to available pool
179Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"usurp_control">>,undefined}
180Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:314 (<0.30367.29>) created consumer amq.ctag-dC1DB2wmerjCejHVpH0jPw via channel <0.2198.0>
181Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:857 (<0.30367.29>) queue started: whistle_apps@newitsp.local-<0.30367.29>-d2cf6283
182Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:994 (<0.30367.29>) creating new binding: 'call'
183Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.30367.29>) bound whistle_apps@newitsp.local-<0.30367.29>-d2cf6283 to callevt exchange (routing key call.CHANNEL_DESTROY.3222b297-2219-1234-6aaf-000c29af03db) via channel <0.2198.0>
184Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.30367.29>) bound whistle_apps@newitsp.local-<0.30367.29>-d2cf6283 to callevt exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.3222b297-2219-1234-6aaf-000c29af03db) via channel <0.2198.0>
185Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.30367.29>) bound whistle_apps@newitsp.local-<0.30367.29>-d2cf6283 to callevt exchange (routing key call.CHANNEL_BRIDGE.3222b297-2219-1234-6aaf-000c29af03db) via channel <0.2198.0>
186Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:994 (<0.30367.29>) creating new binding: 'self'
187Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:307 (<0.30367.29>) bound whistle_apps@newitsp.local-<0.30367.29>-d2cf6283 to targeted exchange (routing key whistle_apps@newitsp.local-<0.30367.29>-d2cf6283) via channel <0.2198.0>
188Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_bridge:311 (<0.30367.29>) set outbound caller id to 102 '102'
189Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.30367.29>) published to callctl(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ecallmgr@newitsp.local-<0.13918.16>-09186f50) via <0.2198.0>
190Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_bridge:256 (<0.30367.29>) sent bridge command to ecallmgr@newitsp.local-<0.13918.16>-09186f50
191Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:994 (<0.13918.16>) inserting at the tail of the control queue call command bridge
192Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:1085 (<0.13918.16>) executing call command 'bridge' fc9822248915d7dce6b4e61b944259ac
193Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_bridge:46 (<0.13918.16>) creating bridge dialplan
194Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:551 (<0.13918.16>) building bridge endpoint: [<<"route">>,undefined,undefined,<<"6135551111">>,<<"sip:+16135551111@192.168.1.243">>,undefined,undefined]
195Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_fs_bridge:148 (<0.13918.16>) bridge will process media through host switch
196Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: set bypass_media=true
197Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: set ignore_display_updates=true
198Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: set continue_on_fail=true
199Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: export sip_redirect_context=context_2
200Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: set hangup_after_bridge=true
201Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: export ecallmgr_Inception=${ecallmgr_Inception}
202Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: bridge {ignore_display_updates='true',ecallmgr_Reseller-ID='957861b40f470ebed48224f09bce6340',ecallmgr_Account-ID='38bda41d624cf9cac14440c621d53fb8',effective_caller_id_number='102',effective_caller_id_name='102',origination_caller_id_number='102',origination_caller_id_name='102',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='Abc1234!',sip_auth_username='admin',ecallmgr_Global-Resource='true',ecallmgr_Resource-ID='3f1bb890510a945db5cf728a8606cd77',ecallmgr_E164-Destination='+16135551111',ecallmgr_Original-Number='6135551111',absolute_codec_string='^^:PCMU:PCMA',leg_progress_timeout='6',effective_callee_id_number='6135551111',effective_callee_id_name='6135551111']sofia/sipinterface_1/+16135551111@192.168.1.243
203Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: event Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge
204Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:86 (<0.13918.16>) building xferext on node freeswitch@newitsp.local: park
205Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE_COMPLETE/park
206Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:572 (<0.13916.16>) not publishing CHANNEL_EXECUTE_COMPLETE(park): undefined
207Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE/set
208Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:666 (<0.13916.16>) publishing call event channel_execute 'set(bypass_media=true)'
209Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
210Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE">>,<<"set">>}
211Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_DATA/undefined
212Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:572 (<0.13916.16>) not publishing CHANNEL_DATA(undefined): undefined
213Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE_COMPLETE/set
214Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:663 (<0.13916.16>) publishing call event channel_execute_complete 'set(bypass_media=true)' result: _none_
215Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
216Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE_COMPLETE">>,<<"set">>}
217Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE/set
218Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:666 (<0.13916.16>) publishing call event channel_execute 'set(ignore_display_updates=true)'
219Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
220Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE">>,<<"set">>}
221Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_DATA/undefined
222Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:572 (<0.13916.16>) not publishing CHANNEL_DATA(undefined): undefined
223Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE_COMPLETE/set
224Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:663 (<0.13916.16>) publishing call event channel_execute_complete 'set(ignore_display_updates=true)' result: _none_
225Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
226Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE_COMPLETE">>,<<"set">>}
227Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE/set
228Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:666 (<0.13916.16>) publishing call event channel_execute 'set(continue_on_fail=true)'
229Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
230Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE">>,<<"set">>}
231Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_DATA/undefined
232Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:572 (<0.13916.16>) not publishing CHANNEL_DATA(undefined): undefined
233Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE_COMPLETE/set
234Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:663 (<0.13916.16>) publishing call event channel_execute_complete 'set(continue_on_fail=true)' result: _none_
235Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
236Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE_COMPLETE">>,<<"set">>}
237Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE/export
238Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:666 (<0.13916.16>) publishing call event channel_execute 'set(sip_redirect_context=context_2)'
239Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
240Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE">>,<<"set">>}
241Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE_COMPLETE/export
242Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:663 (<0.13916.16>) publishing call event channel_execute_complete 'set(sip_redirect_context=context_2)' result: _none_
243Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
244Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE_COMPLETE">>,<<"set">>}
245Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE/set
246Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:666 (<0.13916.16>) publishing call event channel_execute 'set(hangup_after_bridge=true)'
247Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
248Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE">>,<<"set">>}
249Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_DATA/undefined
250Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:572 (<0.13916.16>) not publishing CHANNEL_DATA(undefined): undefined
251Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE_COMPLETE/set
252Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:663 (<0.13916.16>) publishing call event channel_execute_complete 'set(hangup_after_bridge=true)' result: _none_
253Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
254Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE_COMPLETE">>,<<"set">>}
255Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE/export
256Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:666 (<0.13916.16>) publishing call event channel_execute 'set(ecallmgr_Inception=)'
257Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
258Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE">>,<<"set">>}
259Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE_COMPLETE/export
260Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:663 (<0.13916.16>) publishing call event channel_execute_complete 'set(ecallmgr_Inception=)' result: _none_
261Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE_COMPLETE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
262Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE_COMPLETE">>,<<"set">>}
263Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE/bridge
264Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:666 (<0.13916.16>) publishing call event channel_execute 'bridge({ignore_display_updates='true',ecallmgr_Reseller-ID='957861b40f470ebed48224f09bce6340',ecallmgr_Account-ID='38bda41d624cf9cac14440c621d53fb8',effective_caller_id_number='102',effective_caller_id_name='102',origination_caller_id_number='102',origination_caller_id_name='102',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='Abc1234!',sip_auth_username='admin',ecallmgr_Global-Resource='true',ecallmgr_Resource-ID='3f1bb890510a945db5cf728a8606cd77',ecallmgr_E164-Destination='+16135551111',ecallmgr_Original-Number='6135551111',absolute_codec_string='^^:PCMU:PCMA',leg_progress_timeout='6',effective_callee_id_number='6135551111',effective_callee_id_name='6135551111']sofia/sipinterface_1/+16135551111@192.168.1.243)'
265Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
266Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE">>,<<"bridge">>}
267Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_call_events:659 (<0.13958.16>) publishing call event channel_create
268Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.13958.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_CREATE.706849de-a775-11e5-b7eb-ed92e00b2474) via <0.1115.0>
269Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_authz:147 (<0.13958.16>) channel authorization request started
270Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.350.0>) published to callmgr(amqp://guest:guest@127.0.0.1:5672) exchange (routing key authz.authorize) via <0.607.0>
271Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_worker:496 (<0.350.0>) published request with msg id 863632834ff8a255 for <0.13958.16>
272Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_request:123 (<0.30387.29>) using original number 6135551111 instead of +16135551111
273Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|wnm_util:232 (<0.30387.29>) number '+16135551111' is classified as did_us
274Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_allotments:152 (<0.30387.29>) checking if account 38bda41d624cf9cac14440c621d53fb8 has any allotments for outbound_did_us
275Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_allotments:152 (<0.30387.29>) checking if account 38bda41d624cf9cac14440c621d53fb8 has any allotments for did_us
276Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_allotments:28 (<0.30387.29>) account has no allotment
277Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:36 (<0.30387.29>) checking if account 38bda41d624cf9cac14440c621d53fb8 has available flat rate trunks
278Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:155 (<0.30387.29>) account has no inbound trunks
279Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:155 (<0.30387.29>) account has no outbound trunks
280Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:155 (<0.30387.29>) account has no twoway trunks
281Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:155 (<0.30387.29>) account has no burst trunks
282Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_per_minute:23 (<0.30387.29>) checking if account 38bda41d624cf9cac14440c621d53fb8 has available per-minute credit
283Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_presence:193 (<0.30391.29>) received channel create, checking for presence subscribers
284Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_presence:313 (<0.30391.29>) no presence subscriptions for +16135551111@192.168.1.243
285Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_dialog:179 (<0.30392.29>) received channel create, checking for dialog subscribers
286Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_dialog:345 (<0.30392.29>) no dialog subscriptions for +16135551111@192.168.1.243
287Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_per_minute:75 (<0.30387.29>) using prepay from account 38bda41d624cf9cac14440c621d53fb8 $0.5/$11.981
288Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_request:186 (<0.30387.29>) account 38bda41d624cf9cac14440c621d53fb8 authorized channel: per_minute
289Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_allotments:152 (<0.30387.29>) checking if account 957861b40f470ebed48224f09bce6340 has any allotments for outbound_did_us
290Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_allotments:152 (<0.30387.29>) checking if account 957861b40f470ebed48224f09bce6340 has any allotments for did_us
291Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_allotments:28 (<0.30387.29>) account has no allotment
292Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:36 (<0.30387.29>) checking if account 957861b40f470ebed48224f09bce6340 has available flat rate trunks
293Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:155 (<0.30387.29>) account has no inbound trunks
294Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:155 (<0.30387.29>) account has no outbound trunks
295Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_flat_rate:152 (<0.30387.29>) account has unlimited twoway trunks
296Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_request:177 (<0.30387.29>) reseller 957861b40f470ebed48224f09bce6340 authorized channel: flat_rate
297Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.30387.29>) published to targeted(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ecallmgr@newitsp.local-<0.350.0>-d5602304) via <0.2103.0>
298Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.30387.29>) published to callmgr(amqp://guest:guest@127.0.0.1:5672) exchange (routing key authz.authorize.broadcast) via <0.2103.0>
299Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_worker:663 (<0.350.0>) response for msg id 863632834ff8a255 took 8465 micro to return
300Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_command:32 (<0.13958.16>) freeswitch@newitsp.local api uuid_setvar_mulit 706849de-a775-11e5-b7eb-ed92e00b2474 ecallmgr_Reseller-Trunk-Usage=0/0/-1/0;ecallmgr_Account-Trunk-Usage=0/0/0/0
301Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:755 (<0.13918.16>) added leg 706849de-a775-11e5-b7eb-ed92e00b2474 to call
302Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:659 (<0.13961.16>) publishing call event leg_created
303Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"LEG_CREATED">>,undefined}
304Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13961.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.LEG_CREATED.3222b297-2219-1234-6aaf-000c29af03db) via <0.1156.0>
305Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wnm_util:261 (<0.30350.29>) number '+16135551111' can be reconciled, proceeding
306Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_authz:196 (<0.13958.16>) call authorized by account 38bda41d624cf9cac14440c621d53fb8 as per_minute
307Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_authz:210 (<0.13958.16>) call authorized by reseller 957861b40f470ebed48224f09bce6340 as flat_rate
308Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_authz:220 (<0.13958.16>) rating call in <0.13964.16>
309Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_authz:225 (<0.13958.16>) channel authorization succeeded, allowing call
310Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_command:32 (<0.13958.16>) freeswitch@newitsp.local api uuid_setvar_mulit 706849de-a775-11e5-b7eb-ed92e00b2474 ecallmgr_Channel-Authorized=true;ecallmgr_Reseller-Billing=flat_rate;ecallmgr_Reseller-ID=957861b40f470ebed48224f09bce6340;ecallmgr_Account-Billing=per_minute;ecallmgr_Account-ID=38bda41d624cf9cac14440c621d53fb8
311Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|hon_rater:21 (<0.30396.29>) valid rating request
312Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|hon_util:40 (<0.30396.29>) searching for prefixes for +16135551111: [16135551111,1613555111,161355511,16135551,1613555,161355,16135,1613,161,16,1]
313Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.354.0>) published to callmgr(amqp://guest:guest@127.0.0.1:5672) exchange (routing key rate.req) via <0.611.0>
314Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_worker:496 (<0.354.0>) published request with msg id bf91ba07b9ac7da8 for <0.13964.16>
315Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|hon_rater:75 (<0.30396.29>) candidate rates found, filtering
316Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|hon_rater:88 (<0.30396.29>) using rate US-1 for 102 to 6135551111
317Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|hon_rater:122 (<0.30396.29>) base cost for a minute call: 95
318Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|wnm_util:232 (<0.30396.29>) number '+16135551111' is classified as did_us
319Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|hon_rater:111 (<0.30396.29>) did_us number discount percentage: {[{<<"_id">>,<<"limits">>},{<<"_rev">>,<<"1-a923e2396ba9844f93f4c54262cc8f0b">>},{<<"allow_prepay">>,true},{<<"id">>,<<"limits">>},{<<"inbound_trunks">>,0},{<<"outbound_trunks">>,0},{<<"twoway_trunks">>,0},{<<"ui_metadata">>,{[{<<"version">>,<<"3.21-4">>},{<<"ui">>,<<"monster-ui">>},{<<"origin">>,<<"accounts">>}]}},{<<"pvt_type">>,<<"limits">>},{<<"pvt_vsn">>,<<"1">>},{<<"pvt_account_id">>,<<"38bda41d624cf9cac14440c621d53fb8">>},{<<"pvt_account_db">>,<<"account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8">>},{<<"pvt_created">>,63617710763},{<<"pvt_modified">>,63617710763},{<<"pvt_request_id">>,<<"2a52fdb6991c3ce5e005c56cfb22cc57">>},{<<"pvt_auth_user_id">>,<<"793590371fc562a6883a6baef305a027">>},{<<"pvt_auth_account_id">>,<<"957861b40f470ebed48224f09bce6340">>},{<<"pvt_is_authenticated">>,true}]}
320Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_worker:663 (<0.354.0>) response for msg id bf91ba07b9ac7da8 took 7499 micro to return
321Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_authz:309 (<0.13964.16>) setting rating information
322Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_command:32 (<0.13964.16>) freeswitch@newitsp.local api uuid_setvar_mulit 706849de-a775-11e5-b7eb-ed92e00b2474 ecallmgr_Rate=95;ecallmgr_Rate-Increment=60;ecallmgr_Rate-Minimum=60;ecallmgr_Surcharge=0;ecallmgr_Rate-Name=US-1;ecallmgr_Base-Cost=95;ecallmgr_Pvt-Cost=100
323Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.30396.29>) published to targeted(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ecallmgr@newitsp.local-<0.354.0>-88328bc3) via <0.2073.0>
324Dec 20 23:57:34 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.30396.29>) published to callmgr(amqp://guest:guest@127.0.0.1:5672) exchange (routing key rate.resp.broadcast) via <0.2073.0>
325Dec 20 23:57:34 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_fs_authz:29 (<0.13958.16>) channel is authorized
326Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_PROGRESS_MEDIA/undefined
327Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:659 (<0.13916.16>) publishing call event channel_progress_media
328Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_PROGRESS_MEDIA.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
329Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_PROGRESS_MEDIA">>,undefined}
330Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE/signal_bridge
331Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:666 (<0.13916.16>) publishing call event channel_execute 'bridge(706849de-a775-11e5-b7eb-ed92e00b2474)'
332Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_EXECUTE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
333Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_EXECUTE">>,<<"bridge">>}
334Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_EXECUTE_COMPLETE/bridge
335Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:850 (<0.13916.16>) suppressing bridge execute complete in favour the whistle masquerade of this event
336Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:572 (<0.13916.16>) not publishing CHANNEL_EXECUTE_COMPLETE(bridge): undefined
337Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:649 (<0.13918.16>) bridge execute complete, advancing control queue
338Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:679 (<0.13918.16>) no call commands remain queued, hibernating
339Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_BRIDGE/undefined
340Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:657 (<0.13916.16>) publishing channel_bridge to other leg 706849de-a775-11e5-b7eb-ed92e00b2474
341Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_BRIDGE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
342Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:285 (<0.13916.16>) tracking other leg events for 706849de-a775-11e5-b7eb-ed92e00b2474: []
343Dec 20 23:57:34 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:287 (<0.13916.16>) started event process: false
344Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:178 (<0.30354.29>) channel 3222b297-2219-1234-6aaf-000c29af03db bridged to 706849de-a775-11e5-b7eb-ed92e00b2474
345Dec 20 23:57:34 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_bridge:143 (<0.30367.29>) channel bridged to 706849de-a775-11e5-b7eb-ed92e00b2474, canceling timeout
346Dec 20 23:57:35 newitsp 2600hz[2563]: |hangups_monitoring|hangups_monitoring:176 (<0.30407.29>) no stats for hangup cause WRONG_CALL_STATE, skipping
347Dec 20 23:57:35 newitsp 2600hz[2563]: |hangups_monitoring|hangups_monitoring:176 (<0.30407.29>) no stats for hangup cause NO_ROUTE_DESTINATION, skipping
348Dec 20 23:57:35 newitsp 2600hz[2563]: |hangups_monitoring|hangups_monitoring:176 (<0.30407.29>) no stats for hangup cause CALL_REJECT, skipping
349Dec 20 23:57:35 newitsp 2600hz[2563]: |hangups_monitoring|hangups_monitoring:176 (<0.30407.29>) no stats for hangup cause MANDATORY_IE_MISSING, skipping
350Dec 20 23:57:35 newitsp 2600hz[2563]: |hangups_monitoring|hangups_monitoring:176 (<0.30407.29>) no stats for hangup cause PROGRESS_TIMEOUT, skipping
351Dec 20 23:57:35 newitsp 2600hz[2563]: |hangups_monitoring|hangups_monitoring:176 (<0.30407.29>) no stats for hangup cause RECOVERY_ON_TIMER_EXPIRE, skipping
352Dec 20 23:57:36 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:671 (<0.1091.0>) checking if we should compact services on bigcouch@newitsp.local
353Dec 20 23:57:36 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1647 (<0.1091.0>) getting db info attempt 1
354Dec 20 23:57:36 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1677 (<0.1091.0>) data size 4216 is under min_data_size threshold 131072
355Dec 20 23:57:36 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:678 (<0.1091.0>) db services not found on bigcouch@newitsp.local OR heuristic not met
356Dec 20 23:57:37 newitsp 2600hz[2616]: |wh_nodes|wh_amqp_channel:154 (<0.534.0>) published to nodes(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ) via <0.1050.0>
357Dec 20 23:57:37 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_call_events:659 (<0.13986.16>) publishing call event channel_answer
358Dec 20 23:57:37 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.13986.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_ANSWER.706849de-a775-11e5-b7eb-ed92e00b2474) via <0.1115.0>
359Dec 20 23:57:37 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_presence:200 (<0.30418.29>) received channel answer, checking for subscribers
360Dec 20 23:57:37 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_presence:313 (<0.30418.29>) no presence subscriptions for +16135551111@192.168.1.243
361Dec 20 23:57:37 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_dialog:186 (<0.30419.29>) received channel answer, checking for subscribers
362Dec 20 23:57:37 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_dialog:345 (<0.30419.29>) no dialog subscriptions for +16135551111@192.168.1.243
363Dec 20 23:57:37 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:659 (<0.13988.16>) publishing call event channel_answer
364Dec 20 23:57:37 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13988.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_ANSWER.3222b297-2219-1234-6aaf-000c29af03db) via <0.1115.0>
365Dec 20 23:57:37 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_ANSWER">>,undefined}
366Dec 20 23:57:37 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_presence:200 (<0.30425.29>) received channel answer, checking for subscribers
367Dec 20 23:57:37 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_presence:313 (<0.30425.29>) no presence subscriptions for admin@spoon8.local
368Dec 20 23:57:37 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_dialog:186 (<0.30426.29>) received channel answer, checking for subscribers
369Dec 20 23:57:37 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_dialog:345 (<0.30426.29>) no dialog subscriptions for admin@spoon8.local
370Dec 20 23:57:39 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:671 (<0.1091.0>) checking if we should compact numbers/+1226 on bigcouch@newitsp.local
371Dec 20 23:57:39 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1647 (<0.1091.0>) getting db info attempt 1
372Dec 20 23:57:39 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1677 (<0.1091.0>) data size 799 is under min_data_size threshold 131072
373Dec 20 23:57:39 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:678 (<0.1091.0>) db numbers/+1226 not found on bigcouch@newitsp.local OR heuristic not met
374Dec 20 23:57:39 newitsp 2600hz[2563]: |wh_nodes|wh_amqp_channel:154 (<0.1112.0>) published to nodes(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ) via <0.1060.0>
375Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:372 (<0.13916.16>) processing CHANNEL_UNBRIDGE/undefined
376Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:659 (<0.13916.16>) publishing call event channel_unbridge
377Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:181 (<0.30354.29>) unhandled combo: {<<"call_event">>,<<"CHANNEL_UNBRIDGE">>,undefined}
378Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13916.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_UNBRIDGE.3222b297-2219-1234-6aaf-000c29af03db) via <0.1150.0>
379Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:559 (<0.13918.16>) our channel has been destroyed, executing any post-hangup commands
380Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:1185 (<0.13918.16>) started post hangup keep alive timer for 120000ms
381Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:252 (<0.13916.16>) call event listener on node freeswitch@newitsp.local received graceful shutdown request
382Dec 20 23:57:41 newitsp 2600hz[2616]: |00000000000|ecallmgr_fs_channels:401 (<0.816.0>) removed 1 channel(s) with id 3222b297-2219-1234-6aaf-000c29af03db on freeswitch@newitsp.local
383Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:659 (<0.13999.16>) publishing call event channel_destroy
384Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:559 (<0.13918.16>) our channel has been destroyed, executing any post-hangup commands
385Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:1196 (<0.13918.16>) reset post hangup keep alive timer
386Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.13999.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_DESTROY.3222b297-2219-1234-6aaf-000c29af03db) via <0.1115.0>
387Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:151 (<0.30354.29>) channel hungup before bridge
388Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|ts_callflow:207 (<0.30354.29>) Sending hangup to ecallmgr@newitsp.local-<0.13918.16>-09186f50: [{<<"Application-Name">>,<<"hangup">>},{<<"Call-ID">>,<<"3222b297-2219-1234-6aaf-000c29af03db">>},{<<"Insert-At">>,<<"now">>},{<<"Server-ID">>,<<"whistle_apps@newitsp.local-<0.30354.29>-afb81b17">>},{<<"Event-Category">>,<<"call">>},{<<"Event-Name">>,<<"command">>},{<<"App-Name">>,<<"trunkstore">>},{<<"App-Version">>,<<"0.9.0">>},{<<"Node">>,<<"whistle_apps@newitsp.local">>}]
389Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_bridge:189 (<0.30446.29>) channel was destroyed while waiting for bridge
390Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_bridge:586 (<0.30446.29>) outbound request successfully completed
391Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_subscriptions:159 (<0.30450.29>) caching CHANNEL_DESTROY for 3222b297-2219-1234-6aaf-000c29af03db
392Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.30354.29>) published to callctl(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ecallmgr@newitsp.local-<0.13918.16>-09186f50) via <0.2182.0>
393Dec 20 23:57:41 newitsp 2600hz[2563]: |whistle_couch_cache|wh_cache:447 (<0.516.0>) removed 0 objects for {couch_util,<<"account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8-201512">>,<<"201512-3222b297-2219-1234-6aaf-000c29af03db">>}
394Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:725 (<0.95.0>) consumer <0.30354.29>, went down without closing channel: normal
395Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_history|wh_amqp_history:285 (<0.96.0>) removing AMQP history for consumer <0.30354.29> in 2.5s: normal
396Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.30367.29>) published to targeted(amqp://guest:guest@127.0.0.1:5672) exchange (routing key whistle_apps@newitsp.local-<0.30354.29>-afb81b17) via <0.2198.0>
397Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|stepswitch_bridge:226 (<0.30367.29>) listener terminating: normal
398Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:95 (<0.30367.29>) release consumer <0.30367.29> channel assignment
399Dec 20 23:57:41 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.2182.0>) Channel (<0.2182.0>): Unregistering return handler <0.30354.29> because it died. Reason: normal#012
400Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_channel:112 (<0.95.0>) ensuring queue whistle_apps@newitsp.local-<0.30354.29>-afb81b17 is removed
401Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_channel:107 (<0.95.0>) ensuring amq.ctag-0Trs2leZtc356PQwMwQIvw is removed
402Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_presence:207 (<0.30453.29>) received channel destroy, checking for presence subscribers
403Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_presence:313 (<0.30453.29>) no presence subscriptions for admin@spoon8.local
404Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_dialog:193 (<0.30454.29>) received channel destroy, checking for dialog subscribers
405Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|omnip_dialog:345 (<0.30454.29>) no dialog subscriptions for admin@spoon8.local
406Dec 20 23:57:41 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.2182.0>) Channel (<0.2182.0>): Unregistering confirm handler <0.30354.29> because it died. Reason: normal#012
407Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:935 (<0.13918.16>) recv and executing hangup now!
408Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:1085 (<0.13918.16>) executing call command 'hangup' 924412e55186b3e4a26ef424b1489291
409Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_util:108 (<0.13918.16>) terminate call on node freeswitch@newitsp.local
410Dec 20 23:57:41 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.2182.0>) Channel (<0.2182.0>): Unregistering flow handler <0.30354.29> because it died. Reason: normal#012
411Dec 20 23:57:41 newitsp 2600hz[2563]: |0000000000|Undefined:Undefined (<0.2182.0>) Channel (<0.2182.0>): dropping method {{'basic.deliver',<<"amq.ctag-0Trs2leZtc356PQwMwQIvw">>,23,false,<<"targeted">>,<<"whistle_apps@newitsp.local-<0.30354.29>-afb81b17">>},{content,60,none,<<128,0,16,97,112,112,108,105,99,97,116,105,111,110,47,106,115,111,110>>,rabbit_framing_amqp_0_9_1,[<<"{\"Resource-Response\":{\"Switch-URI\":\"sip:192.168.1.247:11000\",\"Switch-URL\":\"sip:mod_sofia@192.168.1.247:11000\",\"To-Tag\":\"ZUr1mFcN28e0j\",\"From-Tag\":\"QS6aU82HvD0pQ\",\"Custom-SIP-Headers\":{\"X-AUTH-IP\":\"192.168.1.241\"},\"Callee-ID-Name\":\"6135551111\",\"Callee-ID-Number\":\"6135551111\",\"Caller-ID-Name\":\"102\",\"Caller-ID-Number\":\"102\",\"Media-Server\":\"newitsp.local\",\"Presence-ID\":\"admin@spoon8.local\",\"Request\":\"+16135551111@spoon8.local\",\"From\":\"admin@spoon8.local\",\"To\":\"+16135551111@192.168.1.247\",\"From-Uri\":\"admin@spoon8.local\",\"To-Uri\":\"+16135551111@192.168.1.247\",\"Ringing-Seconds\":\"1\",\"Billing-Seconds\":\"4\",\"Duration-Seconds\":\"8\",\"Remote-SDP\":\"v=0\\r\\no=FreeSWITCH 1450623947 1450623948 IN IP4 192.168.1.241\\r\\ns=FreeSWITCH\\r\\nc=IN IP4 192.168.1.241\\r\\nt=0 0\\r\\nm=audio 32300 RTP/AVP 0 8 101\\r\\na=rtpmap:0 PCMU/8000\\r\\na=rtpmap:8 PCMA/8000\\r\\na=rtpmap:101 telephone-event/8000\\r\\na=fmtp:101 0-16\\r\\na=ptime:20\\r\\n\",\"User-Agent\":\"FreeSWITCH-mod_sofia/1.7.0+git~20151215T221651Z~7733ff58d9~64bit\",\"Hangup-Code\":\"sip:200\",\"Hangup-Cause\":\"NORMAL_CLEARING\",\"Disposition\":\"SUCCESS\",\"Other-Leg-Call-ID\":\"706849de-a775-11e5-b7eb-ed92e00b2474\",\"Other-Leg-Destination-Number\":\"+16135551111\",\"Other-Leg-Caller-ID-Number\":\"102\",\"Other-Leg-Caller-ID-Name\":\"102\",\"Other-Leg-Direction\":\"outbound\",\"Call-Direction\":\"inbound\",\"Timestamp\":63617875061,\"Custom-Channel-Vars\":{\"Account-ID\":\"38bda41d624cf9cac14440c621d53fb8\",\"Channel-Authorized\":\"true\",\"Username\":\"admin\",\"Realm\":\"spoon8.local\",\"Authorizing-ID\":\"34fffb06739bdcadfe
412Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_channel:104 (<0.95.0>) closed amqp channel <0.2182.0>
413Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:294 (<0.95.0>) removed assignment for consumer <0.30354.29>
414Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:879 (<0.95.0>) unregistered handlers for channel <0.2198.0>
415Dec 20 23:57:41 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:719 (<0.30367.29>) stepswitch_bridge terminated cleanly, going down
416Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_history|wh_amqp_history:285 (<0.96.0>) removing AMQP history for consumer <0.30367.29> in 2.5s: normal
417Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_channel:104 (<0.95.0>) closed amqp channel <0.2198.0>
418Dec 20 23:57:41 newitsp 2600hz[2563]: |wh_amqp_assignments|wh_amqp_assignments:294 (<0.95.0>) removed assignment for consumer <0.30367.29>
419Dec 20 23:57:41 newitsp 2600hz[2616]: |00000000000|ecallmgr_fs_channels:401 (<0.816.0>) removed 1 channel(s) with id 706849de-a775-11e5-b7eb-ed92e00b2474 on freeswitch@newitsp.local
420Dec 20 23:57:41 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|ecallmgr_call_events:659 (<0.14003.16>) publishing call event channel_destroy
421Dec 20 23:57:41 newitsp 2600hz[2616]: |706849de-a775-11e5-b7eb-ed92e00b2474|wh_amqp_channel:154 (<0.14003.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.CHANNEL_DESTROY.706849de-a775-11e5-b7eb-ed92e00b2474) via <0.1115.0>
422Dec 20 23:57:41 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_subscriptions:159 (<0.30463.29>) caching CHANNEL_DESTROY for 706849de-a775-11e5-b7eb-ed92e00b2474
423Dec 20 23:57:41 newitsp 2600hz[2563]: |whistle_couch_cache|wh_cache:447 (<0.516.0>) removed 0 objects for {couch_util,<<"account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8-201512">>,<<"201512-706849de-a775-11e5-b7eb-ed92e00b2474">>}
424Dec 20 23:57:41 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_presence:207 (<0.30466.29>) received channel destroy, checking for presence subscribers
425Dec 20 23:57:41 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_presence:313 (<0.30466.29>) no presence subscriptions for +16135551111@192.168.1.243
426Dec 20 23:57:41 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_dialog:193 (<0.30467.29>) received channel destroy, checking for dialog subscribers
427Dec 20 23:57:41 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|omnip_dialog:345 (<0.30467.29>) no dialog subscriptions for +16135551111@192.168.1.243
428Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:1196 (<0.13918.16>) reset post hangup keep alive timer
429Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_control:838 (<0.13918.16>) removed leg 706849de-a775-11e5-b7eb-ed92e00b2474 from call
430Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:659 (<0.14005.16>) publishing call event leg_destroyed
431Dec 20 23:57:41 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:154 (<0.14005.16>) published to callevt(amqp://guest:guest@127.0.0.1:5672) exchange (routing key call.LEG_DESTROYED.3222b297-2219-1234-6aaf-000c29af03db) via <0.1156.0>
432Dec 20 23:57:42 newitsp 2600hz[2563]: |3222b297-2219-1234-6aaf-000c29af03db|wnm_util:232 (<0.30449.29>) number '+16135551111' is classified as did_us
433Dec 20 23:57:42 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_request:123 (<0.30462.29>) using original number 6135551111 instead of +16135551111
434Dec 20 23:57:42 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|wnm_util:232 (<0.30462.29>) number '+16135551111' is classified as did_us
435Dec 20 23:57:42 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|wht_util:302 (<0.30462.29>) rate $0.0095/60s, minimum 60s, surcharge $0.0, for 4s, no charge time 0s, sub total $0.0095, discount $0.0, total $0.0095
436Dec 20 23:57:42 newitsp 2600hz[2563]: |706849de-a775-11e5-b7eb-ed92e00b2474|j5_per_minute:124 (<0.30462.29>) creating debit transaction in ledger 38bda41d624cf9cac14440c621d53fb8 for $0.0095
437Dec 20 23:57:42 newitsp 2600hz[2563]: |whistle_couch_cache|wh_cache:447 (<0.516.0>) removed 0 objects for {couch_util,<<"account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8-201512">>,<<"706849de-a775-11e5-b7eb-ed92e00b2474-END">>}
438Dec 20 23:57:42 newitsp 2600hz[2563]: |00000000000|wh_amqp_channel:154 (<0.186.0>) published to configuration(amqp://guest:guest@127.0.0.1:5672) exchange (routing key doc_created.account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8-201512.debit.706849de-a775-11e5-b7eb-ed92e00b2474-END) via <0.151.0>
439Dec 20 23:57:42 newitsp 2600hz[2563]: |00000000000|wh_amqp_worker:575 (<0.186.0>) published message undefined for <0.30470.29>
440Dec 20 23:57:42 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:671 (<0.1091.0>) checking if we should compact account/38/bd/a41d624cf9cac14440c621d53fb8 on bigcouch@newitsp.local
441Dec 20 23:57:42 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1647 (<0.1091.0>) getting db info attempt 1
442Dec 20 23:57:42 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1677 (<0.1091.0>) data size 43361 is under min_data_size threshold 131072
443Dec 20 23:57:42 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:678 (<0.1091.0>) db account/38/bd/a41d624cf9cac14440c621d53fb8 not found on bigcouch@newitsp.local OR heuristic not met
444Dec 20 23:57:42 newitsp 2600hz[2616]: |wh_nodes|wh_amqp_channel:154 (<0.534.0>) published to nodes(amqp://guest:guest@127.0.0.1:5672) exchange (routing key ) via <0.1050.0>
445Dec 20 23:57:43 newitsp 2600hz[2616]: |undefined|wh_amqp_channel:154 (<0.532.0>) published to targeted(amqp://guest:guest@127.0.0.1:5672) exchange (routing key statistics) via <0.1110.0>
446Dec 20 23:57:45 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:671 (<0.1091.0>) checking if we should compact account/4d/d6/691d77f86098c7fe81c521a1aa97 on bigcouch@newitsp.local
447Dec 20 23:57:45 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1647 (<0.1091.0>) getting db info attempt 1
448Dec 20 23:57:45 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:1677 (<0.1091.0>) data size 73547 is under min_data_size threshold 131072
449Dec 20 23:57:45 newitsp 2600hz[2563]: |couch_compactor_fsm|couch_compactor_fsm:678 (<0.1091.0>) db account/4d/d6/691d77f86098c7fe81c521a1aa97 not found on bigcouch@newitsp.local OR heuristic not met
450Dec 20 23:57:46 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|ecallmgr_call_events:534 (<0.13916.16>) goodbye and thanks for all the fish: normal
451Dec 20 23:57:46 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|wh_amqp_channel:95 (<0.13916.16>) release consumer <0.13916.16> channel assignment
452Dec 20 23:57:46 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_assignments:879 (<0.98.0>) unregistered handlers for channel <0.1150.0>
453Dec 20 23:57:46 newitsp 2600hz[2616]: |3222b297-2219-1234-6aaf-000c29af03db|gen_listener:719 (<0.13916.16>) ecallmgr_call_events terminated cleanly, going down
454Dec 20 23:57:46 newitsp 2600hz[2616]: |wh_amqp_history|wh_amqp_history:285 (<0.99.0>) removing AMQP history for consumer <0.13916.16> in 2.5s: normal
455Dec 20 23:57:46 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_channel:104 (<0.98.0>) closed amqp channel <0.1150.0>
456Dec 20 23:57:46 newitsp 2600hz[2616]: |wh_amqp_assignments|wh_amqp_assignments:294 (<0.98.0>) removed assignment for consumer <0.13916.16>
457Dec 20 23:57:47 newitsp 2600hz[2563]: |undefined|crossbar:38 (<0.30490.29>) routing to version 2
458Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_resource:105 (<0.30490.29>) OPTIONS: /v2/accounts/38bda41d624cf9cac14440c621d53fb8/descendants?_=1450655284261 from 192.168.1.12
459Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_resource:160 (<0.30490.29>) run: known_methods
460Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_resource:180 (<0.30490.29>) run: allowed_methods
461Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_util:140 (<0.30490.29>) query string: <<"{\"_\":\"1450655284261\"}">>
462Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_util:156 (<0.30490.29>) undefined content type when getting req data, assuming application/json
463Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|kazoo_bindings:700 (<0.30490.29>) matched [<<"accounts">>,<<"allowed_methods">>,<<"*">>] to [<<"accounts">>,<<"allowed_methods">>,<<"v2_resource">>]
464Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_resource:224 (<0.30490.29>) adding cors headers
465Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_resource:236 (<0.30490.29>) allowing OPTIONS request for CORS preflight
466Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_util:747 (<0.30490.29>) options requests are permitted by default
467Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_resource:342 (<0.30490.29>) is CORS request
468Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|api_resource:144 (<0.30490.29>) OPTIONS request fulfilled in 1 ms
469Dec 20 23:57:47 newitsp 2600hz[2563]: |undefined|crossbar:38 (<0.30492.29>) routing to version 2
470Dec 20 23:57:47 newitsp 2600hz[2563]: |undefined|crossbar:38 (<0.30493.29>) routing to version 2
471Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|kazoo_bindings:700 (<0.30491.29>) matched [<<"*">>,<<"*">>,<<"finish_request">>,<<"*">>] to [<<"accounts">>,<<"OPTIONS">>,<<"finish_request">>,<<"v2_resource">>]
472Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_resource:105 (<0.30492.29>) OPTIONS: /v2/accounts/38bda41d624cf9cac14440c621d53fb8/transactions/current_balance?_=1450655284262 from 192.168.1.12
473Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_resource:160 (<0.30492.29>) run: known_methods
474Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_resource:180 (<0.30492.29>) run: allowed_methods
475Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_util:140 (<0.30492.29>) query string: <<"{\"_\":\"1450655284262\"}">>
476Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_util:156 (<0.30492.29>) undefined content type when getting req data, assuming application/json
477Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_resource:105 (<0.30493.29>) OPTIONS: /v2/accounts/38bda41d624cf9cac14440c621d53fb8/transactions?created_from=63615301200&created_to=63617893199&reason=only_calls&_=1450655284263 from 192.168.1.12
478Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_resource:160 (<0.30493.29>) run: known_methods
479Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_resource:180 (<0.30493.29>) run: allowed_methods
480Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_util:140 (<0.30493.29>) query string: <<"{\"created_from\":\"63615301200\",\"created_to\":\"63617893199\",\"reason\":\"only_calls\",\"_\":\"1450655284263\"}">>
481Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|kazoo_bindings:700 (<0.30492.29>) matched [<<"transactions">>,<<"allowed_methods">>,<<"*">>] to [<<"transactions">>,<<"allowed_methods">>,<<"v2_resource">>]
482Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_resource:224 (<0.30492.29>) adding cors headers
483Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_resource:236 (<0.30492.29>) allowing OPTIONS request for CORS preflight
484Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_util:747 (<0.30492.29>) options requests are permitted by default
485Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_resource:342 (<0.30492.29>) is CORS request
486Dec 20 23:57:47 newitsp 2600hz[2563]: |28da5f8d4b5c563acf9575b7e2a19a05|crossbar:38 (<0.30490.29>) routing to version 2
487Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_util:156 (<0.30493.29>) undefined content type when getting req data, assuming application/json
488Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|api_resource:144 (<0.30492.29>) OPTIONS request fulfilled in 4 ms
489Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:105 (<0.30490.29>) GET: /v2/accounts/38bda41d624cf9cac14440c621d53fb8/descendants?_=1450655284261 from 192.168.1.12
490Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:160 (<0.30490.29>) run: known_methods
491Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:180 (<0.30490.29>) run: allowed_methods
492Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_util:140 (<0.30490.29>) query string: <<"{\"_\":\"1450655284261\"}">>
493Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_util:176 (<0.30490.29>) application/json content type when getting req data
494Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|kazoo_bindings:700 (<0.30493.29>) matched [<<"transactions">>,<<"allowed_methods">>,<<"*">>] to [<<"transactions">>,<<"allowed_methods">>,<<"v2_resource">>]
495Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_resource:224 (<0.30493.29>) adding cors headers
496Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_resource:236 (<0.30493.29>) allowing OPTIONS request for CORS preflight
497Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_util:747 (<0.30493.29>) options requests are permitted by default
498Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_resource:342 (<0.30493.29>) is CORS request
499Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:700 (<0.30490.29>) matched [<<"accounts">>,<<"allowed_methods">>,<<"*">>] to [<<"accounts">>,<<"allowed_methods">>,<<"v2_resource">>]
500Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:224 (<0.30490.29>) adding cors headers
501Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_util:726 (<0.30490.29>) using auth token from header
502Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|api_resource:144 (<0.30493.29>) OPTIONS request fulfilled in 4 ms
503Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|kazoo_bindings:700 (<0.30494.29>) matched [<<"*">>,<<"*">>,<<"finish_request">>,<<"*">>] to [<<"transactions">>,<<"OPTIONS">>,<<"finish_request">>,<<"v2_resource">>]
504Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:700 (<0.30490.29>) matched [<<"authenticate">>,<<"*">>] to [<<"authenticate">>,<<"v2_resource">>]
505Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_ip_auth:88 (<0.30490.29>) attemping to authenticate ip 192.168.1.12
506Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:415 (<0.30490.29>) getting start_key from request: undefined
507Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|kazoo_bindings:700 (<0.30495.29>) matched [<<"*">>,<<"*">>,<<"finish_request">>,<<"*">>] to [<<"transactions">>,<<"OPTIONS">>,<<"finish_request">>,<<"v2_resource">>]
508Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_context:223 (<0.30490.29>) checking if request has query-string filter
509Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:341 (<0.30490.29>) limit: 51 page_size: 50 dir: ascending
510Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:363 (<0.30490.29>) couch_mgr:get_results(<<"accounts">>, <<"accounts/listing_by_ip">>, [{limit,51},{key,<<"192.168.1.12">>}])
511Dec 20 23:57:47 newitsp 2600hz[2563]: |88b075a4706c72b02fbc59eca44dec94|crossbar:38 (<0.30493.29>) routing to version 2
512Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:105 (<0.30493.29>) GET: /v2/accounts/38bda41d624cf9cac14440c621d53fb8/transactions/current_balance?_=1450655284262 from 192.168.1.12
513Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:160 (<0.30493.29>) run: known_methods
514Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:180 (<0.30493.29>) run: allowed_methods
515Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_util:140 (<0.30493.29>) query string: <<"{\"_\":\"1450655284262\"}">>
516Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_util:176 (<0.30493.29>) application/json content type when getting req data
517Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:700 (<0.30493.29>) matched [<<"transactions">>,<<"allowed_methods">>,<<"*">>] to [<<"transactions">>,<<"allowed_methods">>,<<"v2_resource">>]
518Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:224 (<0.30493.29>) adding cors headers
519Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_accounts:1154 (<0.30493.29>) account 38bda41d624cf9cac14440c621d53fb8 db exists, setting operating database as account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8
520Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_util:726 (<0.30493.29>) using auth token from header
521Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:700 (<0.30493.29>) matched [<<"authenticate">>,<<"*">>] to [<<"authenticate">>,<<"v2_resource">>]
522Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_ip_auth:88 (<0.30493.29>) attemping to authenticate ip 192.168.1.12
523Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|crossbar_doc:415 (<0.30493.29>) getting start_key from request: undefined
524Dec 20 23:57:47 newitsp 2600hz[2563]: |677aba1b44f1534ee8178eb2a618f17a|crossbar:38 (<0.30492.29>) routing to version 2
525Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:105 (<0.30492.29>) GET: /v2/accounts/38bda41d624cf9cac14440c621d53fb8/transactions?created_from=63615301200&created_to=63617893199&reason=only_calls&_=1450655284263 from 192.168.1.12
526Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:160 (<0.30492.29>) run: known_methods
527Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:180 (<0.30492.29>) run: allowed_methods
528Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_util:140 (<0.30492.29>) query string: <<"{\"created_from\":\"63615301200\",\"created_to\":\"63617893199\",\"reason\":\"only_calls\",\"_\":\"1450655284263\"}">>
529Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_util:176 (<0.30492.29>) application/json content type when getting req data
530Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:700 (<0.30492.29>) matched [<<"transactions">>,<<"allowed_methods">>,<<"*">>] to [<<"transactions">>,<<"allowed_methods">>,<<"v2_resource">>]
531Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:224 (<0.30492.29>) adding cors headers
532Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_accounts:1154 (<0.30492.29>) account 38bda41d624cf9cac14440c621d53fb8 db exists, setting operating database as account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8
533Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_util:726 (<0.30492.29>) using auth token from header
534Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:700 (<0.30492.29>) matched [<<"authenticate">>,<<"*">>] to [<<"authenticate">>,<<"v2_resource">>]
535Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_ip_auth:88 (<0.30492.29>) attemping to authenticate ip 192.168.1.12
536Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|crossbar_doc:415 (<0.30492.29>) getting start_key from request: undefined
537Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:373 (<0.30490.29>) paginating view 'accounts/listing_by_ip' from 'accounts', starting at 'undefined'
538Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_context:223 (<0.30490.29>) checking if request has query-string filter
539Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:319 (<0.30490.29>) databases exhausted
540Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_modules_util:502 (<0.30490.29>) flat token cost of 1 configured
541Dec 20 23:57:47 newitsp 2600hz[2563]: |00000000000|kz_token_bucket:193 (<0.25571.29>) consumed 1, 99 left
542Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_token_auth:197 (<0.30490.29>) checking auth token: '035824fa6a8840974177f9c30bde85e1'
543Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:688 (<0.30490.29>) exact match for v2_resource.authenticate
544Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:700 (<0.30490.29>) matched [<<"authorize">>,<<"*">>] to [<<"authorize">>,<<"v2_resource">>]
545Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_modules_util:377 (<0.30490.29>) checking for superduper admin: 957861b40f470ebed48224f09bce6340 (account%2F95%2F78%2F61b40f470ebed48224f09bce6340)
546Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_modules_util:382 (<0.30490.29>) the requestor is a superduper admin
547Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_simple_authz:142 (<0.30490.29>) authorizing, the request does not contain any system administration modules
548Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_simple_authz:62 (<0.30490.29>) authorizing the request
549Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_token_restrictions:129 (<0.30490.29>) no restrictions
550Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:688 (<0.30490.29>) exact match for v2_resource.authorize
551Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:357 (<0.30490.29>) run: content_types_provided
552Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:470 (<0.30490.29>) run: languages_provided
553Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:481 (<0.30490.29>) adding first accept-lang header language: en-gb
554Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:508 (<0.30490.29>) run: resource_exists
555Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:700 (<0.30490.29>) matched [<<"accounts">>,<<"resource_exists">>,<<"*">>] to [<<"accounts">>,<<"resource_exists">>,<<"v2_resource">>]
556Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:520 (<0.30490.29>) requested resource exists, validating it
557Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:723 (<0.30490.29>) routing v2_resource.validate_resource.accounts matches *.validate_resource.accounts
558Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_accounts:1154 (<0.30490.29>) account 38bda41d624cf9cac14440c621d53fb8 db exists, setting operating database as account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8
559Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:723 (<0.30490.29>) routing v2_resource.validate.accounts matches *.validate.accounts
560Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_accounts:820 (<0.30490.29>) account 38bda41d624cf9cac14440c621d53fb8 startkey
561Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:418 (<0.30490.29>) getting start_key from options: [<<"38bda41d624cf9cac14440c621d53fb8">>,<<>>]
562Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_context:223 (<0.30493.29>) checking if request has query-string filter
563Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|crossbar_doc:341 (<0.30493.29>) limit: 51 page_size: 50 dir: ascending
564Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|crossbar_doc:363 (<0.30493.29>) couch_mgr:get_results(<<"accounts">>, <<"accounts/listing_by_ip">>, [{limit,51},{key,<<"192.168.1.12">>}])
565Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_context:223 (<0.30492.29>) checking if request has query-string filter
566Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|crossbar_doc:395 (<0.30492.29>) pagination disabled in context
567Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|crossbar_doc:341 (<0.30492.29>) limit: undefined page_size: 50 dir: ascending
568Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|crossbar_doc:363 (<0.30492.29>) couch_mgr:get_results(<<"accounts">>, <<"accounts/listing_by_ip">>, [{include_docs,true},{key,<<"192.168.1.12">>}])
569Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|crossbar_doc:373 (<0.30492.29>) paginating view 'accounts/listing_by_ip' from 'accounts', starting at 'undefined'
570Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_context:223 (<0.30492.29>) checking if request has query-string filter
571Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|crossbar_doc:774 (<0.30492.29>) pagination disabled, removing resp envelope keys
572Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|crossbar_doc:319 (<0.30492.29>) databases exhausted
573Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_modules_util:502 (<0.30492.29>) flat token cost of 1 configured
574Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_context:223 (<0.30490.29>) checking if request has query-string filter
575Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:341 (<0.30490.29>) limit: 51 page_size: 50 dir: ascending
576Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:363 (<0.30490.29>) couch_mgr:get_results(<<"accounts">>, <<"accounts/listing_by_descendants">>, [{startkey,[<<"38bda41d624cf9cac14440c621d53fb8">>,<<>>]},{limit,51},{endkey,[<<"38bda41d624cf9cac14440c621d53fb8">>,{[]}]}])
577Dec 20 23:57:47 newitsp 2600hz[2563]: |00000000000|kz_token_bucket:193 (<0.25713.29>) consumed 1, 99 left
578Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|crossbar_doc:373 (<0.30493.29>) paginating view 'accounts/listing_by_ip' from 'accounts', starting at 'undefined'
579Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_context:223 (<0.30493.29>) checking if request has query-string filter
580Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|crossbar_doc:319 (<0.30493.29>) databases exhausted
581Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_modules_util:502 (<0.30493.29>) flat token cost of 1 configured
582Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_token_auth:197 (<0.30492.29>) checking auth token: '035824fa6a8840974177f9c30bde85e1'
583Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:688 (<0.30492.29>) exact match for v2_resource.authenticate
584Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:700 (<0.30492.29>) matched [<<"authorize">>,<<"*">>] to [<<"authorize">>,<<"v2_resource">>]
585Dec 20 23:57:47 newitsp 2600hz[2563]: |00000000000|kz_token_bucket:193 (<0.25713.29>) consumed 1, 98 left
586Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_token_auth:197 (<0.30493.29>) checking auth token: '035824fa6a8840974177f9c30bde85e1'
587Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:688 (<0.30493.29>) exact match for v2_resource.authenticate
588Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:700 (<0.30493.29>) matched [<<"authorize">>,<<"*">>] to [<<"authorize">>,<<"v2_resource">>]
589Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_modules_util:377 (<0.30492.29>) checking for superduper admin: 957861b40f470ebed48224f09bce6340 (account%2F95%2F78%2F61b40f470ebed48224f09bce6340)
590Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_modules_util:382 (<0.30492.29>) the requestor is a superduper admin
591Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_simple_authz:142 (<0.30492.29>) authorizing, the request does not contain any system administration modules
592Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_simple_authz:62 (<0.30492.29>) authorizing the request
593Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_token_restrictions:129 (<0.30492.29>) no restrictions
594Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:688 (<0.30492.29>) exact match for v2_resource.authorize
595Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:357 (<0.30492.29>) run: content_types_provided
596Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:470 (<0.30492.29>) run: languages_provided
597Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:481 (<0.30492.29>) adding first accept-lang header language: en-gb
598Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:508 (<0.30492.29>) run: resource_exists
599Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_modules_util:377 (<0.30493.29>) checking for superduper admin: 957861b40f470ebed48224f09bce6340 (account%2F95%2F78%2F61b40f470ebed48224f09bce6340)
600Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_modules_util:382 (<0.30493.29>) the requestor is a superduper admin
601Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_simple_authz:142 (<0.30493.29>) authorizing, the request does not contain any system administration modules
602Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_simple_authz:62 (<0.30493.29>) authorizing the request
603Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_token_restrictions:129 (<0.30493.29>) no restrictions
604Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:688 (<0.30493.29>) exact match for v2_resource.authorize
605Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:357 (<0.30493.29>) run: content_types_provided
606Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:700 (<0.30492.29>) matched [<<"transactions">>,<<"resource_exists">>,<<"*">>] to [<<"transactions">>,<<"resource_exists">>,<<"v2_resource">>]
607Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:520 (<0.30492.29>) requested resource exists, validating it
608Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:723 (<0.30492.29>) routing v2_resource.validate_resource.accounts matches *.validate_resource.accounts
609Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:470 (<0.30493.29>) run: languages_provided
610Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:481 (<0.30493.29>) adding first accept-lang header language: en-gb
611Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:508 (<0.30493.29>) run: resource_exists
612Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_accounts:1154 (<0.30492.29>) account 38bda41d624cf9cac14440c621d53fb8 db exists, setting operating database as account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8
613Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:700 (<0.30493.29>) matched [<<"transactions">>,<<"resource_exists">>,<<"*">>] to [<<"transactions">>,<<"resource_exists">>,<<"v2_resource">>]
614Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:520 (<0.30493.29>) requested resource exists, validating it
615Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:723 (<0.30493.29>) routing v2_resource.validate_resource.accounts matches *.validate_resource.accounts
616Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:723 (<0.30492.29>) routing v2_resource.validate.transactions matches *.validate.transactions
617Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_modules_util:120 (<0.30492.29>) building created_to from req value
618Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|cb_modules_util:129 (<0.30492.29>) building created_from from req value
619Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_accounts:1154 (<0.30493.29>) account 38bda41d624cf9cac14440c621d53fb8 db exists, setting operating database as account%2F38%2Fbd%2Fa41d624cf9cac14440c621d53fb8
620Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:723 (<0.30493.29>) routing v2_resource.validate.transactions matches *.validate.transactions
621Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:373 (<0.30490.29>) paginating view 'accounts/listing_by_descendants' from 'accounts', starting at '[<<"38bda41d624cf9cac14440c621d53fb8">>,<<>>]'
622Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_context:223 (<0.30490.29>) checking if request has query-string filter
623Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|crossbar_doc:319 (<0.30490.29>) databases exhausted
624Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:723 (<0.30490.29>) routing v2_resource.billing matches v2_resource.billing
625Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_util:944 (<0.30490.29>) billing returned success
626Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:526 (<0.30490.29>) requested resource update validated
627Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:666 (<0.30490.29>) run: to_json
628Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_util:1081 (<0.30490.29>) pull response content: {"page_size":0,"start_key":"","data":[],"revision":"undefined","request_id":"c3d17429bd7ab1c2e7cca3f3ccb10abe","status":"success","auth_token":"035824fa6a8840974177f9c30bde85e1"}
629Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|api_resource:149 (<0.30490.29>) GET request fulfilled in 33 ms
630Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|kazoo_bindings:700 (<0.30511.29>) matched [<<"*">>,<<"*">>,<<"finish_request">>,<<"*">>] to [<<"accounts">>,<<"GET">>,<<"finish_request">>,<<"v2_resource">>]
631Dec 20 23:57:47 newitsp 2600hz[2563]: |c3d17429bd7ab1c2e7cca3f3ccb10abe|cb_token_auth:128 (<0.30511.29>) auth doc is too new (1717s to go), not saving
632Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:723 (<0.30493.29>) routing v2_resource.billing matches v2_resource.billing
633Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_util:944 (<0.30493.29>) billing returned success
634Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:526 (<0.30493.29>) requested resource update validated
635Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:666 (<0.30493.29>) run: to_json
636Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_util:1081 (<0.30493.29>) pull response content: {"data":{"balance":11.971500000000000696},"revision":"undefined","request_id":"c19d30fc30c4f1708ae2944316472966","status":"success","auth_token":"035824fa6a8840974177f9c30bde85e1"}
637Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|api_resource:149 (<0.30493.29>) GET request fulfilled in 41 ms
638Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|kazoo_bindings:700 (<0.30515.29>) matched [<<"*">>,<<"*">>,<<"finish_request">>,<<"*">>] to [<<"transactions">>,<<"GET">>,<<"finish_request">>,<<"v2_resource">>]
639Dec 20 23:57:47 newitsp 2600hz[2563]: |c19d30fc30c4f1708ae2944316472966|cb_token_auth:128 (<0.30515.29>) auth doc is too new (1717s to go), not saving
640Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:723 (<0.30492.29>) routing v2_resource.billing matches v2_resource.billing
641Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_util:944 (<0.30492.29>) billing returned success
642Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:526 (<0.30492.29>) requested resource update validated
643Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:666 (<0.30492.29>) run: to_json
644Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_util:1081 (<0.30492.29>) pull response content: {"data":[{"description":"per minute call","call_id":"39eb8d7c-a774-11e5-b7a1-ed92e00b2474","event":"end","metadata":{"direction":"outbound","duration":4,"account_id":"38bda41d624cf9cac14440c621d53fb8","to":"+16135551111@192.168.1.243","from":"zhangfei@spoon8.local"},"id":"39eb8d7c-a774-11e5-b7a1-ed92e00b2474-END","amount":0.0094999999999999997641,"reason":"per_minute_call","type":"debit","created":63617874543,"version":2,"code":1001},{"description":"per minute call","call_id":"12239996-a775-11e5-b7c6-ed92e00b2474","event":"end","metadata":{"direction":"outbound","duration":13,"account_id":"38bda41d624cf9cac14440c621d53fb8","to":"+16135551111@192.168.1.243","from":"admin@spoon8.local"},"id":"12239996-a775-11e5-b7c6-ed92e00b2474-END","amount":0.0094999999999999997641,"reason":"per_minute_call","type":"debit","created":63617874913,"version":2,"code":1001},{"description":"per minute call","call_id":"706849de-a775-11e5-b7eb-ed92e00b2474","event":"end","metadata":{"direction":"outbound","duration":4,"account_id":"38bda41d624cf9cac14440c621d53fb8","to":"+16135551111@192.168.1.243","from":"admin@spoon8.local"},"id":"706849de-a775-11e5-b7eb-ed92e00b2474-END","amount":0.0094999999999999997641,"reason":"per_minute_call","type":"debit","created":63617875062,"version":2,"code":1001}],"revision":"undefined","request_id":"7ce38eb7d4fae2d4d4383e1ab613c516","status":"success","auth_token":"035824fa6a8840974177f9c30bde85e1"}
645Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|api_resource:149 (<0.30492.29>) GET request fulfilled in 43 ms
646Dec 20 23:57:47 newitsp 2600hz[2563]: |7ce38eb7d4fae2d4d4383e1ab613c516|kazoo_bindings:700 (<0.30516.29>) matched [<<"*">>,<<"*">>,<<"finish_request">>,<<"*">>] to [<<"transactions">>,<<"GET">>,<<"finish_request">>,<<"v2_resource">>]