Well, not the simpl5.org site itself, since it uses its own Websocket-SIP gateway to make calls. I downloaded their code and changed the gateway address from "sipml5.org" to "192.168.0.148" which is the VM where I'm testing. Also, Apache is using ports 8000 and 4443 since Kamailio uses 80 and 443.
But when I try to make a call, filling 192.168.0.48 as the realm, I get "Disconnected: Failed to connet to the server" from the call.htm page. I monitored packets with Wireshark and I see no traffic that would make sense between the machine with the browser and the one with Kamailio. I suspect I have to make more changes in the sipml5.org Javascript, am I correct?
Kamailio itself (Dev version) is up and running and normal SIP from normal UAs works. Here's the config I'm using. It's the example in the websocket module directory, just changed to use my db and the default TLS cert that was installed:
---------------------------------------------------------------------- #!KAMAILIO # # Simple/sample kamailio.cfg for running a proxy/registrar with TLS and # WebSockets support.
#!substdef "!DBURL!mysql://ser:heslo@localhost/ser!g" #!substdef "!MY_IP_ADDR!192.168.0.148!g" #!substdef "!MY_WS_PORT!80!g" #!substdef "!MY_WSS_PORT!443!g" #!substdef "!MY_WS_ADDR!tcp:MY_IP_ADDR:MY_WS_PORT!g" #!substdef "!MY_WSS_ADDR!tls:MY_IP_ADDR:MY_WSS_PORT!g"
##!define LOCAL_TEST_RUN #!define WITH_TLS #!define WITH_WEBSOCKETS
####### Global Parameters #########
fork=yes children=4
#alias="example.com"
#!ifdef WITH_TLS enable_tls=1 #!endif
listen=MY_IP_ADDR #!ifdef WITH_WEBSOCKETS listen=MY_WS_ADDR #!ifdef WITH_TLS listen=MY_WSS_ADDR #!endif #!endif
tcp_connection_lifetime=3604 tcp_accept_no_cl=yes tcp_rd_buf_size=16384
syn_branch=0 debug=2
# set paths to location of modules (to sources or installation folders) #!ifdef WITH_SRCPATH mpath="modules_k:modules" #!else mpath="/usr/local/kamailio/lib/kamailio/modules_k/:/usr/local/kamailio/lib/kamailio/modules/" #!endif
loadmodule "db_mysql.so" loadmodule "tm.so" loadmodule "sl.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "maxfwd.so" loadmodule "usrloc.so" loadmodule "registrar.so" loadmodule "textops.so" loadmodule "siputils.so" loadmodule "xlog.so" loadmodule "sanity.so" loadmodule "ctl.so" loadmodule "auth.so" loadmodule "auth_db.so" loadmodule "kex.so" loadmodule "mi_rpc.so" #!ifdef WITH_TLS loadmodule "tls.so" #!endif #!ifdef WITH_WEBSOCKETS loadmodule "xhttp.so" loadmodule "websocket.so" loadmodule "nathelper.so" #!endif
# ----------------- setting module-specific parameters ---------------
# ----- tm params ----- # auto-discard branches from previous serial forking leg modparam("tm", "failure_reply_mode", 3) # default retransmission timeout: 30sec modparam("tm", "fr_timer", 30000) # default invite retransmission timeout after 1xx: 120sec modparam("tm", "fr_inv_timer", 120000)
# ----- rr params ----- # add value to ;lr param to cope with most of the UAs modparam("rr", "enable_full_lr", 1) # do not append from tag to the RR (no need for this script) modparam("rr", "append_fromtag", 0)
# ----- registrar params ----- modparam("registrar", "method_filtering", 1) modparam("registrar", "max_expires", 3600) modparam("registrar", "gruu_enabled", 0)
# ----- usrloc params ----- modparam("usrloc", "db_url", "DBURL") modparam("usrloc", "db_mode", 0)
# ----- auth_db params ----- modparam("auth_db", "db_url", "DBURL") modparam("auth_db", "calculate_ha1", yes) modparam("auth_db", "password_column", "password") modparam("auth_db", "load_credentials", "")
#!ifdef WITH_TLS # ----- tls params ----- modparam("tls", "config", "/usr/local/kamailio/etc/kamailio/tls.cfg") #!endif
#!ifdef WITH_WEBSOCKETS # ----- nathelper params ----- modparam("nathelper|registrar", "received_avp", "$avp(RECEIVED)") # Note: leaving NAT pings turned off here as nathelper is _only_ being used for # WebSocket connections. NAT pings are not needed as WebSockets have # their own keep-alives. #!endif
####### Routing Logic ########
# Main SIP request routing logic # - processing of any incoming SIP request starts with this route # - note: this is the same as route { ... } request_route {
# per request initial checks route(REQINIT);
#!ifdef WITH_WEBSOCKETS if (nat_uac_test(64)) { # Do NAT traversal stuff for requests from a WebSocket # connection - even if it is not behind a NAT! # This won't be needed in the future if Kamailio and the # WebSocket client support Outbound and Path. force_rport(); if (is_method("REGISTER")) fix_nated_register(); else { if (!add_contact_alias()) { xlog("L_ERR", "Error aliasing contact <$ct>\n"); sl_send_reply("400", "Bad Request"); exit; } } } #!endif
# handle requests within SIP dialogs route(WITHINDLG);
### only initial requests (no To tag)
# CANCEL processing if (is_method("CANCEL")) { if (t_check_trans()) t_relay(); exit; }
t_check_trans();
# authentication route(AUTH);
# record routing for dialog forming requests (in case they are routed) # - remove preloaded route headers remove_hf("Route"); if (is_method("INVITE")) record_route();
# handle registrations route(REGISTRAR);
if ($rU==$null) { # request with no Username in RURI sl_send_reply("484","Address Incomplete"); exit; }
# user location service route(LOCATION);
route(RELAY); }
route[RELAY] { if (!t_relay()) { sl_reply_error(); } exit; }
# Per SIP request initial checks route[REQINIT] { if (!mf_process_maxfwd_header("10")) { sl_send_reply("483","Too Many Hops"); exit; }
if(!sanity_check("1511", "7")) { xlog("Malformed SIP message from $si:$sp\n"); exit; } }
# Handle requests within SIP dialogs route[WITHINDLG] { if (has_totag()) { # sequential request withing a dialog should # take the path determined by record-routing if (loose_route()) { #!ifdef WITH_WEBSOCKETS if ($du == "") { if (!handle_ruri_alias()) { xlog("L_ERR", "Bad alias <$ru>\n"); sl_send_reply("400", "Bad Request"); exit; } } #!endif route(RELAY); } else { if ( is_method("ACK") ) { if ( t_check_trans() ) { # no loose-route, but stateful ACK; # must be an ACK after a 487 # or e.g. 404 from upstream server t_relay(); exit; } else { # ACK without matching transaction... # ignore and discard exit; } } sl_send_reply("404","Not here"); } exit; } }
# Handle SIP registrations route[REGISTRAR] { if (is_method("REGISTER")) { if (!save("location")) sl_reply_error();
exit; } }
# USER location service route[LOCATION] { if (!lookup("location")) { $var(rc) = $rc; t_newtran(); switch ($var(rc)) { case -1: case -3: send_reply("404", "Not Found"); exit; case -2: send_reply("405", "Method Not Allowed"); exit; } } }
# Authentication route route[AUTH] { if (is_method("REGISTER") || from_uri==myself) { # authenticate requests if (!auth_check("$fd", "subscriber", "1")) { auth_challenge("$fd", "0"); exit; } # user authenticated - remove auth header if(!is_method("REGISTER")) consume_credentials(); } # if caller is not local subscriber, then check if it calls # a local destination, otherwise deny, not an open relay here if (from_uri!=myself && uri!=myself) { sl_send_reply("403","Not relaying"); exit; } }
#!ifdef WITH_WEBSOCKETS onreply_route { if (nat_uac_test(64)) { # Do NAT traversal stuff for replies to a WebSocket connection # - even if it is not behind a NAT! # This won't be needed in the future if Kamailio and the # WebSocket client support Outbound and Path. add_contact_alias(); } }
event_route[xhttp:request] { set_reply_close(); set_reply_no_connect(); if ($Rp != MY_WS_PORT && $Rp != MY_WSS_PORT) { xlog("L_WARN", "HTTP request received on $Rp\n"); xhttp_reply("403", "Forbidden", "", ""); exit; }
xlog("L_DBG", "HTTP Request Received\n");
if ($hdr(Upgrade)=~"websocket" && $hdr(Connection)=~"Upgrade" && $rm=~"GET") { xlog("L_DBG", "WebSocket\n"); xlog("L_DBG", " Host: $hdr(Host)\n"); xlog("L_DBG", " Origin: $hdr(Origin)\n");
if ($hdr(Host) == $null || !is_myself($hdr(Host))) { xlog("L_WARN", "Bad host $hdr(Host)\n"); xhttp_reply("403", "Forbidden", "", ""); exit; }
# Optional... validate Origin # Optional... perform HTTP authentication
# ws_handle_handshake() exits (no further configuration file # processing of the request) when complete. if (ws_handle_handshake()) { # Optional... cache some information abou the # successful connection exit; } }
xhttp_reply("404", "Not found", "", ""); }
event_route[websocket:closed] { xlog("L_INFO", "WebSocket connection from $si:$sp has closed\n"); } #!endif ----------------------------------------------------------------------
In case both your kamailio installation and your apache webserver are in the same machine, it is not going to work with the kamailio config example as is.
There's probably an error reported in kamaiio's log file so you should check it out. In my case, I had to comment out parts of the script that were in charge of doing some validation, particularly, in event_route[xhttp:request].
Also, the port I used for signaling in the call.htm (sipml5) is 5060, not 80. This is because xhttp listens for TCP connections on that port.
Regards.
Carlos.
event_route[xhttp:request] { set_reply_close(); set_reply_no_connect();
# if ($Rp != MY_WS_PORT && $Rp != MY_WSS_PORT) { # xlog("L_WARN", "HTTP request received on $Rp\n"); # xhttp_reply("403", "Forbidden", "", ""); # exit; # }
xlog("L_ALERT", "HTTP Request Received\n");
if ($hdr(Upgrade)=~"websocket" && $hdr(Connection)=~"Upgrade" && $rm=~"GET") { xlog("L_DBG", "WebSocket\n"); xlog("L_DBG", " Host: $hdr(Host)\n"); xlog("L_DBG", " Origin: $hdr(Origin)\n");
# if ($hdr(Host) == $null || !is_myself($hdr(Host))) { # xlog("L_WARN", "Bad host $hdr(Host)\n"); # xhttp_reply("403", "Forbidden", "", ""); # exit; # }
# Optional... validate Origin # Optional... perform HTTP authentication
# ws_handle_handshake() exits (no further configuration file # processing of the request) when complete. if (ws_handle_handshake()) return; }
xhttp_reply("404", "Not found", "", ""); }
On Tue, Aug 14, 2012 at 6:13 PM, Juan Castro jcastro@instant.com.br wrote:
Well, not the simpl5.org site itself, since it uses its own Websocket-SIP gateway to make calls. I downloaded their code and changed the gateway address from "sipml5.org" to "192.168.0.148" which is the VM where I'm testing. Also, Apache is using ports 8000 and 4443 since Kamailio uses 80 and 443.
But when I try to make a call, filling 192.168.0.48 as the realm, I get "Disconnected: Failed to connet to the server" from the call.htm page. I monitored packets with Wireshark and I see no traffic that would make sense between the machine with the browser and the one with Kamailio. I suspect I have to make more changes in the sipml5.org Javascript, am I correct?
Kamailio itself (Dev version) is up and running and normal SIP from normal UAs works. Here's the config I'm using. It's the example in the websocket module directory, just changed to use my db and the default TLS cert that was installed:
#!KAMAILIO # # Simple/sample kamailio.cfg for running a proxy/registrar with TLS and # WebSockets support.
#!substdef "!DBURL!mysql://ser:heslo@localhost/ser!g" #!substdef "!MY_IP_ADDR!192.168.0.148!g" #!substdef "!MY_WS_PORT!80!g" #!substdef "!MY_WSS_PORT!443!g" #!substdef "!MY_WS_ADDR!tcp:MY_IP_ADDR:MY_WS_PORT!g" #!substdef "!MY_WSS_ADDR!tls:MY_IP_ADDR:MY_WSS_PORT!g"
##!define LOCAL_TEST_RUN #!define WITH_TLS #!define WITH_WEBSOCKETS
####### Global Parameters #########
fork=yes children=4
#alias="example.com"
#!ifdef WITH_TLS enable_tls=1 #!endif
listen=MY_IP_ADDR #!ifdef WITH_WEBSOCKETS listen=MY_WS_ADDR #!ifdef WITH_TLS listen=MY_WSS_ADDR #!endif #!endif
tcp_connection_lifetime=3604 tcp_accept_no_cl=yes tcp_rd_buf_size=16384
syn_branch=0 debug=2
# set paths to location of modules (to sources or installation folders) #!ifdef WITH_SRCPATH mpath="modules_k:modules" #!else
mpath="/usr/local/kamailio/lib/kamailio/modules_k/:/usr/local/kamailio/lib/kamailio/modules/" #!endif
loadmodule "db_mysql.so" loadmodule "tm.so" loadmodule "sl.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "maxfwd.so" loadmodule "usrloc.so" loadmodule "registrar.so" loadmodule "textops.so" loadmodule "siputils.so" loadmodule "xlog.so" loadmodule "sanity.so" loadmodule "ctl.so" loadmodule "auth.so" loadmodule "auth_db.so" loadmodule "kex.so" loadmodule "mi_rpc.so" #!ifdef WITH_TLS loadmodule "tls.so" #!endif #!ifdef WITH_WEBSOCKETS loadmodule "xhttp.so" loadmodule "websocket.so" loadmodule "nathelper.so" #!endif
# ----------------- setting module-specific parameters ---------------
# ----- tm params ----- # auto-discard branches from previous serial forking leg modparam("tm", "failure_reply_mode", 3) # default retransmission timeout: 30sec modparam("tm", "fr_timer", 30000) # default invite retransmission timeout after 1xx: 120sec modparam("tm", "fr_inv_timer", 120000)
# ----- rr params ----- # add value to ;lr param to cope with most of the UAs modparam("rr", "enable_full_lr", 1) # do not append from tag to the RR (no need for this script) modparam("rr", "append_fromtag", 0)
# ----- registrar params ----- modparam("registrar", "method_filtering", 1) modparam("registrar", "max_expires", 3600) modparam("registrar", "gruu_enabled", 0)
# ----- usrloc params ----- modparam("usrloc", "db_url", "DBURL") modparam("usrloc", "db_mode", 0)
# ----- auth_db params ----- modparam("auth_db", "db_url", "DBURL") modparam("auth_db", "calculate_ha1", yes) modparam("auth_db", "password_column", "password") modparam("auth_db", "load_credentials", "")
#!ifdef WITH_TLS # ----- tls params ----- modparam("tls", "config", "/usr/local/kamailio/etc/kamailio/tls.cfg") #!endif
#!ifdef WITH_WEBSOCKETS # ----- nathelper params ----- modparam("nathelper|registrar", "received_avp", "$avp(RECEIVED)") # Note: leaving NAT pings turned off here as nathelper is _only_ being used for # WebSocket connections. NAT pings are not needed as WebSockets have # their own keep-alives. #!endif
####### Routing Logic ########
# Main SIP request routing logic # - processing of any incoming SIP request starts with this route # - note: this is the same as route { ... } request_route {
# per request initial checks route(REQINIT);
#!ifdef WITH_WEBSOCKETS if (nat_uac_test(64)) { # Do NAT traversal stuff for requests from a WebSocket # connection - even if it is not behind a NAT! # This won't be needed in the future if Kamailio and the # WebSocket client support Outbound and Path. force_rport(); if (is_method("REGISTER")) fix_nated_register(); else { if (!add_contact_alias()) { xlog("L_ERR", "Error aliasing contact <$ct>\n"); sl_send_reply("400", "Bad Request"); exit; } } } #!endif
# handle requests within SIP dialogs route(WITHINDLG); ### only initial requests (no To tag) # CANCEL processing if (is_method("CANCEL")) { if (t_check_trans()) t_relay(); exit; } t_check_trans(); # authentication route(AUTH); # record routing for dialog forming requests (in case they are
routed) # - remove preloaded route headers remove_hf("Route"); if (is_method("INVITE")) record_route();
# handle registrations route(REGISTRAR); if ($rU==$null) { # request with no Username in RURI sl_send_reply("484","Address Incomplete"); exit; } # user location service route(LOCATION); route(RELAY);
}
route[RELAY] { if (!t_relay()) { sl_reply_error(); } exit; }
# Per SIP request initial checks route[REQINIT] { if (!mf_process_maxfwd_header("10")) { sl_send_reply("483","Too Many Hops"); exit; }
if(!sanity_check("1511", "7")) { xlog("Malformed SIP message from $si:$sp\n"); exit; }
}
# Handle requests within SIP dialogs route[WITHINDLG] { if (has_totag()) { # sequential request withing a dialog should # take the path determined by record-routing if (loose_route()) { #!ifdef WITH_WEBSOCKETS if ($du == "") { if (!handle_ruri_alias()) { xlog("L_ERR", "Bad alias <$ru>\n"); sl_send_reply("400", "Bad Request"); exit; } } #!endif route(RELAY); } else { if ( is_method("ACK") ) { if ( t_check_trans() ) { # no loose-route, but stateful ACK; # must be an ACK after a 487 # or e.g. 404 from upstream server t_relay(); exit; } else { # ACK without matching transaction... # ignore and discard exit; } } sl_send_reply("404","Not here"); } exit; } }
# Handle SIP registrations route[REGISTRAR] { if (is_method("REGISTER")) { if (!save("location")) sl_reply_error();
exit; }
}
# USER location service route[LOCATION] { if (!lookup("location")) { $var(rc) = $rc; t_newtran(); switch ($var(rc)) { case -1: case -3: send_reply("404", "Not Found"); exit; case -2: send_reply("405", "Method Not Allowed"); exit; } } }
# Authentication route route[AUTH] { if (is_method("REGISTER") || from_uri==myself) { # authenticate requests if (!auth_check("$fd", "subscriber", "1")) { auth_challenge("$fd", "0"); exit; } # user authenticated - remove auth header if(!is_method("REGISTER")) consume_credentials(); } # if caller is not local subscriber, then check if it calls # a local destination, otherwise deny, not an open relay here if (from_uri!=myself && uri!=myself) { sl_send_reply("403","Not relaying"); exit; } }
#!ifdef WITH_WEBSOCKETS onreply_route { if (nat_uac_test(64)) { # Do NAT traversal stuff for replies to a WebSocket connection # - even if it is not behind a NAT! # This won't be needed in the future if Kamailio and the # WebSocket client support Outbound and Path. add_contact_alias(); } }
event_route[xhttp:request] { set_reply_close(); set_reply_no_connect();
if ($Rp != MY_WS_PORT && $Rp != MY_WSS_PORT) { xlog("L_WARN", "HTTP request received on $Rp\n"); xhttp_reply("403", "Forbidden", "", ""); exit; } xlog("L_DBG", "HTTP Request Received\n"); if ($hdr(Upgrade)=~"websocket" && $hdr(Connection)=~"Upgrade" && $rm=~"GET") { xlog("L_DBG", "WebSocket\n"); xlog("L_DBG", " Host: $hdr(Host)\n"); xlog("L_DBG", " Origin: $hdr(Origin)\n"); if ($hdr(Host) == $null || !is_myself($hdr(Host))) { xlog("L_WARN", "Bad host $hdr(Host)\n"); xhttp_reply("403", "Forbidden", "", ""); exit; } # Optional... validate Origin # Optional... perform HTTP authentication # ws_handle_handshake() exits (no further configuration
file # processing of the request) when complete. if (ws_handle_handshake()) { # Optional... cache some information abou the # successful connection exit; } }
xhttp_reply("404", "Not found", "", "");
}
event_route[websocket:closed] { xlog("L_INFO", "WebSocket connection from $si:$sp has closed\n"); }
#!endif
-- Juan Carlos Castro y Castro Instant Solutions - Telefonia Gerando Resultado http://www.instant.com.br Principais capitais: 4063-6100 Demais regiões: (11)4063-6100
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
WOOHOO! Thanks for reminding me, I had just forgot to set the port! There was this weird load-balancing with random ports, which I replaced with 80 and it REGISTERED!
Next up: make calls.
On Tue, Aug 14, 2012 at 7:25 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
Also, the port I used for signaling in the call.htm (sipml5) is 5060, not 80. This is because xhttp listens for TCP connections on that port.
Please, keep updating this thread with your test results. I haven't get there yet but whenever I release myself from the plenty of job I have now, I will get back to this and post the results.
Regards.
Carlos.
On Tue, Aug 14, 2012 at 6:49 PM, Juan Castro jcastro@instant.com.br wrote:
WOOHOO! Thanks for reminding me, I had just forgot to set the port! There was this weird load-balancing with random ports, which I replaced with 80 and it REGISTERED!
Next up: make calls.
On Tue, Aug 14, 2012 at 7:25 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
Also, the port I used for signaling in the call.htm (sipml5) is 5060,
not 80. This is because xhttp listens for TCP connections on that port.
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
Yup, i am closely watching websockets development in kamailio. Peter has recently done many changes and fixes in WS code, and right now he and other team members are working on outbound support. This is really exciting.
Meanwhile we can and should continue testing and provide our feedback to kamailio developers so WS support gets more and more mature for final release. So, lets keep this thread alive. :-)
Thank you.
On Wed, Aug 15, 2012 at 1:20 AM, Carlos Ruiz Díaz <carlos.ruizdiaz@gmail.com
wrote:
Please, keep updating this thread with your test results. I haven't get there yet but whenever I release myself from the plenty of job I have now, I will get back to this and post the results.
Regards.
Carlos.
On Tue, Aug 14, 2012 at 6:49 PM, Juan Castro jcastro@instant.com.brwrote:
WOOHOO! Thanks for reminding me, I had just forgot to set the port! There was this weird load-balancing with random ports, which I replaced with 80 and it REGISTERED!
Next up: make calls.
On Tue, Aug 14, 2012 at 7:25 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
Also, the port I used for signaling in the call.htm (sipml5) is 5060,
not 80. This is because xhttp listens for TCP connections on that port.
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
Meanwhile we can and should continue testing and provide our feedback to kamailio developers so WS support gets more and more mature for final release. So, lets keep this thread alive. :-)
Unfortunately the developers in the project gets all the attention, but testing and writing documentation is equally important. In Kamailio.org we do rely on our community to help us with testing of all new features and keep reporting bugs. You are the real important people.
...especially if you also help fund the work done by developers ;-)
/O
OK. Strange behavior trying to make a call. When an EyeBeam extension tries to call a sipml5 extension, the right part of the sipml5 window pops with a live camera thumbnail, but there's no way to answer, and no indication of who's calling. EyeBeam keeps showing "Calling..."
Trying to make calls FROM sipml5 just doesn't go through.
Will gather more information shortly.
On Wed, Aug 15, 2012 at 3:58 AM, Olle E. Johansson oej@edvina.net wrote:
Meanwhile we can and should continue testing and provide our feedback to kamailio developers so WS support gets more and more mature for final release. So, lets keep this thread alive. :-)
Unfortunately the developers in the project gets all the attention, but testing and writing documentation is equally important. In Kamailio.org we do rely on our community to help us with testing of all new features and keep reporting bugs. You are the real important people.
...especially if you also help fund the work done by developers ;-)
/O _______________________________________________ sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
Please enlighten me on this "outbound support" you speak of. Does that mean web agents can't yet receive calls? Or make them? Or something else entirely?
On Tue, Aug 14, 2012 at 9:20 PM, Muhammad Shahzad shaheryarkh@googlemail.com wrote:
Yup, i am closely watching websockets development in kamailio. Peter has recently done many changes and fixes in WS code, and right now he and other team members are working on outbound support. This is really exciting.
Meanwhile we can and should continue testing and provide our feedback to kamailio developers so WS support gets more and more mature for final release. So, lets keep this thread alive. :-)
Thank you.
On Wed, Aug 15, 2012 at 1:20 AM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
Please, keep updating this thread with your test results. I haven't get there yet but whenever I release myself from the plenty of job I have now, I will get back to this and post the results.
Regards.
On Tue, Aug 14, 2012 at 6:49 PM, Juan Castro jcastro@instant.com.br wrote:
WOOHOO! Thanks for reminding me, I had just forgot to set the port! There was this weird load-balancing with random ports, which I replaced with 80 and it REGISTERED!
Next up: make calls.
Its something else entirely.
http://datatracker.ietf.org/doc/draft-ietf-sip-outbound/
Thank you.
On Wed, Aug 15, 2012 at 2:43 PM, Juan Castro jcastro@instant.com.br wrote:
Please enlighten me on this "outbound support" you speak of. Does that mean web agents can't yet receive calls? Or make them? Or something else entirely?
On Tue, Aug 14, 2012 at 9:20 PM, Muhammad Shahzad shaheryarkh@googlemail.com wrote:
Yup, i am closely watching websockets development in kamailio. Peter has
recently done many changes and fixes in WS code, and right now he and other team members are working on outbound support. This is really exciting.
Meanwhile we can and should continue testing and provide our feedback to
kamailio developers so WS support gets more and more mature for final release. So, lets keep this thread alive. :-)
Thank you.
On Wed, Aug 15, 2012 at 1:20 AM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
Please, keep updating this thread with your test results. I haven't get
there yet but whenever I release myself from the plenty of job I have now, I will get back to this and post the results.
Regards.
On Tue, Aug 14, 2012 at 6:49 PM, Juan Castro jcastro@instant.com.br
wrote:
WOOHOO! Thanks for reminding me, I had just forgot to set the port!
There was this weird load-balancing with random ports, which I replaced with 80 and it REGISTERED!
Next up: make calls.
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
I see no logs in /var/log/messages other than Kamailio's startup blah-blah. (I'm using CentOS 6.) Where do the xlog() messages go to?
On Tue, Aug 14, 2012 at 7:25 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
There's probably an error reported in kamaiio's log file so you should check it out. In my case, I had to comment out parts of the script that were in charge of doing some validation, particularly, in event_route[xhttp:request].
Everything goes to /var/log/messages unless you explicitly change it. Try enabling debug with #!define WITH_DEBUG
On Thu, Aug 16, 2012 at 9:11 AM, Juan Castro jcastro@instant.com.br wrote:
I see no logs in /var/log/messages other than Kamailio's startup blah-blah. (I'm using CentOS 6.) Where do the xlog() messages go to?
On Tue, Aug 14, 2012 at 7:25 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
There's probably an error reported in kamaiio's log file so you should
check it out. In my case, I had to comment out parts of the script that were in charge of doing some validation, particularly, in
event_route[xhttp:request].
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
By default all logs goto, syslog facility local0. So either,
1. Your syslog daemon is configured to write local0 logs to some different file.
OR
2. You have set log level in kamailio too low, i think log level 0 disables almost all logs. Set it to 4 for debug.
Thank you.
On Thu, Aug 16, 2012 at 3:11 PM, Juan Castro jcastro@instant.com.br wrote:
I see no logs in /var/log/messages other than Kamailio's startup blah-blah. (I'm using CentOS 6.) Where do the xlog() messages go to?
On Tue, Aug 14, 2012 at 7:25 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
There's probably an error reported in kamaiio's log file so you should
check it out. In my case, I had to comment out parts of the script that were in charge of doing some validation, particularly, in
event_route[xhttp:request].
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
There's no "#!ifdef WITH_DEBUG" anywhere, and I have "debug=9" which didn't do any changes. Here's my current config:
------------------------------------------------------------ #!KAMAILIO # # Simple/sample kamailio.cfg for running a proxy/registrar with TLS and # WebSockets support.
#!substdef "!DBURL!mysql://ser:heslo@localhost/ser!g" #!substdef "!MY_IP_ADDR!192.168.0.148!g" #!substdef "!MY_WS_PORT!80!g" #!substdef "!MY_WSS_PORT!443!g" #!substdef "!MY_WS_ADDR!tcp:MY_IP_ADDR:MY_WS_PORT!g" #!substdef "!MY_WSS_ADDR!tls:MY_IP_ADDR:MY_WSS_PORT!g"
##!define LOCAL_TEST_RUN #!define WITH_TLS #!define WITH_WEBSOCKETS
####### Global Parameters #########
fork=yes children=4
#alias="example.com"
#!ifdef WITH_TLS enable_tls=1 #!endif
listen=MY_IP_ADDR #!ifdef WITH_WEBSOCKETS listen=MY_WS_ADDR #!ifdef WITH_TLS listen=MY_WSS_ADDR #!endif #!endif
tcp_connection_lifetime=3604 tcp_accept_no_cl=yes tcp_rd_buf_size=16384
syn_branch=0 debug=9
# set paths to location of modules (to sources or installation folders) #!ifdef WITH_SRCPATH mpath="modules_k:modules" #!else mpath="/usr/local/kamailio/lib/kamailio/modules_k/:/usr/local/kamailio/lib/kamailio/modules/" #!endif
loadmodule "db_mysql.so" loadmodule "mi_fifo.so" loadmodule "tm.so" loadmodule "sl.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "maxfwd.so" loadmodule "usrloc.so" loadmodule "registrar.so" loadmodule "textops.so" loadmodule "siputils.so" loadmodule "xlog.so" loadmodule "sanity.so" loadmodule "ctl.so" loadmodule "auth.so" loadmodule "auth_db.so" loadmodule "kex.so" loadmodule "mi_rpc.so" #!ifdef WITH_TLS loadmodule "tls.so" #!endif #!ifdef WITH_WEBSOCKETS loadmodule "xhttp.so" loadmodule "websocket.so" loadmodule "nathelper.so" #!endif
# ----------------- setting module-specific parameters ---------------
# ----- mi_fifo params ----- modparam("mi_fifo", "fifo_name", "/tmp/kamailio_fifo")
# ----- tm params ----- # auto-discard branches from previous serial forking leg modparam("tm", "failure_reply_mode", 3) # default retransmission timeout: 30sec modparam("tm", "fr_timer", 30000) # default invite retransmission timeout after 1xx: 120sec modparam("tm", "fr_inv_timer", 120000)
# ----- rr params ----- # add value to ;lr param to cope with most of the UAs modparam("rr", "enable_full_lr", 1) # do not append from tag to the RR (no need for this script) modparam("rr", "append_fromtag", 0)
# ----- registrar params ----- modparam("registrar", "method_filtering", 1) modparam("registrar", "max_expires", 3600) modparam("registrar", "gruu_enabled", 0)
# ----- usrloc params ----- modparam("usrloc", "db_url", "DBURL") modparam("usrloc", "db_mode", 0)
# ----- auth_db params ----- modparam("auth_db", "db_url", "DBURL") modparam("auth_db", "calculate_ha1", yes) modparam("auth_db", "password_column", "password") modparam("auth_db", "load_credentials", "")
#!ifdef WITH_TLS # ----- tls params ----- modparam("tls", "config", "/usr/local/kamailio/etc/kamailio/tls.cfg") #!endif
#!ifdef WITH_WEBSOCKETS # ----- nathelper params ----- modparam("nathelper|registrar", "received_avp", "$avp(RECEIVED)") # Note: leaving NAT pings turned off here as nathelper is _only_ being used for # WebSocket connections. NAT pings are not needed as WebSockets have # their own keep-alives. #!endif
####### Routing Logic ########
# Main SIP request routing logic # - processing of any incoming SIP request starts with this route # - note: this is the same as route { ... } request_route {
# per request initial checks route(REQINIT);
#!ifdef WITH_WEBSOCKETS if (nat_uac_test(64)) { # Do NAT traversal stuff for requests from a WebSocket # connection - even if it is not behind a NAT! # This won't be needed in the future if Kamailio and the # WebSocket client support Outbound and Path. force_rport(); if (is_method("REGISTER")) fix_nated_register(); else { if (!add_contact_alias()) { xlog("L_ERR", "Error aliasing contact <$ct>\n"); sl_send_reply("400", "Bad Request"); exit; } } } #!endif
# handle requests within SIP dialogs route(WITHINDLG);
### only initial requests (no To tag)
# CANCEL processing if (is_method("CANCEL")) { if (t_check_trans()) t_relay(); exit; }
t_check_trans();
# authentication route(AUTH);
# record routing for dialog forming requests (in case they are routed) # - remove preloaded route headers remove_hf("Route"); if (is_method("INVITE")) record_route();
# handle registrations route(REGISTRAR);
if ($rU==$null) { # request with no Username in RURI sl_send_reply("484","Address Incomplete"); exit; }
# user location service route(LOCATION);
route(RELAY); }
route[RELAY] { if (!t_relay()) { sl_reply_error(); } exit; }
# Per SIP request initial checks route[REQINIT] { if (!mf_process_maxfwd_header("10")) { sl_send_reply("483","Too Many Hops"); exit; }
if(!sanity_check("1511", "7")) { xlog("Malformed SIP message from $si:$sp\n"); exit; } }
# Handle requests within SIP dialogs route[WITHINDLG] { if (has_totag()) { # sequential request withing a dialog should # take the path determined by record-routing if (loose_route()) { #!ifdef WITH_WEBSOCKETS if ($du == "") { if (!handle_ruri_alias()) { xlog("L_ERR", "Bad alias <$ru>\n"); sl_send_reply("400", "Bad Request"); exit; } } #!endif route(RELAY); } else { if ( is_method("ACK") ) { if ( t_check_trans() ) { # no loose-route, but stateful ACK; # must be an ACK after a 487 # or e.g. 404 from upstream server t_relay(); exit; } else { # ACK without matching transaction... # ignore and discard exit; } } sl_send_reply("404","Not here"); } exit; } }
# Handle SIP registrations route[REGISTRAR] { if (is_method("REGISTER")) { if (!save("location")) sl_reply_error();
exit; } }
# USER location service route[LOCATION] { if (!lookup("location")) { $var(rc) = $rc; t_newtran(); switch ($var(rc)) { case -1: case -3: send_reply("404", "Not Found"); exit; case -2: send_reply("405", "Method Not Allowed"); exit; } } }
# Authentication route route[AUTH] { if (is_method("REGISTER") || from_uri==myself) { # authenticate requests if (!auth_check("$fd", "subscriber", "1")) { auth_challenge("$fd", "0"); exit; } # user authenticated - remove auth header if(!is_method("REGISTER")) consume_credentials(); } # if caller is not local subscriber, then check if it calls # a local destination, otherwise deny, not an open relay here if (from_uri!=myself && uri!=myself) { sl_send_reply("403","Not relaying"); exit; } }
#!ifdef WITH_WEBSOCKETS onreply_route { if (nat_uac_test(64)) { # Do NAT traversal stuff for replies to a WebSocket connection # - even if it is not behind a NAT! # This won't be needed in the future if Kamailio and the # WebSocket client support Outbound and Path. add_contact_alias(); } }
event_route[xhttp:request] { set_reply_close(); set_reply_no_connect(); # if ($Rp != MY_WS_PORT && $Rp != MY_WSS_PORT) { # xlog("L_WARN", "HTTP request received on $Rp\n"); # xhttp_reply("403", "Forbidden", "", ""); # exit; # }
xlog("L_DBG", "HTTP Request Received\n");
if ($hdr(Upgrade)=~"websocket" && $hdr(Connection)=~"Upgrade" && $rm=~"GET") { xlog("L_DBG", "WebSocket\n"); xlog("L_DBG", " Host: $hdr(Host)\n"); xlog("L_DBG", " Origin: $hdr(Origin)\n");
# if ($hdr(Host) == $null || !is_myself($hdr(Host))) { # xlog("L_WARN", "Bad host $hdr(Host)\n"); # xhttp_reply("403", "Forbidden", "", ""); # exit; # }
# Optional... validate Origin # Optional... perform HTTP authentication
# ws_handle_handshake() exits (no further configuration file # processing of the request) when complete. if (ws_handle_handshake()) { # Optional... cache some information abou the # successful connection exit; } }
xhttp_reply("404", "Not found", "", ""); }
event_route[websocket:closed] { xlog("L_INFO", "WebSocket connection from $si:$sp has closed\n"); } #!endif ------------------------------------------------------------
On Thu, Aug 16, 2012 at 10:48 AM, Muhammad Shahzad shaheryarkh@googlemail.com wrote:
By default all logs goto, syslog facility local0. So either,
- Your syslog daemon is configured to write local0 logs to some different
file.
OR
- You have set log level in kamailio too low, i think log level 0 disables
almost all logs. Set it to 4 for debug.
Thank you.
On Thu, Aug 16, 2012 at 3:11 PM, Juan Castro jcastro@instant.com.br wrote:
I see no logs in /var/log/messages other than Kamailio's startup blah-blah. (I'm using CentOS 6.) Where do the xlog() messages go to?
On Tue, Aug 14, 2012 at 7:25 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
There's probably an error reported in kamaiio's log file so you should check it out. In my case, I had to comment out parts of the script that were in charge of doing some validation, particularly, in event_route[xhttp:request].
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
-- Muhammad Shahzad
CISCO Rich Media Communication Specialist (CRMCS) CISCO Certified Network Associate (CCNA) Cell: +92 334 422 40 88 MSN: shari_786pk@hotmail.com Email: shaheryarkh@googlemail.com
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
You should be seeing at least the output of "xlog("L_DBG", "HTTP Request Received\n");", if not, there's no http request arriving to the server. Try flushing iptables and disabling selinux.
On Thu, Aug 16, 2012 at 10:03 AM, Juan Castro jcastro@instant.com.brwrote:
There's no "#!ifdef WITH_DEBUG" anywhere, and I have "debug=9" which didn't do any changes. Here's my current config:
#!KAMAILIO # # Simple/sample kamailio.cfg for running a proxy/registrar with TLS and # WebSockets support.
#!substdef "!DBURL!mysql://ser:heslo@localhost/ser!g" #!substdef "!MY_IP_ADDR!192.168.0.148!g" #!substdef "!MY_WS_PORT!80!g" #!substdef "!MY_WSS_PORT!443!g" #!substdef "!MY_WS_ADDR!tcp:MY_IP_ADDR:MY_WS_PORT!g" #!substdef "!MY_WSS_ADDR!tls:MY_IP_ADDR:MY_WSS_PORT!g"
##!define LOCAL_TEST_RUN #!define WITH_TLS #!define WITH_WEBSOCKETS
####### Global Parameters #########
fork=yes children=4
#alias="example.com"
#!ifdef WITH_TLS enable_tls=1 #!endif
listen=MY_IP_ADDR #!ifdef WITH_WEBSOCKETS listen=MY_WS_ADDR #!ifdef WITH_TLS listen=MY_WSS_ADDR #!endif #!endif
tcp_connection_lifetime=3604 tcp_accept_no_cl=yes tcp_rd_buf_size=16384
syn_branch=0 debug=9
# set paths to location of modules (to sources or installation folders) #!ifdef WITH_SRCPATH mpath="modules_k:modules" #!else
mpath="/usr/local/kamailio/lib/kamailio/modules_k/:/usr/local/kamailio/lib/kamailio/modules/" #!endif
loadmodule "db_mysql.so" loadmodule "mi_fifo.so" loadmodule "tm.so" loadmodule "sl.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "maxfwd.so" loadmodule "usrloc.so" loadmodule "registrar.so" loadmodule "textops.so" loadmodule "siputils.so" loadmodule "xlog.so" loadmodule "sanity.so" loadmodule "ctl.so" loadmodule "auth.so" loadmodule "auth_db.so" loadmodule "kex.so" loadmodule "mi_rpc.so" #!ifdef WITH_TLS loadmodule "tls.so" #!endif #!ifdef WITH_WEBSOCKETS loadmodule "xhttp.so" loadmodule "websocket.so" loadmodule "nathelper.so" #!endif
# ----------------- setting module-specific parameters ---------------
# ----- mi_fifo params ----- modparam("mi_fifo", "fifo_name", "/tmp/kamailio_fifo")
# ----- tm params ----- # auto-discard branches from previous serial forking leg modparam("tm", "failure_reply_mode", 3) # default retransmission timeout: 30sec modparam("tm", "fr_timer", 30000) # default invite retransmission timeout after 1xx: 120sec modparam("tm", "fr_inv_timer", 120000)
# ----- rr params ----- # add value to ;lr param to cope with most of the UAs modparam("rr", "enable_full_lr", 1) # do not append from tag to the RR (no need for this script) modparam("rr", "append_fromtag", 0)
# ----- registrar params ----- modparam("registrar", "method_filtering", 1) modparam("registrar", "max_expires", 3600) modparam("registrar", "gruu_enabled", 0)
# ----- usrloc params ----- modparam("usrloc", "db_url", "DBURL") modparam("usrloc", "db_mode", 0)
# ----- auth_db params ----- modparam("auth_db", "db_url", "DBURL") modparam("auth_db", "calculate_ha1", yes) modparam("auth_db", "password_column", "password") modparam("auth_db", "load_credentials", "")
#!ifdef WITH_TLS # ----- tls params ----- modparam("tls", "config", "/usr/local/kamailio/etc/kamailio/tls.cfg") #!endif
#!ifdef WITH_WEBSOCKETS # ----- nathelper params ----- modparam("nathelper|registrar", "received_avp", "$avp(RECEIVED)") # Note: leaving NAT pings turned off here as nathelper is _only_ being used for # WebSocket connections. NAT pings are not needed as WebSockets have # their own keep-alives. #!endif
####### Routing Logic ########
# Main SIP request routing logic # - processing of any incoming SIP request starts with this route # - note: this is the same as route { ... } request_route {
# per request initial checks route(REQINIT);
#!ifdef WITH_WEBSOCKETS if (nat_uac_test(64)) { # Do NAT traversal stuff for requests from a WebSocket # connection - even if it is not behind a NAT! # This won't be needed in the future if Kamailio and the # WebSocket client support Outbound and Path. force_rport(); if (is_method("REGISTER")) fix_nated_register(); else { if (!add_contact_alias()) { xlog("L_ERR", "Error aliasing contact <$ct>\n"); sl_send_reply("400", "Bad Request"); exit; } } } #!endif
# handle requests within SIP dialogs route(WITHINDLG); ### only initial requests (no To tag) # CANCEL processing if (is_method("CANCEL")) { if (t_check_trans()) t_relay(); exit; } t_check_trans(); # authentication route(AUTH); # record routing for dialog forming requests (in case they are
routed) # - remove preloaded route headers remove_hf("Route"); if (is_method("INVITE")) record_route();
# handle registrations route(REGISTRAR); if ($rU==$null) { # request with no Username in RURI sl_send_reply("484","Address Incomplete"); exit; } # user location service route(LOCATION); route(RELAY);
}
route[RELAY] { if (!t_relay()) { sl_reply_error(); } exit; }
# Per SIP request initial checks route[REQINIT] { if (!mf_process_maxfwd_header("10")) { sl_send_reply("483","Too Many Hops"); exit; }
if(!sanity_check("1511", "7")) { xlog("Malformed SIP message from $si:$sp\n"); exit; }
}
# Handle requests within SIP dialogs route[WITHINDLG] { if (has_totag()) { # sequential request withing a dialog should # take the path determined by record-routing if (loose_route()) { #!ifdef WITH_WEBSOCKETS if ($du == "") { if (!handle_ruri_alias()) { xlog("L_ERR", "Bad alias <$ru>\n"); sl_send_reply("400", "Bad Request"); exit; } } #!endif route(RELAY); } else { if ( is_method("ACK") ) { if ( t_check_trans() ) { # no loose-route, but stateful ACK; # must be an ACK after a 487 # or e.g. 404 from upstream server t_relay(); exit; } else { # ACK without matching transaction... # ignore and discard exit; } } sl_send_reply("404","Not here"); } exit; } }
# Handle SIP registrations route[REGISTRAR] { if (is_method("REGISTER")) { if (!save("location")) sl_reply_error();
exit; }
}
# USER location service route[LOCATION] { if (!lookup("location")) { $var(rc) = $rc; t_newtran(); switch ($var(rc)) { case -1: case -3: send_reply("404", "Not Found"); exit; case -2: send_reply("405", "Method Not Allowed"); exit; } } }
# Authentication route route[AUTH] { if (is_method("REGISTER") || from_uri==myself) { # authenticate requests if (!auth_check("$fd", "subscriber", "1")) { auth_challenge("$fd", "0"); exit; } # user authenticated - remove auth header if(!is_method("REGISTER")) consume_credentials(); } # if caller is not local subscriber, then check if it calls # a local destination, otherwise deny, not an open relay here if (from_uri!=myself && uri!=myself) { sl_send_reply("403","Not relaying"); exit; } }
#!ifdef WITH_WEBSOCKETS onreply_route { if (nat_uac_test(64)) { # Do NAT traversal stuff for replies to a WebSocket connection # - even if it is not behind a NAT! # This won't be needed in the future if Kamailio and the # WebSocket client support Outbound and Path. add_contact_alias(); } }
event_route[xhttp:request] { set_reply_close(); set_reply_no_connect();
# if ($Rp != MY_WS_PORT && $Rp != MY_WSS_PORT) { # xlog("L_WARN", "HTTP request received on $Rp\n"); # xhttp_reply("403", "Forbidden", "", ""); # exit; # }
xlog("L_DBG", "HTTP Request Received\n"); if ($hdr(Upgrade)=~"websocket" && $hdr(Connection)=~"Upgrade" && $rm=~"GET") { xlog("L_DBG", "WebSocket\n"); xlog("L_DBG", " Host: $hdr(Host)\n"); xlog("L_DBG", " Origin: $hdr(Origin)\n");
# if ($hdr(Host) == $null || !is_myself($hdr(Host))) { # xlog("L_WARN", "Bad host $hdr(Host)\n"); # xhttp_reply("403", "Forbidden", "", ""); # exit; # }
# Optional... validate Origin # Optional... perform HTTP authentication # ws_handle_handshake() exits (no further configuration
file # processing of the request) when complete. if (ws_handle_handshake()) { # Optional... cache some information abou the # successful connection exit; } }
xhttp_reply("404", "Not found", "", "");
}
event_route[websocket:closed] { xlog("L_INFO", "WebSocket connection from $si:$sp has closed\n"); }
#!endif
On Thu, Aug 16, 2012 at 10:48 AM, Muhammad Shahzad shaheryarkh@googlemail.com wrote:
By default all logs goto, syslog facility local0. So either,
- Your syslog daemon is configured to write local0 logs to some
different
file.
OR
- You have set log level in kamailio too low, i think log level 0
disables
almost all logs. Set it to 4 for debug.
Thank you.
On Thu, Aug 16, 2012 at 3:11 PM, Juan Castro jcastro@instant.com.br
wrote:
I see no logs in /var/log/messages other than Kamailio's startup blah-blah. (I'm using CentOS 6.) Where do the xlog() messages go to?
On Tue, Aug 14, 2012 at 7:25 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
There's probably an error reported in kamaiio's log file so you should check it out. In my case, I had to comment out parts of the script
that were
in charge of doing some validation, particularly, in event_route[xhttp:request].
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
-- Muhammad Shahzad
CISCO Rich Media Communication Specialist (CRMCS) CISCO Certified Network Associate (CCNA) Cell: +92 334 422 40 88 MSN: shari_786pk@hotmail.com Email: shaheryarkh@googlemail.com
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
-- Juan Carlos Castro y Castro Instant Solutions - Telefonia Gerando Resultado http://www.instant.com.br Principais capitais: 4063-6100 Demais regiões: (11)4063-6100
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
local0.* wasn't routed to anything in rsyslog.conf. I enabled it and now I get a message when I logOUT, but none when I logIN. The message I get is "WebSocket connection from 192.168.0.92:1273 has closed". It's in event_route[websocket:closed].
It seems it's only logging L_INFO and not L_DBG. I changed the from the latter to the former and now it shows. Again, I had debug=9. Bug in the log system?
On Thu, Aug 16, 2012 at 11:08 AM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
You should be seeing at least the output of "xlog("L_DBG", "HTTP Request Received\n");", if not, there's no http request arriving to the server. Try flushing iptables and disabling selinux.
humm, interesting.
Can you try to put following on top of your config?
--- debug=4 log_stderror=no
memdbg=5 memlog=5
log_facility=LOG_LOCAL0
fork=yes children=4 ---
Make sure you don't override these variable in later config. This should work, if not something is really wrong with your installation.
Thank you.
On Thu, Aug 16, 2012 at 4:27 PM, Juan Castro jcastro@instant.com.br wrote:
local0.* wasn't routed to anything in rsyslog.conf. I enabled it and now I get a message when I logOUT, but none when I logIN. The message I get is "WebSocket connection from 192.168.0.92:1273 has closed". It's in event_route[websocket:closed].
It seems it's only logging L_INFO and not L_DBG. I changed the from the latter to the former and now it shows. Again, I had debug=9. Bug in the log system?
On Thu, Aug 16, 2012 at 11:08 AM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
You should be seeing at least the output of "xlog("L_DBG", "HTTP Request
Received\n");", if not, there's no http request arriving to the server. Try flushing iptables and disabling selinux.
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
Hoo-hah. Now I get a truckload of messages. Thanks!
On Thu, Aug 16, 2012 at 1:07 PM, Muhammad Shahzad shaheryarkh@googlemail.com wrote:
humm, interesting.
Can you try to put following on top of your config?
debug=4 log_stderror=no
memdbg=5 memlog=5
log_facility=LOG_LOCAL0
fork=yes children=4
Make sure you don't override these variable in later config. This should work, if not something is really wrong with your installation.
Thank you.
Progress! Now, I get an "Unsupported media type" message in the sipml5 page when I try to make a call, which is a much more polite behavior. Maybe I just have to enable some codec in the other softphone? Here's the log.
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x8253ea0, 28, -1, 0x0) fd_no=22 called Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:4296]: tcp: DBG: sending to child, events 1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:3967]: selected tcp worker 3 13(6718) for activity on [tcp:192.168.0.148:80], 0xb559f770 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1503]: received n=4 con=0xb559f770, fd=9 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:497]: Rx (decoded): INVITE sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Contact: "Web Ext 2003"sip:default_2003@df7jal23ls0d.invalid;transport=ws;+sip.ice#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 2989#015#012Max-Forwards: 70#015#012User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4 127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13 126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4 189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702 2 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ host generation 0#015#012a=candidate:2173160166 2 tcp 1694506751 192.168.0.92 3303 typ host generation 0#015#012a=ice-ufrag:zUOb+h3ouRkd/QYd#015#012a=ice-pwd:kyZNFOSaqMo0cv4hP6+gsbeO#015#012a=sendrecv#015#012a=mid:audio#015#012a=rtcp- Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012INVITE sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Contact: "Web Ext 2003"sip:default_2003@df7jal23ls0d.invalid;transport=ws;+sip.ice#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 2989#015#012Max-Forwards: 70#015#012User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4 127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13 126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4 189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702 2 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ host generation 0#015#012a=candidate:2173160166 2 tcp 1694506751 192.168.0.92 3303 typ host generation 0#015#012a=ice-ufrag:zUOb+h3ouRkd/QYd#015#012a=ice-pwd:kyZNFOSaqMo0cv4hP6+gsbeO#015#012a=sendrecv#015#012a=mid:audio#015#012a= Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:623]: SIP Request: Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:625]: method: <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:627]: uri: sip:default_2002@192.168.0.148 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:629]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <n/a>; state=17 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [34]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148#015#012] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56775> <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body : content_length=2989 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:106]: found end of header Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=iWkTlWnsvaUNzW1reSya Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: nathelper [nathelper.c:831]: contact uri host is not an ip address Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: nathelper [nathelper.c:904]: adding param <;alias=192.168.0.92~3290~5> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: siputils [checks.c:103]: no totag Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffff Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=34648, isACK=0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=1 global id=1 T end=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if host==us: 13==13 && [192.168.0.148] == [192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth_db [authorize.c:363]: realm [192.168.0.148] table [subscriber] flags [1] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [api.c:86]: auth:pre_auth: Credentials with realm '192.168.0.148' not found Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth_db [authorize.c:180]: no credentials Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [challenge.c:128]: build_challenge_hf: realm='192.168.0.148' Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [challenge.c:270]: auth: 'Proxy-Authenticate: Digest realm="192.168.0.148", nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012' Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: sl [sl.c:278]: reply in stateless mode (sl) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92, df7jal23ls0d.invalid, 0) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 407 Proxy Authentication Required#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport=3290;received=192.168.0.92#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 INVITE#015#012Proxy-Authenticate: Digest realm="192.168.0.148", nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012Server: kamailio (3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2314]: tcp_send: send from reader (6718 (13)), reusing fd Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c= 0xb559f770 n=534 fd=9 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#002#022SIP/2.0 407 Proxy Authentication Required#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport=3290;received=192.168.0.92#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 INVITE#015#012Proxy-Authenticate: Digest realm="192.168.0.148", nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012Server: kamailio (3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x82760e0, 9, 2, 0xb559f770), fd_no=1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:497]: Rx (decoded): ACK sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012ACK sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:623]: SIP Request: Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:625]: method: <ACK> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:627]: uri: sip:default_2002@192.168.0.148 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:629]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <n/a>; state=17 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [76]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: sl [sl_funcs.c:415]: SL local ACK found -> dropping it! Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:497]: Rx (decoded): INVITE sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Contact: "Web Ext 2003"sip:default_2003@df7jal23ls0d.invalid;transport=ws;+sip.ice#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 2989#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest username="default_2003",realm="192.168.0.148",nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1",uri="sip:default_2002@192.168.0.148",response="30649c7abfef028651393486fdc21cb3",algorithm=MD5#015#012User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4 127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13 126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4 189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702 2 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ host generation 0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012INVITE sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Contact: "Web Ext 2003"sip:default_2003@df7jal23ls0d.invalid;transport=ws;+sip.ice#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 2989#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest username="default_2003",realm="192.168.0.148",nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1",uri="sip:default_2002@192.168.0.148",response="30649c7abfef028651393486fdc21cb3",algorithm=MD5#015#012User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4 127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13 126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4 189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702 2 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ host generat Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:623]: SIP Request: Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:625]: method: <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:627]: uri: sip:default_2002@192.168.0.148 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:629]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <n/a>; state=17 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [34]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148#015#012] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56776> <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body : content_length=2989 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:106]: found end of header Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=iWkTlWnsvaUNzW1reSya Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: nathelper [nathelper.c:831]: contact uri host is not an ip address Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: nathelper [nathelper.c:904]: adding param <;alias=192.168.0.92~3290~5> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: siputils [checks.c:103]: no totag Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=3 global id=1 T start=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=34645, isACK=0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=3 global id=3 T end=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if host==us: 13==13 && [192.168.0.148] == [192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth_db [authorize.c:363]: realm [192.168.0.148] table [subscriber] flags [1] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [api.c:96]: auth: digest-algo: MD5 parsed value: 1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb73cc8c0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:155]: allocate 4 bytes for result names at 0xb73cc7b8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0xb73cc7c8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb73cc7d8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb73cc7d8)[0]=[password] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb73cc7e8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_row.c:119]: allocate 20 bytes for row values at 0xb73cc7f8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_val.c:117]: converting STRING [2003] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth_db [authorize.c:124]: HA1 string calculated: 77b5c108972f5224aa674d91c4ba6fdc Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [api.c:211]: check_response: Our result = '30649c7abfef028651393486fdc21cb3' Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [api.c:218]: check_response: Authorization is OK Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:81]: freeing 1 columns Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb73cc7d8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:94]: freeing result names at 0xb73cc7b8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:99]: freeing result types at 0xb73cc7c8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:54]: freeing 1 rows Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_row.c:97]: freeing row values at 0xb73cc7f8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:62]: freeing rows at 0xb73cc7e8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:136]: freeing result set at 0xb73cc8c0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if host==us: 13==13 && [192.168.0.148] == [192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: registrar [lookup.c:181]: contact for [default_2002] found by address Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=3 , global msg id=3 , T on entrance=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=34645, isACK=0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated: c80d0124b3aeb262f000b4f5d8fe26b5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_funcs.c:351]: SER: new INVITE Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92, df7jal23ls0d.invalid, 0) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 100 trying -- your call is important to us#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290;received=192.168.0.92#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012Server: kamailio (3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2314]: tcp_send: send from reader (6718 (13)), reusing fd Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c= 0xb559f770 n=409 fd=9 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#001�SIP/2.0 100 trying -- your call is important to us#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290;received=192.168.0.92#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012Server: kamailio (3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_reply.c:703]: DEBUG: reply sent out. buf=0xb73ccf80: SIP/2.0 100 trying -..., shmem=0xb559dce8: SIP/2.0 100 trying - Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_reply.c:713]: DEBUG: _reply_light: finished Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92, df7jal23ls0d.invalid, 0) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_funcs.c:388]: SER: new transaction fwd'ed Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:633]: SIP Reply (status): Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:635]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:637]: status: <100> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:639]: reason: <Trying> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bK5578.c80d0124b3aeb262f000b4f5d8fe26b5.0>; state=16 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1 T start=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 234, <received> = <192.168.0.92>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <3290>; state=16 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=22 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:526]: parse_headers: this is the second via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [34]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148#015#012] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56776> <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 34645 label 0 branch 0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xb55a3948)! Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2 T end=0xb55a3948 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=100, new_code=100 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0, relay=-1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:633]: SIP Reply (status): Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:635]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:637]: status: <415> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:639]: reason: <Unsupported Media Type> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bK5578.c80d0124b3aeb262f000b4f5d8fe26b5.0>; state=16 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1 T start=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 234, <received> = <192.168.0.92>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <3290>; state=16 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=22 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:526]: parse_headers: this is the second via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=7145a639 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [47]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56776> <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 34645 label 0 branch 0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xb55a3948)! Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2 T end=0xb55a3948 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=100, new_code=415 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0, relay=0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [msg_translator.c:2009]: old size: 490, new size: 404 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [msg_translator.c:2027]: build_res_from_sip_res: copied size: orig:122, new: 36, rest: 368 msg=#012SIP/2.0 415 Unsupported Media Type#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp 67284#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 415 Unsupported Media Type#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp 67284#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2330]: tcp_send: tcp connection found (0xb559f770), acquiring fd Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:3618]: handle_ser_child: read response= b559f770, 2, fd -1 from 3 (6708) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2341]: tcp_send, c= 0xb559f770, n=8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2371]: tcp_send: after receive_fd: c= 0xb559f770 n=4 fd=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c= 0xb559f770 n=408 fd=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#001�SIP/2.0 415 Unsupported Media Type#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp 67284#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:497]: Rx (decoded): ACK sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012ACK sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:623]: SIP Request: Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:625]: method: <ACK> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:627]: uri: sip:default_2002@192.168.0.148 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:629]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <n/a>; state=17 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=7145a639 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [47]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56776> <ACK> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body : content_length=0 Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x82760e0, 9, -1, 0x10) fd_no=2 called Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1430]: releasing con 0xb559f770, state 1, fd=9, id=1 Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1431]: extra_data (nil) Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:3379]: handle_tcp_child: reader response= b559f770, 1 from 3 Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8253ea0, 28, 2, 0xb559f770), fd_no=21 Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:3509]: handle_tcp_child: CONN_RELEASE 0xb559f770 refcnt= 16 Aug 16 13:36:17 localhost /usr/local/kamailio/sbin/kamailio[6709]: DEBUG: <core> [udp_server.c:498]: udp_rcv_loop: probing packet received from 192.168.0.101 50195
On Thu, Aug 16, 2012 at 1:32 PM, Juan Castro jcastro@instant.com.br wrote:
Hoo-hah. Now I get a truckload of messages. Thanks!
On Thu, Aug 16, 2012 at 1:07 PM, Muhammad Shahzad shaheryarkh@googlemail.com wrote:
humm, interesting.
Can you try to put following on top of your config?
debug=4 log_stderror=no
memdbg=5 memlog=5
log_facility=LOG_LOCAL0
fork=yes children=4
Make sure you don't override these variable in later config. This should work, if not something is really wrong with your installation.
Thank you.
Hi,
There are two major technologies used in sipml5 (and any other HTML5 SIP client): * (SIP over) WebSockets - which is used only for the signalling * RTCWeb - which is used only for the media
The Kamailio WebSockets implementation enables signalling interworking between HTML5 and "ordinary" SIP clients (or gateways, media servers, and so on). However, this does not mean that media will interwork.
The current RTCWeb drafts mandates the RTP/SAVPF media profile - which is only supported by a few SIP clients. Most SIP clients only support RTP/AVP.
It is quite likely that your client just doesn't support RTP/SAVPF.
Regards,
Peter
On Thu, 2012-08-16 at 13:43 -0300, Juan Castro wrote:
Progress! Now, I get an "Unsupported media type" message in the sipml5 page when I try to make a call, which is a much more polite behavior. Maybe I just have to enable some codec in the other softphone? Here's the log.
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x8253ea0, 28, -1, 0x0) fd_no=22 called Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:4296]: tcp: DBG: sending to child, events 1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:3967]: selected tcp worker 3 13(6718) for activity on [tcp:192.168.0.148:80], 0xb559f770 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1503]: received n=4 con=0xb559f770, fd=9 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:497]: Rx (decoded): INVITE sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Contact: "Web Ext 2003"sip:default_2003@df7jal23ls0d.invalid;transport=ws;+sip.ice#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 2989#015#012Max-Forwards: 70#015#012User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4 127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13 126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4 189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702 2 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ host generation 0#015#012a=candidate:2173160166 2 tcp 1694506751 192.168.0.92 3303 typ host generation 0#015#012a=ice-ufrag:zUOb+h3ouRkd/QYd#015#012a=ice-pwd:kyZNFOSaqMo0cv4hP6+gsbeO#015#012a=sendrecv#015#012a=mid:audio#015#012a=rtcp- Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012INVITE sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Contact: "Web Ext 2003"sip:default_2003@df7jal23ls0d.invalid;transport=ws;+sip.ice#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 2989#015#012Max-Forwards: 70#015#012User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4 127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13 126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4 189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702 2 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ host generation 0#015#012a=candidate:2173160166 2 tcp 1694506751 192.168.0.92 3303 typ host generation 0#015#012a=ice-ufrag:zUOb+h3ouRkd/QYd#015#012a=ice-pwd:kyZNFOSaqMo0cv4hP6+gsbeO#015#012a=sendrecv#015#012a=mid:audio#015#012a= Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:623]: SIP Request: Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:625]: method: <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:627]: uri: sip:default_2002@192.168.0.148 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:629]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <n/a>; state=17 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [34]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148#015#012] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56775> <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body : content_length=2989 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:106]: found end of header Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=iWkTlWnsvaUNzW1reSya Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: nathelper [nathelper.c:831]: contact uri host is not an ip address Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: nathelper [nathelper.c:904]: adding param <;alias=192.168.0.92~3290~5> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: siputils [checks.c:103]: no totag Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffff Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=34648, isACK=0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=1 global id=1 T end=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if host==us: 13==13 && [192.168.0.148] == [192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth_db [authorize.c:363]: realm [192.168.0.148] table [subscriber] flags [1] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [api.c:86]: auth:pre_auth: Credentials with realm '192.168.0.148' not found Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth_db [authorize.c:180]: no credentials Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [challenge.c:128]: build_challenge_hf: realm='192.168.0.148' Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [challenge.c:270]: auth: 'Proxy-Authenticate: Digest realm="192.168.0.148", nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012' Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: sl [sl.c:278]: reply in stateless mode (sl) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92, df7jal23ls0d.invalid, 0) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 407 Proxy Authentication Required#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport=3290;received=192.168.0.92#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 INVITE#015#012Proxy-Authenticate: Digest realm="192.168.0.148", nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012Server: kamailio (3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2314]: tcp_send: send from reader (6718 (13)), reusing fd Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c= 0xb559f770 n=534 fd=9 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#002#022SIP/2.0 407 Proxy Authentication Required#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport=3290;received=192.168.0.92#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 INVITE#015#012Proxy-Authenticate: Digest realm="192.168.0.148", nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012Server: kamailio (3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x82760e0, 9, 2, 0xb559f770), fd_no=1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:497]: Rx (decoded): ACK sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012ACK sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775 ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:623]: SIP Request: Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:625]: method: <ACK> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:627]: uri: sip:default_2002@192.168.0.148 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:629]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <n/a>; state=17 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [76]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: sl [sl_funcs.c:415]: SL local ACK found -> dropping it! Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:497]: Rx (decoded): INVITE sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Contact: "Web Ext 2003"sip:default_2003@df7jal23ls0d.invalid;transport=ws;+sip.ice#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 2989#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest username="default_2003",realm="192.168.0.148",nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1",uri="sip:default_2002@192.168.0.148",response="30649c7abfef028651393486fdc21cb3",algorithm=MD5#015#012User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4 127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13 126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4 189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702 2 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ host generation 0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012INVITE sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Contact: "Web Ext 2003"sip:default_2003@df7jal23ls0d.invalid;transport=ws;+sip.ice#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012Content-Type: application/sdp#015#012Content-Length: 2989#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest username="default_2003",realm="192.168.0.148",nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1",uri="sip:default_2002@192.168.0.148",response="30649c7abfef028651393486fdc21cb3",algorithm=MD5#015#012User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4 127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13 126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4 189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966 typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702 2 udp 2130714367 192.168.0.92 60825 typ host generation 0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559 189.24.147.149 60827 typ srflx generation 0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751 169.254.103.29 3302 typ host generation 0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ host generat Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:623]: SIP Request: Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:625]: method: <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:627]: uri: sip:default_2002@192.168.0.148 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:629]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <n/a>; state=17 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [34]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148#015#012] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56776> <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body : content_length=2989 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:106]: found end of header Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=iWkTlWnsvaUNzW1reSya Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: nathelper [nathelper.c:831]: contact uri host is not an ip address Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: nathelper [nathelper.c:904]: adding param <;alias=192.168.0.92~3290~5> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: siputils [checks.c:103]: no totag Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=3 global id=1 T start=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=34645, isACK=0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=3 global id=3 T end=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if host==us: 13==13 && [192.168.0.148] == [192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth_db [authorize.c:363]: realm [192.168.0.148] table [subscriber] flags [1] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [api.c:96]: auth: digest-algo: MD5 parsed value: 1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb73cc8c0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:155]: allocate 4 bytes for result names at 0xb73cc7b8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0xb73cc7c8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb73cc7d8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb73cc7d8)[0]=[password] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb73cc7e8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_row.c:119]: allocate 20 bytes for row values at 0xb73cc7f8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_val.c:117]: converting STRING [2003] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth_db [authorize.c:124]: HA1 string calculated: 77b5c108972f5224aa674d91c4ba6fdc Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [api.c:211]: check_response: Our result = '30649c7abfef028651393486fdc21cb3' Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: auth [api.c:218]: check_response: Authorization is OK Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:81]: freeing 1 columns Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb73cc7d8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:94]: freeing result names at 0xb73cc7b8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:99]: freeing result types at 0xb73cc7c8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:54]: freeing 1 rows Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_row.c:97]: freeing row values at 0xb73cc7f8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:62]: freeing rows at 0xb73cc7e8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [db_res.c:136]: freeing result set at 0xb73cc8c0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if host==us: 13==13 && [192.168.0.148] == [192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port 5060 (advertise 0) matches port 5060 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: registrar [lookup.c:181]: contact for [default_2002] found by address Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=3 , global msg id=3 , T on entrance=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=34645, isACK=0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated: c80d0124b3aeb262f000b4f5d8fe26b5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_funcs.c:351]: SER: new INVITE Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92, df7jal23ls0d.invalid, 0) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 100 trying -- your call is important to us#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290;received=192.168.0.92#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012Server: kamailio (3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2314]: tcp_send: send from reader (6718 (13)), reusing fd Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c= 0xb559f770 n=409 fd=9 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#001�SIP/2.0 100 trying -- your call is important to us#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290;received=192.168.0.92#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012Server: kamailio (3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_reply.c:703]: DEBUG: reply sent out. buf=0xb73ccf80: SIP/2.0 100 trying -..., shmem=0xb559dce8: SIP/2.0 100 trying - Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_reply.c:713]: DEBUG: _reply_light: finished Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92, df7jal23ls0d.invalid, 0) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: tm [t_funcs.c:388]: SER: new transaction fwd'ed Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:633]: SIP Reply (status): Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:635]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:637]: status: <100> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:639]: reason: <Trying> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bK5578.c80d0124b3aeb262f000b4f5d8fe26b5.0>; state=16 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1 T start=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 234, <received> = <192.168.0.92>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <3290>; state=16 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=22 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:526]: parse_headers: this is the second via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [34]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148#015#012] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56776> <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 34645 label 0 branch 0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xb55a3948)! Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2 T end=0xb55a3948 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=100, new_code=100 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0, relay=-1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:633]: SIP Reply (status): Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:635]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:637]: status: <415> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:639]: reason: <Unsupported Media Type> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bK5578.c80d0124b3aeb262f000b4f5d8fe26b5.0>; state=16 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1 T start=(nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 234, <received> = <192.168.0.92>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <3290>; state=16 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=22 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:526]: parse_headers: this is the second via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=7145a639 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [47]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56776> <INVITE> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 34645 label 0 branch 0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xb55a3948)! Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2 T end=0xb55a3948 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=100, new_code=415 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0, relay=0 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [msg_translator.c:2009]: old size: 490, new size: 404 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [msg_translator.c:2027]: build_res_from_sip_res: copied size: orig:122, new: 36, rest: 368 msg=#012SIP/2.0 415 Unsupported Media Type#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp 67284#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 415 Unsupported Media Type#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp 67284#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2330]: tcp_send: tcp connection found (0xb559f770), acquiring fd Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:3618]: handle_ser_child: read response= b559f770, 2, fd -1 from 3 (6708) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2341]: tcp_send, c= 0xb559f770, n=8 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2371]: tcp_send: after receive_fd: c= 0xb559f770 n=4 fd=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c= 0xb559f770 n=408 fd=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#001�SIP/2.0 415 Unsupported Media Type#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp 67284#015#012Content-Length: 0#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers reset Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]: DEBUG: <core> [receive.c:293]: receive_msg: cleaning up Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK) Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:497]: Rx (decoded): ACK sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012ACK sip:default_2002@192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From: sip:default_2003@192.168.0.148;tag=iWkTlWnsvaUNzW1reSya#015#012To: sip:default_2002@192.168.0.148;tag=7145a639#015#012Call-ID: f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776 ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:623]: SIP Request: Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:625]: method: <ACK> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:627]: uri: sip:default_2002@192.168.0.148 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:629]: version: <SIP/2.0> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232, <branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport> = <n/a>; state=17 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_via.c:2672]: end of header reached, state=5 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found, flags=2 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the first via Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:149]: After parse_msg... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [receive.c:190]: preparing to run routing scripts... Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=7145a639 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29 Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To> [47]; uri=[sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body [sip:default_2002@192.168.0.148] Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>: <56776> <ACK> Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body : content_length=0 Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x82760e0, 9, -1, 0x10) fd_no=2 called Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1430]: releasing con 0xb559f770, state 1, fd=9, id=1 Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]: DEBUG: <core> [tcp_read.c:1431]: extra_data (nil) Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:3379]: handle_tcp_child: reader response= b559f770, 1 from 3 Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8253ea0, 28, 2, 0xb559f770), fd_no=21 Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]: DEBUG: <core> [tcp_main.c:3509]: handle_tcp_child: CONN_RELEASE 0xb559f770 refcnt= 16 Aug 16 13:36:17 localhost /usr/local/kamailio/sbin/kamailio[6709]: DEBUG: <core> [udp_server.c:498]: udp_rcv_loop: probing packet received from 192.168.0.101 50195
On Thu, Aug 16, 2012 at 1:32 PM, Juan Castro jcastro@instant.com.br wrote:
Hoo-hah. Now I get a truckload of messages. Thanks!
On Thu, Aug 16, 2012 at 1:07 PM, Muhammad Shahzad shaheryarkh@googlemail.com wrote:
humm, interesting.
Can you try to put following on top of your config?
debug=4 log_stderror=no
memdbg=5 memlog=5
log_facility=LOG_LOCAL0
fork=yes children=4
Make sure you don't override these variable in later config. This should work, if not something is really wrong with your installation.
Thank you.
Juan Castro writes:
Progress! Now, I get an "Unsupported media type" message in the sipml5 page when I try to make a call, which is a much more polite behavior. Maybe I just have to enable some codec in the other softphone? Here's the log.
perhaps it has something to do with this:
http://lists.iptel.org/pipermail/semsdev/2012-July/006904.html
-- juha
You should try http://code.google.com/p/boghe/ instead of eyebeam. Boghe implements the right set of media options and it's IMS-ready, not just a regular SIP softphone.
On Thu, Aug 16, 2012 at 1:02 PM, Juha Heinanen jh@tutpro.com wrote:
Juan Castro writes:
Progress! Now, I get an "Unsupported media type" message in the sipml5 page when I try to make a call, which is a much more polite behavior. Maybe I just have to enable some codec in the other softphone? Here's the log.
perhaps it has something to do with this:
http://lists.iptel.org/pipermail/semsdev/2012-July/006904.html
-- juha
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
But what I really want is interoperability with generic, plain SIP endpoints.
I could make a call between two machines using sipml5, corroborating what Juha said. I also have another, tangential question -- anyone knows how I change the simpl5 code so a voice-only call is made? I.e., no attempts to use the camera?
On Thu, Aug 16, 2012 at 3:15 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
You should try http://code.google.com/p/boghe/ instead of eyebeam. Boghe implements the right set of media options and it's IMS-ready, not just a regular SIP softphone.
On Thu, Aug 16, 2012 at 1:02 PM, Juha Heinanen jh@tutpro.com wrote:
Juan Castro writes:
Progress! Now, I get an "Unsupported media type" message in the sipml5 page when I try to make a call, which is a much more polite behavior. Maybe I just have to enable some codec in the other softphone? Here's the log.
Yes, but you have to consider that this is a very new technology and it will take time to be implemented in the majority of the legacy softphones.
Regarding the other question: there's a thread about sipml5 for voice only in Doubango's mailing list.
Regards.
Carlos.
On Thu, Aug 16, 2012 at 5:03 PM, Juan Castro jcastro@instant.com.br wrote:
But what I really want is interoperability with generic, plain SIP endpoints.
I could make a call between two machines using sipml5, corroborating what Juha said. I also have another, tangential question -- anyone knows how I change the simpl5 code so a voice-only call is made? I.e., no attempts to use the camera?
On Thu, Aug 16, 2012 at 3:15 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
You should try http://code.google.com/p/boghe/ instead of eyebeam.
Boghe implements the right set of media options and it's IMS-ready, not just a regular SIP softphone.
On Thu, Aug 16, 2012 at 1:02 PM, Juha Heinanen jh@tutpro.com wrote:
Juan Castro writes:
Progress! Now, I get an "Unsupported media type" message in the sipml5 page when I try to make a call, which is a much more polite behavior. Maybe I just have to enable some codec in the other softphone? Here's the log.
sr-dev mailing list sr-dev@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-dev
On Thu, Aug 16, 2012 at 6:09 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
Yes, but you have to consider that this is a very new technology and it will take time to be implemented in the majority of the legacy softphones.
That doesn't need to happen, only a good gateway needs to exist. Be it Kamailio, Asterisk, or whatever.
Regarding the other question: there's a thread about sipml5 for voice only in Doubango's mailing list.
Thanks, gonna check.
On Thu, Aug 16, 2012 at 6:09 PM, Carlos Ruiz Díaz That doesn't need to happen, only a good gateway needs to exist. Be it Kamailio, Asterisk, or whatever.
There are two separate protocols here. (SIP over) WebSockets and RTCWeb.
Kamailio interworks SIP calls between the WebSockets and other transports. Media interworking (RTCWeb) is something separate. Kamailio itself does not handle media so the interworking for that needs to happen elsewhere.
It's always going to be better (more efficient and fewer interworking issues) when the UAs support the required media profiles. However, if a gateway is required using Asterisk is probably not a generally workable solution. Asterisk will B2BUA the signalling rather than proxying it - which is not ideal.
If the majority of UAs aren't going to support the required (RTP/SAVPF) profile then the best interworking options would be to enhance a media proxy (such as RTPproxy or MediaProxy) to convert. No codec manipulation is required for this, so it is potentially a very high-performance option. What would be needed: 1) media proxy to convert between RTP/SAVPF and RTP/SAVP This involves one side (the RTCWeb side) providing (the minimal) RTCP required and the other not 2) media proxy to convert between RTP/SAVPF and RTP/AVP (for clients that do not support secure RTP) This involves one side (the RTCWeb side) providing the (minimal) RTCP required, decrypting received packets, encrypting packets for sending, and the other side receiving/transmitting "plain" RTP
Some small updates would then be needed to the Kamailio rtpproxy or mediaproxy modules to munge the SDP and call the external media proxy with the correct options to initiate the profile conversion.
Of course the problem you will then have is choosing which calls need the media proxy and which don't. - WebSocket to WebSocket calls won't - but it will not always be the case that Kamailio can tell that the destination of the call will be connected over WebSockets (endpoint registered on a different network, and cases involving parallel forking come to mind). - Some non-WebSocket endpoint will support the correct media profile (and this number will grow as time goes on) and using a media proxy for calls to these is inefficient (additional bandwidth for media handling will be required in the ITSP network _and_ undesirable latency is added to the media path by unnecessary media proxies) - but how do you tell whether an endpoint supports WebSockets or not in advance?
The right thing to do for the media conversion might be to always attempt to make the call without the media proxy and then do appropriate handling of 488 responses with message body. That is, if the call with media profile RTP/SAVPF is responded to with a 488 containing SDP indicating support for: - RTP/SAVP then the media proxy needs to be used and told to do conversion 1 above - RTP/AVP then the media proxy needs to be used and told to do conversion 2 above
But of course, this requires the endpoint to fill in the 488 correctly - which may not be the case ;-)
There has been some discussion on this list about updating the RTP proxy (in particular the protocol used between Kamailio and the RTP proxy). Is this something that will happen within the Kamailio 3.4 timescale?
If it is, would the changes to the RTP proxy to support profile conversion be something that could be considered at the same time? If not, perhaps consideration should be given to ensuring the updated protocol is one that would comfortable support options for the profile conversions in the future?
Regards,
Peter
2012/8/16 Juan Castro jcastro@instant.com.br:
On Thu, Aug 16, 2012 at 6:09 PM, Carlos Ruiz Díaz carlos.ruizdiaz@gmail.com wrote:
Yes, but you have to consider that this is a very new technology and it will take time to be implemented in the majority of the legacy softphones.
That doesn't need to happen, only a good gateway needs to exist. Be it Kamailio, Asterisk, or whatever.
A legacy SIP client will not interoperate with a WebRTC browser in the media plane. There is nothing Kamailio (a SIP proxy) can do to "solve" that.