I am an web developer and want to create a WEBRTC application. My knowledge level in SIP is beginner. I want my application to talk to kamailio and in process of setting up kamailio by following the below articles.
http://nil.uniza.sk/sip/kamailio/configuring-kamailio-4x-websockethttp://kamailio.org/docs/modules/4.3.x/modules/websocket.html
I was able to connect (using try.jssp.net) to kamailio as multiple clients for initial setup and successfully able to get voice and video working.
But I'm unable to send messages between clients and receive "408 Request Timeout" error.
I have the presence and presence_xml modules loaded and have the
# ----- mi_fifo params -----
modparam("mi_fifo", "fifo_name", "/var/run/kamailio/kamailio_fifo")
# ----- ctl params -----
modparam("ctl", "binrpc", "unix:/var/run/kamailio/kamailio_ctl")
Below is the log. Any help is appreciated.
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[AUTH] c=[/usr/local/etc/kamailio/kamailio.cfg] l=825 a=2 n=exit
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [receive.c:278]: receive_msg(): cleaning up
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [tcp_read.c:1153]: ws_process_msg(): WebSocket Message: [[>>>#012??#002?E?<<<]]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_conn.c:429]: wsconn_get(): wsconn_get for id [2]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_conn.c:437]: wsconn_get(): wsconn_get returns wsc [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_frame.c:415]: decode_and_validate_ws_frame(): decoding WebSocket frame
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_frame.c:456]: decode_and_validate_ws_frame(): supported non-control frame: 0x1
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_frame.c:555]: decode_and_validate_ws_frame(): Rx (decoded): MESSAGE sip:abc123@ip-205-64-10.dqa.capitalone.com SIP/2.0#015#012Via: SIP/2.0/WS kjh0uotuqma6.invalid;branch=z9hG4bK9552000#015#012Max-Forwards: 69#015#012To: <sip:abc123@ip-205-64-10.dqa.capitalone.com>#015#012From: "xyz987" <sip:xyz987@ip-205-64-10.test.com:8088>;tag=c1joq54vne#015#012Call-ID: orel8as144p4ebuuf96n#015#012CSeq: 1977 MESSAGE#015#012Proxy-Authorization: Digest algorithm=MD5, username="xyz987", realm="ip-205-64-10.test.com", nonce="Vee3iFXntlzqK/kDTxUAIxwI55RzH/ll", uri="sip:abc123@ip-205-64-10.test.com", response="0d513943ed7a0234b7016c5f4546b0b7"#015#012Content-Type: text/plain#015#012Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER#015#012Supported: outbound#015#012User-Agent: JsSIP 0.7.4#015#012Content-Length: 13#015#012#015#012samplemsgwork
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_frame.c:659]: ws_frame_receive(): Rx SIP message:#012MESSAGE sip:abc123@ip-10-255-54-20.test.com SIP/2.0#015#012Via: SIP/2.0/WS kjh0uotuqma6.invalid;branch=z9hG4bK9552000#015#012Max-Forwards: 69#015#012To: <sip:abc123@ip-10-255-54-20.test.com>#015#012From: "xyz987" <sip:xyz987@ip-10-255-54-20.test.com:8088>;tag=c1joq54vne#015#012Call-ID: orel8as144p4ebuuf96n#015#012CSeq: 1977 MESSAGE#015#012Proxy-Authorization: Digest algorithm=MD5, username="xyz987", realm="ip-10-255-54-20.test.com", nonce="Vee3iFXntlzqK/kDTxUAIxwI55RzH/ll", uri="sip:abc123@ip-10-255-54-20.test.com", response="0d513943ed7a0234b7016c5f4546b0b7"#015#012Content-Type: text/plain#015#012Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER#015#012Supported: outbound#015#012User-Agent: JsSIP 0.7.4#015#012Content-Length: 13#015#012#015#012samplemsgwork
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method: <MESSAGE>
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: <sip:abc123@ip-10-255-54-20.test.com>
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version: <SIP/2.0>
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK9552000>; state=16
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [receive.c:134]: receive_msg(): After parse_msg...
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [receive.c:177]: receive_msg(): preparing to run routing scripts...
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=506 a=5 n=route
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=639 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=634 a=25 n=mf_process_maxfwd_header
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 69
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=644 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=639 a=25 n=is_method
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=648 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[REQINIT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=644 a=26 n=sanity_check
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=10
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG: get_hdr_field: <To> [46]; uri=[sip:abc123@ip-10-255-54-20.test.com]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG: to body [<sip:abc123@ip-10-255-54-20.test.com>#015#012]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <1977> <MESSAGE>
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG: get_hdr_body : content_length=13
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param(): DEBUG: add_param: tag=c1joq54vne
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec(): end of header reached, state=29
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity checks result: 1
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=529 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=509 a=25 n=nat_uac_test
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=514 a=55 n=force_rport
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=524 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=515 a=25 n=is_method
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=524 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=519 a=24 n=add_contact_alias
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: nathelper [nathelper.c:958]: add_contact_alias_0_f(): no contact header
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=529 a=5 n=route
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=846 a=55 n=force_rport
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=857 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=847 a=25 n=nat_uac_test
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=853 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=848 a=25 n=is_method
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=853 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=851 a=24 n=is_first_hop
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: siputils [sipops.c:159]: w_is_first_hop(): no 2nd via found - first hop
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=852 a=24 n=set_contact_alias
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=854 a=39 n=setflag
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[NATDETECT] c=[/usr/local/etc/kamailio/kamailio.cfg] l=857 a=2 n=return
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=540 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=532 a=25 n=is_method
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=540 a=5 n=route
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=654 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=652 a=24 n=has_totag
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: siputils [checks.c:97]: has_totag(): no totag
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[WITHINDLG] c=[/usr/local/etc/kamailio/kamailio.cfg] l=652 a=2 n=return
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=549 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=545 a=24 n=t_precheck_trans
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=549 a=24 n=t_check_trans
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=3 T start=(nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request: start searching: hash=65389, isACK=0
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261 transaction matching failed
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg: msg id=4 global id=4 T end=(nil)
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/usr/local/etc/kamailio/kamailio.cfg] l=552 a=5 n=route
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[AUTH] c=[/usr/local/etc/kamailio/kamailio.cfg] l=833 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[AUTH] c=[/usr/local/etc/kamailio/kamailio.cfg] l=820 a=25 n=is_method
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:563]: grep_sock_info(): checking if host==us: 31==12 && [ip-10-255-54-20.test.com] == [10.205.64.10]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:566]: grep_sock_info(): checking if port 8088 (advertise 0) matches port 8088
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:563]: grep_sock_info(): checking if host==us: 31==12 && [ip-10-255-54-20.test.com] == [10.205.64.10]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:566]: grep_sock_info(): checking if port 8088 (advertise 0) matches port 8088
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:563]: grep_sock_info(): checking if host==us: 31==12 && [ip-10-255-54-20.test.com] == [10.205.64.10]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:566]: grep_sock_info(): checking if port 8080 (advertise 0) matches port 8088
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:563]: grep_sock_info(): checking if host==us: 31==12 && [ip-10-255-54-20.test.com] == [10.205.64.10]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:566]: grep_sock_info(): checking if port 5061 (advertise 0) matches port 8088
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:563]: grep_sock_info(): checking if host==us: 31==12 && [ip-10-255-54-20.test.com] == [10.205.64.10]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [socket_info.c:566]: grep_sock_info(): checking if port 4443 (advertise 0) matches port 8088
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[AUTH] c=[/usr/local/etc/kamailio/kamailio.cfg] l=828 a=16 n=if
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10177]: exec: *** cfgtrace:request_route=[AUTH] c=[/usr/local/etc/kamailio/kamailio.cfg] l=823 a=27 n=auth_check
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:457]: wsconn_get_list(): wsconn_get_list
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a775a8] prev => [(nil)] next => [0x7f06a2a76298]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a76298] prev => [0x7f06a2a775a8] next => [(nil)]
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a775a8] id [1] ref++
Sep 2 22:54:20 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a76298] id [2] ref++
Sep 2 22:54:25 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:501]: wsconn_get_list(): wsconn_get_list returns list [0x7f06ab4f8350] with [2] members
Sep 2 22:54:25 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:511]: wsconn_put_list(): wsconn_put_list [0x7f06ab4f8350]
Sep 2 22:54:25 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a775a8] refcnt [2]
Sep 2 22:54:25 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a775a8] refcnt [1]
Sep 2 22:54:25 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:25 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [io_wait.h:598]: io_watch_del(): DBG: io_watch_del (0xa40820, 9, -1, 0x10) fd_no=2 called
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [tcp_read.c:1450]: release_tcpconn(): releasing con 0x7f06a2a8c3b8, state 1, fd=9, id=2
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10177]: DEBUG: <core> [tcp_read.c:1451]: release_tcpconn(): extra_data (nil)
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10178]: DEBUG: <core> [tcp_main.c:3243]: handle_tcp_child(): reader response= 7f06a2a8c3b8, 1 from 3
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10178]: DEBUG: <core> [io_wait.h:376]: io_watch_add(): DBG: io_watch_add(0x9fc580, 34, 2, 0x7f06a2a8c3b8), fd_no=23
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10178]: DEBUG: <core> [tcp_main.c:3370]: handle_tcp_child(): CONN_RELEASE 0x7f06a2a8c3b8 refcnt= 1
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:457]: wsconn_get_list(): wsconn_get_list
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a775a8] prev => [(nil)] next => [0x7f06a2a76298]
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a76298] prev => [0x7f06a2a775a8] next => [(nil)]
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a775a8] id [1] ref++
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a76298] id [2] ref++
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:501]: wsconn_get_list(): wsconn_get_list returns list [0x7f06ab4f8350] with [2] members
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:511]: wsconn_put_list(): wsconn_put_list [0x7f06ab4f8350]
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a775a8] refcnt [2]
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a775a8] refcnt [1]
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:26 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:457]: wsconn_get_list(): wsconn_get_list
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a775a8] prev => [(nil)] next => [0x7f06a2a76298]
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a76298] prev => [0x7f06a2a775a8] next => [(nil)]
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a775a8] id [1] ref++
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a76298] id [2] ref++
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:501]: wsconn_get_list(): wsconn_get_list returns list [0x7f06ab4f8350] with [2] members
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:511]: wsconn_put_list(): wsconn_put_list [0x7f06ab4f8350]
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a775a8] refcnt [2]
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a775a8] refcnt [1]
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:27 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:457]: wsconn_get_list(): wsconn_get_list
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a775a8] prev => [(nil)] next => [0x7f06a2a76298]
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a76298] prev => [0x7f06a2a775a8] next => [(nil)]
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a775a8] id [1] ref++
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a76298] id [2] ref++
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:501]: wsconn_get_list(): wsconn_get_list returns list [0x7f06ab4f8350] with [2] members
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:511]: wsconn_put_list(): wsconn_put_list [0x7f06ab4f8350]
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a775a8] refcnt [2]
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a775a8] refcnt [1]
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:28 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]
Sep 2 22:54:29 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:457]: wsconn_get_list(): wsconn_get_list
Sep 2 22:54:29 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a775a8] prev => [(nil)] next => [0x7f06a2a76298]
Sep 2 22:54:29 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a76298] prev => [0x7f06a2a775a8] next => [(nil)]
Sep 2 22:54:29 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a775a8] id [1] ref++
Sep 2 22:54:29 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a76298] id [2] ref++
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:501]: wsconn_get_list(): wsconn_get_list returns list [0x7f06ab4f8350] with [2] members
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:511]: wsconn_put_list(): wsconn_put_list [0x7f06ab4f8350]
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a775a8] refcnt [2]
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a775a8] refcnt [1]
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: tm [t_reply.c:1230]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=408
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: tm [t_reply.c:1748]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (172.18.116.115, kjh0uotuqma6.invalid, 0)
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: <core> [mem/shm_mem.c:101]: _shm_resize(): WARNING:vqm_resize: resize(0) called
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: websocket [ws_conn.c:429]: wsconn_get(): wsconn_get for id [2]
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: websocket [ws_conn.c:437]: wsconn_get(): wsconn_get returns wsc [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: websocket [ws_frame.c:751]: ws_frame_transmit(): Tx message:#012SIP/2.0 408 Request Timeout#015#012Via: SIP/2.0/WS kjh0uotuqma6.invalid;branch=z9hG4bK2462101;rport=59540;received=172.18.116.115#015#012To: <sip:abc123@ip-10-255-54-20.test.com>;tag=b44d120c617c843b219e52eae8b5e9ff-3dde#015#012From: "xyz987" <sip:xyz987@ip-10-255-54-20.test.com:8088>;tag=5qg1sitf3i#015#012Call-ID: orel8dhn00r81cega00f#015#012CSeq: 862 MESSAGE#015#012Server: kamailio (4.4.0-dev2 (x86_64/linux))#015#012Content-Length: 0#015#012#015#012
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: websocket [ws_frame.c:150]: encode_and_send_ws_frame(): encoding WebSocket frame
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: websocket [ws_frame.c:181]: encode_and_send_ws_frame(): supported non-control frame: 0x1
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: <core> [tcp_main.c:2211]: tcpconn_send_put(): tcp connection found (0x7f06a2a8c3b8), acquiring fd
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: <core> [tcp_main.c:2221]: tcpconn_send_put(): c=0x7f06a2a8c3b8, n=16
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10178]: DEBUG: <core> [tcp_main.c:3476]: handle_ser_child(): read response= 7f06a2a8c3b8, 2, fd -1 from 5 (10164)
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: <core> [tcp_main.c:2251]: tcpconn_send_put(): after receive_fd: c= 0x7f06a2a8c3b8 n=8 fd=8
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending...
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7f06a2a8c3b8 n=419 fd=8
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012?~#001?SIP/2.0 408 Request Timeout#015#012Via: SIP/2.0/WS kjh0uotuqma6.invalid;branch=z9hG4bK2462101;rport=59540;received=172.18.116.115#015#012To: <sip:abc123@ip-10-255-54-20.test.com>;tag=b44d120c617c843b219e52eae8b5e9ff-3dde#015#012From: "xyz987" <sip:xyz987@ip-10-255-54-20.test.com:8088>;tag=5qg1sitf3i#015#012Call-ID: orel8dhn00r81cega00f#015#012CSeq: 862 MESSAGE#015#012Server: kamailio (4.4.0-dev2 (x86_64/linux))#015#012Content-Length: 0#015#012#015#012
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:31 ip-10-255-54-20 kamailio[10164]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:457]: wsconn_get_list(): wsconn_get_list
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a775a8] prev => [(nil)] next => [0x7f06a2a76298]
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:465]: wsconn_get_list(): counter wsc [0x7f06a2a76298] prev => [0x7f06a2a775a8] next => [(nil)]
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a775a8] id [1] ref++
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:492]: wsconn_get_list(): wsc [0x7f06a2a76298] id [2] ref++
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:501]: wsconn_get_list(): wsconn_get_list returns list [0x7f06ab4f8350] with [2] members
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:511]: wsconn_put_list(): wsconn_put_list [0x7f06ab4f8350]
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a775a8] refcnt [2]
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a775a8] refcnt [1]
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:382]: wsconn_put(): wsconn_put start for [0x7f06a2a76298] refcnt [2]
Sep 2 22:54:32 ip-10-255-54-20 kamailio[10173]: DEBUG: websocket [ws_conn.c:415]: wsconn_put(): wsconn_put end for [0x7f06a2a76298] refcnt [1]