[sr-dev] [kamailio/kamailio] http_async_client frees pointer twice(Kamailio 5.0.4) (#1340)

Federico Cabiddu notifications at github.com
Fri Dec 22 10:57:25 CET 2017


Hi,
thanks all for looking at it. I'm relocating in these days, I'll have a
look once I'm established and I have internet back.

Federico

On 22 Dec 2017 10:42, "Victor Seva" <notifications at github.com> wrote:

> Some debug output just before the crash
>
> 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 at 127.0.0.1' from='sip:1018-customer0000 at 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 at 1.2.3.4.5;transport=udp' fu='sip:Unknown at 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 at 127.0.0.1' from='sip:1016-customer0000 at 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 at 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 at sip.serverfake.com:5080' to='sip:1016-customer0000 at kamailio-leader.query.consul' ci='2f7392ee04d01c7f1ca209d4268a9e87 at 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 at 1.2.3.4.5;transport=udp' fu='sip:Unknown at sip.serverfake.com:5080' ci='2f7392ee04d01c7f1ca209d4268a9e87 at sip.serverfake.com'
> Dec 21 17:17:02 kamailio-1889-0 kamailio[39849]: INFO: <script>: RELAY_BRANCH rm='NOTIFY' - ci='2f7392ee04d01c7f1ca209d4268a9e87 at 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 at sip.serverfake.com' from='sip:Unknown at 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 at 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 at 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
>
>> You are receiving this because you were mentioned.
> Reply to this email directly, view it on GitHub
> <https://github.com/kamailio/kamailio/issues/1340#issuecomment-353561342>,
> or mute the thread
> <https://github.com/notifications/unsubscribe-auth/ADip439hOVEANuMJB8SLoyPrXeu2F87xks5tC3mNgaJpZM4Qs2oq>
> .
> ...


-- 
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/kamailio/kamailio/issues/1340#issuecomment-353564132
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.kamailio.org/pipermail/sr-dev/attachments/20171222/038b94dc/attachment-0001.html>


More information about the sr-dev mailing list