[SR-Users] 408 Request Timeout

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-websocket http://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]