[SR-Users] 408 Request Timeout
vamsi
vamsymohan at gmail.com
Thu Sep 3 05:15:22 CEST 2015
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 at 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 at ip-205-64-10.dqa.capitalone.com>#015#012From:
"xyz987" <sip:xyz987 at 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 at 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 at 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 at ip-10-255-54-20.test.com>#015#012From: "xyz987" <
sip:xyz987 at 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 at 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 at 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 at 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 at 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 at ip-10-255-54-20.test.com>;tag=b44d120c617c843b219e52eae8b5e9ff-3dde#015#012From:
"xyz987" <sip:xyz987 at 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 at ip-10-255-54-20.test.com>;tag=b44d120c617c843b219e52eae8b5e9ff-3dde#015#012From:
"xyz987" <sip:xyz987 at 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]
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20150902/7453bf9a/attachment.html>
More information about the sr-users
mailing list