Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: INFO: <script>: SET_QUEUE_STATUS: user='1018-customer0000' - ci='89374MDk1OWQxYjk0MDFmYzg3MmU3MDA5NjcyMDI3NTRmNDI'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: INFO: <script>: set dispatch to a='asterisk-green-profile1-foerdc.query.consul' b='asterisk-green-profile1-foeedc.query.consul' c='asterisk-blue-profile1-foerdc.query.consul' d='asterisk-blue-profile1-foeedc.query.consul' ci='89374MDk1OWQxYjk0MDFmYzg3MmU3MDA5NjcyMDI3NTRmNDI'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: INFO: <script>: Device State URL is 'http://asterisk-green-profile1-foerdc.query.consul:8088/' - ci='89374MDk1OWQxYjk0MDFmYzg3MmU3MDA5NjcyMDI3NTRmNDI'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: DEBUG: http_async_client [http_async_client_mod.c:476]: set_query_cparam(): param set to 'AriFoehnUser'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: DEBUG: http_async_client [http_async_client_mod.c:476]: set_query_cparam(): param set to 'passwordfake0'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: DEBUG: http_async_client [async_http.c:411]: async_send_query(): no pre-existing transaction, switching to transaction-less behavior
Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: DEBUG: http_async_client [async_http.c:578]: async_push_query(): query sent [http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1018-customer0000] (0x7f4b57bc6488) to worker 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: INFO: <script>: SET_QUEUE_STATUS: Device='1018-customer0000' to be enabled req_id=39875-1076 ci='89374MDk1OWQxYjk0MDFmYzg3MmU3MDA5NjcyMDI3NTRmNDI'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:348]: notification_socket_cb(): query received: [http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1018-customer0000] (0x7f4b57bc6488)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:423]: new_request(): received query http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1018-customer0000 with timeout 100, tls_verify_peer 0, tls_verify_host 0 (param=0x7f4b57bc6488)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:119]: build_http_m_cell(): hash id for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:130]: link_http_m_cell(): linking new cell 0x7f4b57acaff8 to table 0x7f4b57463150 [922]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:523]: new_request(): set username to AriFoehnUser [authmethod 1]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:530]: new_request(): Adding easy 0x7f4b57e47c20 to multi 0x7f4b574361e0 (http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1018-customer0000)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39875]: INFO: <script>: set dispatch to a='asterisk-green-profile1-foerdc.query.consul' b='asterisk-green-profile1-foeedc.query.consul' c='asterisk-blue-profile1-foerdc.query.consul' d='asterisk-blue-profile1-foeedc.query.consul' ci='89374MDk1OWQxYjk0MDFmYzg3MmU3MDA5NjcyMDI3NTRmNDI'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=23 e=0x7f4b57e47c20 what=IN
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:179]: sock_cb(): Adding data: IN
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:615]: setsock(): added event 0xca8d80 to socket 23
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
---
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:96]: event_cb(): activity 2 on socket 23: action 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:129]: event_cb(): performing action 1 on socket 23
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: HTTP/1.1 200 OK#015#012 [17]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Server: Asterisk/13.17.0#015#012 [26]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012 [37]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Cache-Control: no-cache, no-store#015#012 [35]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Content-type: application/json#015#012 [32]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Content-Length: 57#015#012 [20]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: #015#012 [2]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: {"state":"UNAVAILABLE","name":"Custom:1018-customer0000"} [57]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=23 e=0x7f4b57e47c20 what=REMOVE
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:166]: sock_cb(): freeing event 0xca8d80
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:131]: event_cb(): action 1 on socket 23 performed
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 0
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:568]: check_multi_info(): DONE: http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1018-customer0000 => (0) 
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:135]: async_http_cb(): query result = HTTP/1.1 200 OK#015#012Server: Asterisk/13.17.0#015#012Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012Cache-Control: no-cache, no-store#015#012Content-type: application/json#015#012Content-Length: 57#015#012#015#012{"state":"UNAVAILABLE","name":"Custom:1018-customer0000"} [226]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:187]: async_http_cb(): successfully parsed http reply 0x7f4b684e20c0
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: INFO: <script>: QUEUE_CHECK_STATUS_OR_SET QueueDevice='1018-customer0000'  is in state='UNAVAILABLE' req_id=39875-1076 ci='123'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: INFO: <script>: SET_QUEUE_STATUS: user='1016-customer0000' - ci='85992MDU2ODE1ZjZkOGI5OTM3ZDAxODBhZWE1MGQwNjkwNzc'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:411]: async_send_query(): no pre-existing transaction, switching to transaction-less behavior
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:578]: async_push_query(): query sent [http://asterisk-green-profile1-foerdc.query.consul:8088/ari/asterisk/variable] (0x7f4b57a3fde0) to worker 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.h:133]: free_async_query(): freeing query 0x7f4b57bc6488
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:579]: check_multi_info(): reply: [200] HTTP/1.1 200 OK#015#012Server: Asterisk/13.17.0#015#012Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012Cache-Control: no-cache, no-store#015#012Content-type: ap
plication/json#015#012Content-Length: 57#015#012#015#012{"state":"UNAVAILABLE","name":"Custom:1018-customer0000"} [226]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:584]: check_multi_info(): cleaning up cell 0x7f4b57acaff8
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:589]: check_multi_info(): Removing handle 0x7f4b57e47c20
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to -1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:147]: event_cb(): last transfer done, kill timeout
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:348]: notification_socket_cb(): query received: [http://asterisk-green-profile1-foerdc.query.consul:8088/ari/asterisk/variable] (0x7f4b57a3fde0)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:423]: new_request(): received query http://asterisk-green-profile1-foerdc.query.consul:8088/ari/asterisk/variable with timeout 10000, tls_verify_peer 0, tls_verify_host 0 (param=0x7f4b57a3fde0)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: INFO: <script>: set dispatch to a='asterisk-green-profile1-foerdc.query.consul' b='asterisk-green-profile1-foeedc.query.consul' c='asterisk-blue-profile1-foerdc.query.consul' d='asterisk-blue-profile1-foeedc.query.consul' ci='85992MDU2ODE1ZjZkOGI5OTM3ZDAxODBhZWE1MGQwNjkwNzc'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: INFO: <script>: Device State URL is 'http://asterisk-green-profile1-foerdc.query.consul:8088/' - ci='85992MDU2ODE1ZjZkOGI5OTM3ZDAxODBhZWE1MGQwNjkwNzc'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: DEBUG: http_async_client [http_async_client_mod.c:476]: set_query_cparam(): param set to 'AriFoehnUser'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: DEBUG: http_async_client [http_async_client_mod.c:476]: set_query_cparam(): param set to 'passwordfake0'
---
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: DEBUG: http_async_client [async_http.c:411]: async_send_query(): no pre-existing transaction, switching to transaction-less behavior
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:119]: build_http_m_cell(): hash id for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:130]: link_http_m_cell(): linking new cell 0x7f4b57acaff8 to table 0x7f4b57463150 [922]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:530]: new_request(): Adding easy 0x7f4b57e47c20 to multi 0x7f4b574361e0 (http://asterisk-green-profile1-foerdc.query.consul:8088/ari/asterisk/variable)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: DEBUG: http_async_client [async_http.c:578]: async_push_query(): query sent [http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1016-customer0000] (0x7f4b57bc6488) to worker 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: INFO: <script>: SET_QUEUE_STATUS: Device='1016-customer0000' to be enabled req_id=39867-1065 ci='85992MDU2ODE1ZjZkOGI5OTM3ZDAxODBhZWE1MGQwNjkwNzc'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:348]: notification_socket_cb(): query received: [http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1016-customer0000] (0x7f4b57bc6488)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:423]: new_request(): received query http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1016-customer0000 with timeout 100, tls_verify_peer 0, tls_verify_host 0 (param=0x7f4b57bc6488)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:119]: build_http_m_cell(): hash id for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:130]: link_http_m_cell(): linking new cell 0x7f4b57c32a08 to table 0x7f4b57463150 [51]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:523]: new_request(): set username to AriFoehnUser [authmethod 1]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:530]: new_request(): Adding easy 0x7f4b57e3ef20 to multi 0x7f4b574361e0 (http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1016-customer0000)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39867]: INFO: <script>: set dispatch to a='asterisk-green-profile1-foerdc.query.consul' b='asterisk-green-profile1-foeedc.query.consul' c='asterisk-blue-profile1-foerdc.query.consul' d='asterisk-blue-profile1-foeedc.query.consul' ci='85992MDU2ODE1ZjZkOGI5OTM3ZDAxODBhZWE1MGQwNjkwNzc'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=23 e=0x7f4b57e3ef20 what=IN
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:179]: sock_cb(): Adding data: IN
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:615]: setsock(): added event 0xca8d80 to socket 23
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 2
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:96]: event_cb(): activity 2 on socket 23: action 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:129]: event_cb(): performing action 1 on socket 23
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: HTTP/1.1 200 OK#015#012 [17]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e3ef20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Server: Asterisk/13.17.0#015#012 [26]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e3ef20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012 [37]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e3ef20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Cache-Control: no-cache, no-store#015#012 [35]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e3ef20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Content-type: application/json#015#012 [32]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e3ef20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Content-Length: 55#015#012 [20]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e3ef20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: #015#012 [2]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e3ef20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: {"state":"NOT_INUSE","name":"Custom:1016-customer0000"} [55]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
---
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e3ef20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=23 e=0x7f4b57e3ef20 what=REMOVE
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:166]: sock_cb(): freeing event 0xca8d80
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:131]: event_cb(): action 1 on socket 23 performed
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:568]: check_multi_info(): DONE: http://asterisk-green-profile1-foerdc.query.consul:8088/ari/deviceStates/Custom:1016-customer0000 => (0) 
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e3ef20 (8)-> 0x7f4b57e3ef20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e3ef20 is 51
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e3ef20 found on table entry 51#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:135]: async_http_cb(): query result = HTTP/1.1 200 OK#015#012Server: Asterisk/13.17.0#015#012Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012Cache-Control: no-cache, no-store#015#012Content-type: app
lication/json#015#012Content-Length: 55#015#012#015#012{"state":"NOT_INUSE","name":"Custom:1016-customer0000"} [224]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:187]: async_http_cb(): successfully parsed http reply 0x7f4b684e20c0
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: INFO: <script>: QUEUE_CHECK_STATUS_OR_SET QueueDevice='1016-customer0000'  is in state='NOT_INUSE' req_id=39867-1065 ci='123'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.h:133]: free_async_query(): freeing query 0x7f4b57bc6488
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:579]: check_multi_info(): reply: [200] HTTP/1.1 200 OK#015#012Server: Asterisk/13.17.0#015#012Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012Cache-Control: no-cache, no-store#015#012Content-type: ap
plication/json#015#012Content-Length: 55#015#012#015#012{"state":"NOT_INUSE","name":"Custom:1016-customer0000"} [224]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:584]: check_multi_info(): cleaning up cell 0x7f4b57c32a08
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:589]: check_multi_info(): Removing handle 0x7f4b57e3ef20
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39847]: INFO: <script>: default onreply_route ci='4d37030636fbea05-39875@127.0.0.1' from='sip:1018-customer0000@sip.serverfake.com' to='<null>' method='REGISTER' status='200 src='172.18.241.29:5080'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39857]: INFO: <script>: from NAT address rm='NOTIFY' ci='89374MTk0MmI1OGYyYWVmNTUwYmEwZmM3OGFmZGNlNjdjOTk'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39857]: INFO: <script>: RELAY: method='NOTIFY' ip='172.18.241.29:5080' du='<null>' ru='sip:6B9500CD-5A3BAC99000C4A96-DCE61700@1.2.3.4.5;transport=udp' fu='sip:Unknown@sip.serverfake.com:5080' ci='89374MTk0MmI1OGYyYWVmNTUwYmEwZmM3OGFmZGNlNjdjOTk'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39857]: INFO: <script>: RELAY_BRANCH rm='NOTIFY' - ci='89374MTk0MmI1OGYyYWVmNTUwYmEwZmM3OGFmZGNlNjdjOTk'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 3 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=25 e=0x7f4b57e47c20 what=OUT
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:179]: sock_cb(): Adding data: OUT
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39851]: INFO: <script>: default onreply_route ci='4d37030636fbe9fb-39867@127.0.0.1' from='sip:1016-customer0000@sip.serverfake.com' to='<null>' method='REGISTER' status='200 src='172.18.241.29:5080'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39849]: INFO: <script>: from NAT address rm='NOTIFY' ci='2f7392ee04d01c7f1ca209d4268a9e87@sip.serverfake.com'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:615]: setsock(): added event 0xca8d80 to socket 25
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 199 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:96]: event_cb(): activity 4 on socket 25: action 2
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:129]: event_cb(): performing action 2 on socket 25
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=25 e=0x7f4b57e47c20 what=IN
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:184]: sock_cb(): Changing action from OUT to IN
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:615]: setsock(): added event 0xca8d80 to socket 25
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:131]: event_cb(): action 2 on socket 25 performed
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:96]: event_cb(): activity 2 on socket 25: action 1
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:129]: event_cb(): performing action 1 on socket 25
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: HTTP/1.1 401 Unauthorized#015#012 [27]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Server: Asterisk/13.17.0#015#012 [26]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
---
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
cec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012 [37]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Cache-Control: no-cache, no-store#015#012 [35]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39849]: INFO: <script>: tenant=<null> call_type='outbound_b' from='sip:Unknown@sip.serverfake.com:5080' to='sip:1016-customer0000@kamailio-leader.query.consul' ci='2f7392ee04d01c7f1ca209d4268a9e87@sip.serverfake.com'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: WWW-Authenticate: Basic realm="Asterisk REST Interface"#015#012 [57]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Content-type: application/json#015#012 [32]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Content-Length: 37#015#012 [20]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: #015#012 [2]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39849]: INFO: <script>: RELAY: method='NOTIFY' ip='172.18.241.29:5080' du='<null>' ru='sip:4a64058e38255f44@1.2.3.4.5;transport=udp' fu='sip:Unknown@sip.serverfake.com:5080' ci='2f7392ee04d01c7f1ca209d4268a9e87@sip.serverfake.com'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39849]: INFO: <script>: RELAY_BRANCH rm='NOTIFY' - ci='2f7392ee04d01c7f1ca209d4268a9e87@sip.serverfake.com'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: {"message":"Authentication required"} [37]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7f4b57e47c20)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=25 e=0x7f4b57e47c20 what=REMOVE
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:166]: sock_cb(): freeing event 0xca8d80
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:131]: event_cb(): action 1 on socket 25 performed
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:561]: check_multi_info(): REMAINING: 0
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:568]: check_multi_info(): DONE: http://asterisk-green-profile1-foerdc.query.consul:8088/ari/asterisk/variable => (0) 
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7f4b57e47c20 (8)-> 0x7f4b57e47c20 (14)
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7f4b57e47c20 is 922
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7f4b57e47c20 found on table entry 922#012
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:135]: async_http_cb(): query result = HTTP/1.1 401 Unauthorized#015#012Server: Asterisk/13.17.0#015#012Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012Cache-Control: no-cache, no-store#015#012WWW-Aut
henticate: Basic realm="Asterisk REST Interface"#015#012Content-type: application/json#015#012Content-Length: 37#015#012#015#012{"message":"Authentication required"} [273]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.c:187]: async_http_cb(): successfully parsed http reply 0x7f4b684e20c0
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: INFO: <script>: HTTP_SET reply correctly req_id='39894-157'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [async_http.h:133]: free_async_query(): freeing query 0x7f4b57a3fde0
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:579]: check_multi_info(): reply: [401] HTTP/1.1 401 Unauthorized#015#012Server: Asterisk/13.17.0#015#012Date: Thu, 21 Dec 2017 17:17:02 GMT#015#012Cache-Control: no-cache, no-store#015#012WWW-Authenticate: Basic realm="Asterisk REST Interface"#015#012Content-type: application/json#015#012Content-Length: 37#015#012#015#012{"message":"Authentication required"} [273]
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:584]: check_multi_info(): cleaning up cell 0x7f4b57acaff8
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:589]: check_multi_info(): Removing handle 0x7f4b57e47c20
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to -1 ms
Dec 21 17:17:02 kamailio-1889-0 kamailio[39894]: DEBUG: http_async_client [http_multi.c:147]: event_cb(): last transfer done, kill timeout
Dec 21 17:17:02 kamailio-1889-0 kamailio[39862]: INFO: <script>: default onreply_route ci='2f7392ee04d01c7f1ca209d4268a9e87@sip.serverfake.com' from='sip:Unknown@sip.serverfake.com:5080' to='<null>' method='NOTIFY' status='200 src='1.2.3.4.5:5060'
Dec 21 17:17:02 kamailio-1889-0 kamailio[39874]: INFO: <script>: default onreply_route ci='89374MTk0MmI1OGYyYWVmNTUwYmEwZmM3OGFmZGNlNjdjOTk' from='sip:Unknown@sip.serverfake.com:5080' to='<null>' method='NOTIFY' status='200 src='1.2.3.4.5:5060'
Dec 21 17:17:05 kamailio-1889-0 kamailio[39866]: ERROR: <script>: auth_challenge error '-4' fu='sip:200-eloy@sip.serverfake.com' ci='84929NTkyYzNmMWFmZDc3NDQ1MGZhN2NhOTM5N2JkOWI1YmQ'
Dec 21 17:17:05 kamailio-1889-0 kamailio[39877]: INFO: <script>: SET_QUEUE_STATUS: user='200-eloy' - ci='84929NTkyYzNmMWFmZDc3NDQ1MGZhN2NhOTM5N2JkOWI1YmQ'
Dec 21 17:17:05 kamailio-1889-0 kamailio[39877]: INFO: <script>: set dispatch to a='asterisk-green-profile1-foeedc.query.consul' b='asterisk-green-profile1-foerdc.query.consul' c='asterisk-blue-profile1-foeedc.query.consul' d='asterisk-blue-profile1-foerdc.query.consul' ci='84929NTkyYzNmMWFmZDc3NDQ1MGZhN2NhOTM5N2JkOWI1YmQ'
Dec 21 17:17:05 kamailio-1889-0 kamailio[39877]: INFO: <script>: Device State URL is 'http://asterisk-green-profile1-foeedc.query.consul:8088/' - ci='84929NTkyYzNmMWFmZDc3NDQ1MGZhN2NhOTM5N2JkOWI1YmQ'
Dec 21 17:17:05 kamailio-1889-0 kamailio[39877]: DEBUG: http_async_client [http_async_client_mod.c:476]: set_query_cparam(): param set to 'AriFoehnUser'
Dec 21 17:17:05 kamailio-1889-0 kamailio[39877]: DEBUG: http_async_client [http_async_client_mod.c:476]: set_query_cparam(): param set to 'passwordfake0'
Dec 21 17:17:05 kamailio-1889-0 kamailio[39877]: DEBUG: http_async_client [async_http.c:411]: async_send_query(): no pre-existing transaction, switching to transaction-less behavior
Dec 21 17:17:05 kamailio-1889-0 kamailio[39877]: : <core> [core/mem/q_malloc.c:483]: qm_free(): BUG: qm_free: freeing already freed pointer (0x7f4b573fb7c8), called from http_async_client: async_http.c: set_query_params(633), first free http_async_client: http_multi.c: curl_shm_free(334) - aborting
Dec 21 17:17:11 kamailio-1889-0 kamailio[39911]: CRITICAL: <core> [core/pass_fd.c:277]: receive_fd(): EOF on 70
Dec 21 17:17:11 kamailio-1889-0 kamailio[39829]: ALERT: <core> [main.c:743]: handle_sigs(): child process 39877 exited by a signal 6