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

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/Cu…] (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/Cu…] (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/Cu… 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/Cu…) 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/Cu… => (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/variab…] (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/variab…] (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/variab… 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/variab…) 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/Cu…] (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/Cu…] (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/Cu… 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/Cu…) 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/Cu… => (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=&#39;4d37030636fbea05-39875(a)127.0.0.1&#39; 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=&#39;4d37030636fbe9fb-39867(a)127.0.0.1&#39; 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=&#39;2f7392ee04d01c7f1ca209d4268a9e87(a)sip.serverfake.com&#39; 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=&#39;2f7392ee04d01c7f1ca209d4268a9e87(a)sip.serverfake.com&#39; 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=&#39;2f7392ee04d01c7f1ca209d4268a9e87(a)sip.serverfake.com&#39; Dec 21 17:17:02 kamailio-1889-0 kamailio[39849]: INFO: <script>: RELAY_BRANCH rm='NOTIFY' - ci=&#39;2f7392ee04d01c7f1ca209d4268a9e87(a)sip.serverfake.com&#39; 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/variab… => (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=&#39;2f7392ee04d01c7f1ca209d4268a9e87(a)sip.serverfake.com&#39; 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 — 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> . ...