Apr 20 17:36:14 devsip00 /sbin/kamailio[6244]: INFO: http_async_client [http_async_client_mod.c:250]: mod_init(): Initializing Http Async module
Apr 20 17:36:14 devsip00 /sbin/kamailio[6244]: INFO: http_async_client [async_http.c:93]: async_http_init_sockets(): inter-process event notification sockets initialized
Apr 20 17:36:14 devsip00 /sbin/kamailio[6244]: INFO: http_async_client [async_http.c:93]: async_http_init_sockets(): inter-process event notification sockets initialized
Apr 20 17:36:14 devsip00 /sbin/kamailio[6244]: INFO: http_async_client [async_http.c:76]: async_http_init_worker(): started worker process: 1
Apr 20 17:36:14 devsip00 /sbin/kamailio[6244]: INFO: http_async_client [async_http.c:76]: async_http_init_worker(): started worker process: 2
Apr 20 17:37:14 devsip00 /sbin/kamailio[6283]: {1 3094 INVITE d9695149ce484811ab98ca41a4e8c694} INFO: <script>: TranslationDetail: Recieved on 10.99.52.17:5061
Apr 20 17:37:14 devsip00 /sbin/kamailio[6283]: {1 3094 INVITE d9695149ce484811ab98ca41a4e8c694} INFO: <script>: Translation: TextBroker - Making CallArrived for d9695149ce484811ab98ca41a4e8c694
Apr 20 17:37:14 devsip00 /sbin/kamailio[6283]: {1 3094 INVITE d9695149ce484811ab98ca41a4e8c694} DEBUG: http_async_client [async_http.c:509]: header_list_add(): stored new http header: [Content-Type: application/json]
Apr 20 17:37:14 devsip00 /sbin/kamailio[6283]: {1 3094 INVITE d9695149ce484811ab98ca41a4e8c694} DEBUG: http_async_client [async_http.c:319]: async_send_query(): no pre-existing transaction, switching to transaction-less behavior
Apr 20 17:37:14 devsip00 /sbin/kamailio[6283]: {1 3094 INVITE d9695149ce484811ab98ca41a4e8c694} DEBUG: http_async_client [async_http.c:436]: async_push_query(): query sent [http://tucitd067.cyracom.com:50634/api/events/CallArrived] (0x7fabcedbfa30) to worker 1
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [async_http.c:270]: notification_socket_cb(): query received: [http://tucitd067.cyracom.com:50634/api/events/CallArrived] (0x7fabcedbfa30)
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:419]: new_request(): received query http://tucitd067.cyracom.com:50634/api/events/CallArrived with timeout 500, tls_verify_peer 1, tls_verify_host 1 (param=0x7fabcedbfa30)
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedc31a0 (8)-> 0x7fabcedc31a0 (14)
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedc31a0 is 1894
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:119]: build_http_m_cell(): hash id for 0x7fabcedc31a0 is 1894
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:130]: link_http_m_cell(): linking new cell 0x7fabcedc0810 to table 0x7fabced5ee60 [1894]
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:521]: new_request(): Adding easy 0x7fabcedc31a0 to multi 0x7fabced1bc00 (http://tucitd067.cyracom.com:50634/api/events/CallArrived)
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Apr 20 17:37:14 devsip00 /sbin/kamailio[6283]: {1 3094 INVITE d9695149ce484811ab98ca41a4e8c694} INFO: <script>: TranslationDetail: Received request for valid translation domain
Apr 20 17:37:14 devsip00 /sbin/kamailio[6283]: {1 3094 INVITE d9695149ce484811ab98ca41a4e8c694} INFO: <script>: Translation: Found agent sip:agent2@devtranslation.sms-test.cyracom.com to be free
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] About to connect() to tucitd067.cyracom.com port 50634 (#0)
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL]   Trying 10.11.200.35...
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=9 e=0x7fabcedc31a0 what=OUT
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:179]: sock_cb(): Adding data: OUT
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedc31a0 (8)-> 0x7fabcedc31a0 (14)
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedc31a0 is 1894
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedc31a0 found on table entry 1894
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:606]: setsock(): added event 0x1fbf100 to socket 9
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to -1 ms
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:552]: check_multi_info(): REMAINING: 1
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:552]: check_multi_info(): REMAINING: 1
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedc31a0 (8)-> 0x7fabcedc31a0 (14)
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedc31a0 is 1894
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedc31a0 found on table entry 1894
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:96]: event_cb(): activity 4 on socket 9: action 2
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:129]: event_cb(): performing action 2 on socket 9
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] Connected to tucitd067.cyracom.com (10.11.200.35) port 50634 (#0)
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr out] POST /api/events/CallArrived HTTP/1.1#015#012Host: tucitd067.cyracom.com:50634#015#012Accept: */*#015#012Content-Type: application/json#015#012Content-Length: 263
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] upload completely sent off: 263 out of 263 bytes
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=9 e=0x7fabcedc31a0 what=IN
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:184]: sock_cb(): Changing action from OUT to IN
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:606]: setsock(): added event 0x1fbf100 to socket 9
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:131]: event_cb(): action 2 on socket 9 performed
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:552]: check_multi_info(): REMAINING: 1
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] HTTP/1.1 200 OK
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] Transfer-Encoding: chunked
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] Content-Type: text/plain; charset=utf-8
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] Server: Kestrel
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] X-Powered-By: ASP.NET
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] Date: Thu, 20 Apr 2017 17:37:14 GMT
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in]
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] Connection #0 to host tucitd067.cyracom.com left intact
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: <script>: route[HTTP_REPLY]: status:200
Apr 20 17:37:14 devsip00 /sbin/kamailio[6280]: INFO: <script>: route[HTTP_REPLY]: body   CallArrived - At Time:Thu Apr 20 17:37:14 2017 CallId:d9695149ce484811ab98ca41a4e8c694 RequestUri:sip:spanish@devtranslation.sms-test.cyracom.com To:sip:spanish@devtranslation.sms-test.cyracom.com From:sip:cust1@devtranslation.sms-test.cyracom.com
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Auth path full headers are - To-Path: msrps://devtranslation.sms-test.cyracom.com;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: ipfromhdrs is: 10.99.54.28 and ipfrompacket is: 10.99.54.28
Apr 20 17:37:17 devsip00 /sbin/kamailio[6286]: {2 3094 INVITE d9695149ce484811ab98ca41a4e8c694} INFO: <script>: Translation: TextBroker - Making CallAnswered for d9695149ce484811ab98ca41a4e8c694
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012From-Path: msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012Message-ID: 46353c45febf89a#015#012Byte-Range: 1-0/0
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.99.54.28:55480
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Message-ID: 9dbe1affb95620e3#015#012Byte-Range: 1-320/320#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012From-Path: msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012Message-ID: e259ecdad2bde0f5#015#012Byte-Range: 1-679/679#015#012Success-Report: yes#015#012Failure-Report: yes#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.99.54.28:55480
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012From-Path: msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012Message-ID: b35da8b1af5f1583#015#012Byte-Range: 1-313/313#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.99.54.28:55480
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Status: 000 200 OK#015#012Message-ID: e259ecdad2bde0f5#015#012Byte-Range: 1-679/679
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Message-ID: f1bfc511121b5c7a#015#012Byte-Range: 1-551/551#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012From-Path: msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012Message-ID: f1256bc42f1196c6#015#012Byte-Range: 1-544/544#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.99.54.28:55480
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Message-ID: 740f2d619e0980a3#015#012Byte-Range: 1-551/551#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012From-Path: msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012Message-ID: a9b87a21bfac4e44#015#012Byte-Range: 1-488/488#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.99.54.28:55480
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Message-ID: 8d60e0407fcd394a#015#012Byte-Range: 1-915/915#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012From-Path: msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012Message-ID: 951a17d294331ab9#015#012Byte-Range: 1-488/488#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.99.54.28:55480
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Message-ID: 50f1f87ff9f5974f#015#012Byte-Range: 1-915/915#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012From-Path: msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012Message-ID: 1ee0f9775e8bdfe5#015#012Byte-Range: 1-880/880#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:17 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.99.54.28:55480
Apr 20 17:37:18 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:18 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Message-ID: e08c1cb79052989c#015#012Byte-Range: 1-795/795#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:18 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:19 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:19 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Message-ID: b3d8ef53686671e7#015#012Byte-Range: 1-791/791#015#012Success-Report: no#015#012Failure-Report: no#015#012Content-Type: message/cpim
Apr 20 17:37:19 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:19 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Blink hack - changing dest to msrps://10.11.200.35:51621
Apr 20 17:37:19 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012From-Path: msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012Message-ID: a8cbf045618dc6#015#012Byte-Range: 1-1186/1186#015#012Success-Report: yes#015#012Failure-Report: yes#015#012Content-Type: message/cpim
Apr 20 17:37:19 devsip00 /sbin/kamailio[6284]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.11.200.35:51621
Apr 20 17:37:19 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Full headers are - To-Path: msrps://10.99.52.17:2855/s.6284.1.1730931015;tcp msrps://10.99.54.28:55480/907be33aae8bc93c9961;tcp#015#012From-Path: msrps://10.0.0.12:2855/a77f27b2899bfad7dd9a;tcp#015#012Status: 000 200 OK#015#012Message-ID: a8cbf045618dc6#015#012Byte-Range: 1-1186/1186
Apr 20 17:37:19 devsip00 /sbin/kamailio[6285]: INFO: <script>: TranslationDetail: Changing dest to - msrps://10.99.54.28:55480
Apr 20 17:37:22 devsip00 /sbin/kamailio[6286]: {1 4013 BYE d9695149ce484811ab98ca41a4e8c694} DEBUG: dialog [dlg_hash.c:1122]: next_state_dlg(): dialog 0x7fabcedc0a60 changed from state 4 to state 5, due event 7 (ref 3)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6286]: {1 4013 BYE d9695149ce484811ab98ca41a4e8c694} DEBUG: http_async_client [async_http.c:319]: async_send_query(): no pre-existing transaction, switching to transaction-less behavior
Apr 20 17:37:22 devsip00 /sbin/kamailio[6286]: {1 4013 BYE d9695149ce484811ab98ca41a4e8c694} DEBUG: http_async_client [async_http.c:436]: async_push_query(): query sent [http://tucitd067.cyracom.com:50634/api/events/CallEnded] (0x7fabcedf9db0) to worker 1
Apr 20 17:37:22 devsip00 /sbin/kamailio[6286]: {1 4013 BYE d9695149ce484811ab98ca41a4e8c694} INFO: <script>: Translation: TextBroker - Making CallEnded for d9695149ce484811ab98ca41a4e8c694
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [async_http.c:270]: notification_socket_cb(): query received: [http://tucitd067.cyracom.com:50634/api/events/CallEnded] (0x7fabcedf9db0)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:419]: new_request(): received query http://tucitd067.cyracom.com:50634/api/events/CallEnded with timeout 500, tls_verify_peer 1, tls_verify_host 1 (param=0x7fabcedf9db0)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:119]: build_http_m_cell(): hash id for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:130]: link_http_m_cell(): linking new cell 0x7fabcede66f8 to table 0x7fabced5ee60 [447]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:521]: new_request(): Adding easy 0x7fabcedfb510 to multi 0x7fabced1bc00 (http://tucitd067.cyracom.com:50634/api/events/CallEnded)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to 1 ms
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] Found bundle for host tucitd067.cyracom.com: 0x7fabcedbd0b0
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] Re-using existing connection! (#0) with host tucitd067.cyracom.com
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] Connected to tucitd067.cyracom.com (10.11.200.35) port 50634 (#0)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr out] POST /api/events/CallEnded HTTP/1.1#015#012Host: tucitd067.cyracom.com:50634#015#012Accept: */*#015#012Content-Type: application/json#015#012Content-Length: 82
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] upload completely sent off: 82 out of 82 bytes
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=9 e=0x7fabcedfb510 what=IN
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:179]: sock_cb(): Adding data: IN
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:606]: setsock(): added event 0x1fbf100 to socket 9
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:53]: multi_timer_cb(): multi_timer_cb: Setting timeout to -1 ms
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:552]: check_multi_info(): REMAINING: 1
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:67]: timer_cb(): timeout on socket -1
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:552]: check_multi_info(): REMAINING: 1
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:96]: event_cb(): activity 2 on socket 9: action 1
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:129]: event_cb(): performing action 1 on socket 9
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] HTTP/1.1 200 OK
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: HTTP/1.1 200 OK#015#012 [17]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7fabcedfb510)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] Transfer-Encoding: chunked
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Transfer-Encoding: chunked#015#012 [28]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7fabcedfb510)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] Content-Type: text/plain; charset=utf-8
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Content-Type: text/plain; charset=utf-8#015#012 [41]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7fabcedfb510)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] Server: Kestrel
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Server: Kestrel#015#012 [17]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7fabcedfb510)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] X-Powered-By: ASP.NET
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: X-Powered-By: ASP.NET#015#012 [23]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7fabcedfb510)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in] Date: Thu, 20 Apr 2017 17:37:21 GMT
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: Date: Thu, 20 Apr 2017 17:37:21 GMT#015#012 [37]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7fabcedfb510)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: #015#012 [2]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7fabcedfb510)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL hdr in]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:249]: write_cb(): data received: CallEnded - At Time:Thu Apr 20 17:37:22 2017 CallId:d9695149ce484811ab98ca41a4e8c694 [84]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:289]: write_cb(): getting easy handler info (0x7fabcedfb510)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: http_async_client [http_multi.c:238]: debug_cb(): [cURL] Connection #0 to host tucitd067.cyracom.com left intact
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:161]: sock_cb(): socket callback: s=9 e=0x7fabcedfb510 what=REMOVE
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:166]: sock_cb(): freeing event 0x1fbf100
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:131]: event_cb(): action 1 on socket 9 performed
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:552]: check_multi_info(): REMAINING: 0
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:559]: check_multi_info(): DONE: http://tucitd067.cyracom.com:50634/api/events/CallEnded => (0)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:80]: build_hash_key(): received id 0x7fabcedfb510 (8)-> 0x7fabcedfb510 (14)
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:93]: build_hash_key(): hash for 0x7fabcedfb510 is 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [hm_hash.c:157]: http_m_cell_lookup(): http_m_cell with easy=0x7fabcedfb510 found on table entry 447
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [async_http.c:128]: async_http_cb(): query result = HTTP/1.1 200 OK#015#012Transfer-Encoding: chunked#015#012Content-Type: text/plain; charset=utf-8#015#012Server: Kestrel#015#012X-Powered-By: ASP.NET#015#012Date: Thu, 20 Apr 2017 17:37:21 GMT#015#012#015#012CallEnded - At Time:Thu Apr 20 17:37:22 2017 CallId:d9695149ce484811ab98ca41a4e8c694 [249]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [async_http.c:161]: async_http_cb(): successfully parsed http reply 0x7fabd920c4b0
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: <script>: route[HTTP_REPLY]: status:200
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: INFO: <script>: route[HTTP_REPLY]: body   CallEnded - At Time:Thu Apr 20 17:37:22 2017 CallId:d9695149ce484811ab98ca41a4e8c694
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [async_http.h:126]: free_async_query(): freeing query 0x7fabcedf9db0
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:570]: check_multi_info(): reply: [200] HTTP/1.1 200 OK#015#012Transfer-Encoding: chunked#015#012Content-Type: text/plain; charset=utf-8#015#012Server: Kestrel#015#012X-Powered-By: ASP.NET#015#012Date: Thu, 20 Apr 2017 17:37:21 GMT#015#012#015#012CallEnded - At Time:Thu Apr 20 17:37:22 2017 CallId:d9695149ce484811ab98ca41a4e8c694 [249]
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:575]: check_multi_info(): cleaning up cell 0x7fabcede66f8
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:580]: check_multi_info(): Removing handle 0x7fabcedfb510
Apr 20 17:37:22 devsip00 /sbin/kamailio[6280]: DEBUG: http_async_client [http_multi.c:147]: event_cb(): last transfer done, kill timeout