[SR-Users] Msilo not delivering message (status 408)
Daniel-Constantin Mierla
miconda at gmail.com
Thu Jul 17 14:22:06 CEST 2014
I don't remember any relevant updates to msilo lately. So it should be
the same.
Unfortunately the heavy traveling I had lately added a lot of load for
the moment to properly investigate you case. But msilo is running fine
on many installations here, so I expect to be some problem with the config.
I haven't seen any sip trace taken on the server, which I asked, iirc.
Did you monitored the loopback interface?
You can try to set the outbound proxy address to the kamailio's ip and port:
http://kamailio.org/docs/modules/stable/modules/msilo.html#idp121032
Then see what happens on the network interface.
Cheers,
Daniel
On 17/07/14 14:10, Peter Villeneuve wrote:
> I guess no one really knows why it isn't working either.
> The only option I have left seems to be to try and update to the 4.2
> nightly and hope that whatever's wrong is a bug with 4.1.4 and not a
> simple mistake in my routing syntax or logic.
>
> Daniel, is 4.2 nightly debian package stable enough for a production
> system? Would you recommend I upgrade?
>
> Thanks
>
>
> On Wed, Jul 16, 2014 at 9:37 PM, Peter Villeneuve <petervnv1 at gmail.com
> <mailto:petervnv1 at gmail.com>> wrote:
>
> Can anyone help please? I was supposed to have this server up and
> running hours ago and I can't think of anything to do next to help
> debug this.
>
> Thanks
>
>
> On Wed, Jul 16, 2014 at 7:01 PM, Peter Villeneuve
> <petervnv1 at gmail.com <mailto:petervnv1 at gmail.com>> wrote:
>
> I removed the localhost listeners on TCP and UDP, and, as I
> suspected, Kamailio doesn't seem to like that.
> Why isn't kamailio sending the message back through the TLS
> socket?
>
>
> NOTICE: <script>: -------------------------MESSAGE PROCESSING: dumping offline messages
> ERROR: tm [ut.h:343]: uri2dst2(): no corresponding socket for af 2
> ERROR: tm [uac.c:266]: t_uac_prepare(): t_uac: no socket found
>
>
>
> On Wed, Jul 16, 2014 at 4:44 PM, Peter Villeneuve
> <petervnv1 at gmail.com <mailto:petervnv1 at gmail.com>> wrote:
>
> And here's the full routing block. I copied it from
> someone else on the list that was troubleshooting msilo a
> while back.
> Also, I can confirm the message never gets delivered
> because it remains in msilo DB and kamailio logs shows it
> trying and trying.
>
>
> ####### Routing Logic ########
>
>
> # Main SIP request routing logic
> # - processing of any incoming SIP request starts with
> this route
> route {
>
> # per request initial checks
> route(REQINIT);
>
> # NAT detection
> route(NAT);
>
> # 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;
> }
>
> if (is_method("MESSAGE"))
> {
> log(1,"-------------------------------------------------------->WE
> HAVE A NEW MESSAGE\n");
> route(MSILO_MESSAGE);
> }
> 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|SUBSCRIBE"))
> record_route();
>
> # account only INVITEs
> if (is_method("INVITE"))
> {
> setflag(FLT_ACC); # do accounting
> }
>
> # dispatch requests to foreign domains
> route(SIPOUT);
>
> ### requests for my local domains
>
> # handle presence related requests
> route(PRESENCE);
>
> # handle registrations
> route(REGISTRAR);
>
> if ($rU==$null)
> {
> # request with no Username in RURI
> sl_send_reply("484","Address Incomplete");
> exit;
> }
>
> # dispatch destinations to PSTN
> route(PSTN);
>
> # user location service
> route(LOCATION);
>
> route(RELAY);
> }
>
>
> route[RELAY] {
> #!ifdef WITH_NAT
> if (check_route_param("nat=yes")) {
> setbflag(FLB_NATB);
> }
> if (isflagset(FLT_NATS) || isbflagset(FLB_NATB)) {
> route(RTPPROXY);
> }
> #!endif
>
>
> /* example how to enable some additional event routes */
> if (is_method("INVITE")) {
> #t_on_branch("BRANCH_ONE");
> t_on_reply("REPLY_ONE");
> t_on_failure("FAIL_ONE");
> }
>
> if (!t_relay()) {
> sl_reply_error();
> }
> exit;
> }
>
> ##storing offline messages
> route[MSILO_MESSAGE]{
> if (lookup("location")){
> t_on_failure("FAIL_MESSAGE");
> t_relay();
> xlog("L_NOTICE","---------------------------------MI:
> ($mi), MB: ($mb)\n");
> }else{
> xlog("L_NOTICE","------------------MESSAGE PROCESSING:
> location not found, storing offline message ID: ($ru)\n");
> m_store("$ru");
> sl_send_reply("202", "ACCEPTED");
> xlog("L_NOTICE","------------------MESSAGE PROCESSING:
> location not found, stored offline message ID: ($ru)\n");
> };
> exit;
> };
>
> failure_route[FAIL_MESSAGE]{
>
> xlog("L_NOTICE","------------------MESSAGE PROCESSING:
> user cannot be reached, storing offline message ID: ($ou)\n");
> m_store("$ou");
> t_reply("202", "ACCEPTED");
> xlog("L_NOTICE","------------------MESSAGE PROCESSING:
> user cannot be reached, stored offline message ID: ($ou)\n");
> exit;
> }
>
>
>
>
> # Per SIP request initial checks
> route[REQINIT] {
> #!ifdef WITH_ANTIFLOOD
> # flood dection from same IP and traffic ban for a while
> # be sure you exclude checking trusted peers, such as pstn
> gateways
> # - local host excluded (e.g., loop to self)
> if(src_ip!=myself)
> {
> if($sht(ipban=>$si)!=$null)
> {
> # ip is already blocked
> xdbg("request from blocked IP - $rm from $fu (IP:$si:$sp)\n");
> exit;
> }
> if (!pike_check_req())
> {
> xlog("L_ALERT","ALERT: pike blocking $rm from $fu
> (IP:$si:$sp)\n");
> $sht(ipban=>$si) = 1;
> exit;
> }
> }
> #!endif
>
> 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()) {
> if (is_method("BYE")) {
> setflag(FLT_ACC); # do accounting ...
> setflag(FLT_ACCFAILED); # ... even if the transaction fails
> }
> route(RELAY);
> } else {
> if (is_method("SUBSCRIBE") && uri == myself) {
> # in-dialog subscribe requests
> route(PRESENCE);
> exit;
> }
> 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(isflagset(FLT_NATS))
> {
> setbflag(FLB_NATB);
> # uncomment next line to do SIP NAT pinging
> setbflag(FLB_NATSIPPING);
> }
> if (!save("location"))
> sl_reply_error();
> # MSILO - dumping user's offline messages
> m_dump();
> log(1,"-------------------------MESSAGE PROCESSING:
> dumping offline messages\n");
> exit;
> }
> }
>
> # USER location service
> route[LOCATION] {
>
> #!ifdef WITH_ALIASDB
> # search in DB-based aliases
> alias_db_lookup("dbaliases");
> #!endif
>
> if (!lookup("location")) {
> switch ($rc) {
> case -1:
> case -3:
> t_newtran();
> t_reply("404", "Not Found");
> exit;
> case -2:
> sl_send_reply("405", "Method Not Allowed");
> exit;
> }
> }
>
> # when routing via usrloc, log the missed calls also
> if (is_method("INVITE"))
> {
> setflag(FLT_ACCMISSED);
> }
> }
>
> # Presence server route
> route[PRESENCE] {
> if(!is_method("PUBLISH|SUBSCRIBE"))
> return;
>
> #!ifdef WITH_PRESENCE
> if (!t_newtran())
> {
> sl_reply_error();
> exit;
> };
>
> if(is_method("PUBLISH"))
> {
> handle_publish();
> t_release();
> }
> else
> if( is_method("SUBSCRIBE"))
> {
> handle_subscribe();
> t_release();
> }
> exit;
> #!endif
> # if presence enabled, this part will not be executed
> if (is_method("PUBLISH") || $rU==$null)
> {
> sl_send_reply("404", "Not here");
> exit;
> }
> return;
> }
>
> # Authentication route
> route[AUTH] {
> #!ifdef WITH_AUTH
> if (is_method("REGISTER"))
> {
> # authenticate the REGISTER requests (uncomment to enable
> auth)
> if (!www_authorize("$td", "subscriber"))
> {
> www_challenge("$td", "0");
> exit;
> }
>
> if ($au!=$tU)
> {
> sl_send_reply("403","Forbidden auth ID");
> exit;
> }
> } else {
>
> #!ifdef WITH_IPAUTH
> if(allow_source_address())
> {
> # source IP allowed
> return;
> }
> #!endif
>
> # authenticate if from local subscriber
> if (from_uri==myself)
> {
> if (!proxy_authorize("$fd", "subscriber")) {
> proxy_challenge("$fd", "0");
> exit;
> }
> if (is_method("PUBLISH"))
> {
> if ($au!=$tU) {
> sl_send_reply("403","Forbidden auth ID");
> exit;
> }
> } else {
> if ($au!=$fU) {
> sl_send_reply("403","Forbidden auth ID");
> exit;
> }
> }
>
> consume_credentials();
> # caller authenticated
> } else {
> # caller is not local subscriber, then check if it calls
> # a local destination, otherwise deny, not an open relay here
> if (!uri==myself)
> {
> sl_send_reply("403","Not relaying");
> exit;
> }
> }
> }
> #!endif
> return;
> }
>
> # Caller NAT detection route
> route[NAT] {
> #!ifdef WITH_NAT
> force_rport();
> if (nat_uac_test("19")) {
> if (method=="REGISTER") {
> fix_nated_register();
> } else {
> fix_nated_contact();
> }
> setflag(FLT_NATS);
> }
> #!endif
> return;
> }
>
> # RTPProxy control
> route[RTPPROXY] {
> #!ifdef WITH_NAT
> if (is_method("BYE")) {
> unforce_rtp_proxy();
> } else if (is_method("INVITE")){
> rtpproxy_offer();
> }
> if (!has_totag()) add_rr_param(";nat=yes");
> #!endif
> return;
> }
>
> # Routing to foreign domains
> route[SIPOUT] {
> if (!uri==myself)
> {
> append_hf("P-hint: outbound\r\n");
> route(RELAY);
> }
> }
>
> # PSTN GW routing
> route[PSTN] {
> #!ifdef WITH_PSTN
> # check if PSTN GW IP is defined
> if (strempty($sel(cfg_get.pstn.gw_ip))) {
> xlog("SCRIPT: PSTN rotuing enabled but pstn.gw_ip not
> defined\n");
> return;
> }
>
> # route to PSTN dialed numbers starting with '+' or '00'
> # (international format)
> # - update the condition to match your dialing rules for
> PSTN routing
> if(!($rU=~"^(\+|00)[1-9][0-9]{3,20}$"))
> return;
>
> # only local users allowed to call
> if(from_uri!=myself) {
> sl_send_reply("403", "Not Allowed");
> exit;
> }
>
> $ru = "sip:" + $rU + "@" + $sel(cfg_get.pstn.gw_ip);
>
> route(RELAY);
> exit;
> #!endif
>
> return;
> }
>
> # XMLRPC routing
> #!ifdef WITH_XMLRPC
> route[XMLRPC]
> {
> # allow XMLRPC from localhost
> if ((method=="POST" || method=="GET")
> && (src_ip==127.0.0.1)) {
> # close connection only for xmlrpclib user agents (there
> is a bug in
> # xmlrpclib: it waits for EOF before interpreting the
> response).
> if ($hdr(User-Agent) =~ "xmlrpclib")
> set_reply_close();
> set_reply_no_connect();
> dispatch_rpc();
> exit;
> }
> send_reply("403", "Forbidden");
> exit;
> }
> #!endif
>
> # Sample branch router
> branch_route[BRANCH_ONE] {
> xdbg("new branch at $ru\n");
> }
>
> # Sample onreply route
> onreply_route[REPLY_ONE] {
> xdbg("incoming reply\n");
> #!ifdef WITH_NAT
> if ((isflagset(FLT_NATS) || isbflagset(FLB_NATB))
> && status=~"(183)|(2[0-9][0-9])") {
>
> rtpproxy_answer();
> }
> if (isbflagset("6")) {
> fix_nated_contact();
> }
> #!endif
> }
>
> # Sample failure route
> failure_route[FAIL_ONE] {
> #!ifdef WITH_NAT
> if (is_method("INVITE")
> && (isbflagset(FLB_NATB) || isflagset(FLT_NATS))) {
> unforce_rtp_proxy();
> }
> #!endif
>
> if (t_is_canceled()) {
> exit;
> }
>
> # uncomment the following lines if you want to block client
> # redirect based on 3xx replies.
> ##if (t_check_status("3[0-9][0-9]")) {
> ##t_reply("404","Not found");
> ##exit;
> ##}
>
> # uncomment the following lines if you want to redirect
> the failed
> # calls to a different new destination
> ##if (t_check_status("486|408")) {
> ##sethostport("192.168.2.100:5060
> <http://192.168.2.100:5060>");
> ##append_branch();
> ### do not set the missed call flag again
> ##t_relay();
> ##}
> }
>
>
> On Wed, Jul 16, 2014 at 4:38 PM, Peter Villeneuve
> <petervnv1 at gmail.com <mailto:petervnv1 at gmail.com>> wrote:
>
> Thanks for your help Daniel.
>
> OK I ngrepped port 5060 and indeed the message tries
> to get delivered. However it never arrives at the
> client (1010 in this case).
> I have added localhost listeners for UDP and TCP, even
> though all clients register through TLS only (could
> this be the root of the problem?). I added localhost
> listeners because I was getting the no socket found
> error message.
>
> Listening on
> udp: 127.0.0.1:5080 <http://127.0.0.1:5080>
> tcp: 127.0.0.1:5060 <http://127.0.0.1:5060>
> tls: 79.my.server.IP1:5061
> Aliases:
> tcp: localhost:5060
> udp: localhost:5080
> *: my.domain.com:*
>
> U 2014/07/16 15:29:14.904285 127.0.0.1:5080
> <http://127.0.0.1:5080> -> 79.my.server.IP1:5060
> MESSAGE sip:1010 at my.domain.com
> <mailto:sip%3A1010 at my.domain.com> SIP/2.0.
> Via: SIP/2.0/UDP
> 127.0.0.1:5080;branch=z9hG4bKcde3.542219f4000000000000000000000000.0.
> To: <sip:1010 at my.domain.com
> <mailto:sip%3A1010 at my.domain.com>>.
> From: <sip:1000 at my.domain.com
> <mailto:sip%3A1000 at my.domain.com>>;tag=def4124455da8a0b8e97eafabd028e26-2c30.
> CSeq: 10 MESSAGE.
> Call-ID: 16b126996f51d89c-12460 at 127.0.0.1
> <mailto:16b126996f51d89c-12460 at 127.0.0.1>.
> Max-Forwards: 70.
> Content-Length: 58.
> User-Agent: kamailio (4.1.4 (x86_64/linux)).
> Date: Wed, 16 Jul 2014 15:22:31 GMT.
> Content-Type: text/plain.
> .
> [Offline message - Wed Jul 16 15:22:31 2014] Test 1,2,3
>
>
> On Wed, Jul 16, 2014 at 8:58 AM, Daniel-Constantin
> Mierla <miconda at gmail.com <mailto:miconda at gmail.com>>
> wrote:
>
> Hello,
>
> can you watch the sip traffic on the network to
> see if the sip messages is sent out? You can use
> ngrep, like:
>
> ngrep -d any -qt -W byline "sip" port 5060
>
> Cheers,
> Daniel
>
>
> On 14/07/14 19:56, Peter Villeneuve wrote:
>> Hi,
>>
>> This is most likely a stupid mistake on my route
>> config, but msilo correctly stores messages for
>> offline users, but when they come back online,
>> the message never arrives.
>>
>> Looking through the debug logs indeed there is an
>> error, with status 408, which suggests kamaiio
>> can't find a route to the user that just logged.
>> Before I spend hours looking through all the
>> routing blocks, perhaps someone more exprienced
>> in kamailio can see the problem right away and
>> offer a solution?
>>
>> Thanks
>>
>>
>> DEBUG: msilo [msilo.c:1148]: m_dump(): msg [1-12]
>> for: sip:1010 at my.domain.com
>> <mailto:sip%3A1010 at my.domain.com>
>> DEBUG: <core> [io_wait.h:617]: io_watch_del():
>> DBG: io_watch_del (0x8e3040, 9, -1, 0x10) fd_no=2
>> called
>> DEBUG: <core> [tcp_read.c:1437]:
>> release_tcpconn(): releasing con 0x7f61ced1b1b0,
>> state 1, fd=9, id=1
>> DEBUG: <core> [tcp_read.c:1438]:
>> release_tcpconn(): extra_data 0x7f61ced1c4f8
>> DEBUG: <core> [tcp_main.c:3385]:
>> handle_tcp_child(): handle_tcp_child: reader
>> response= 7f61ced1b1b0, 1 from 0
>> DEBUG: <core> [io_wait.h:390]: io_watch_add():
>> DBG: io_watch_add(0x89ec40, 26, 2,
>> 0x7f61ced1b1b0), fd_no=21
>> DEBUG: <core> [tcp_main.c:3515]:
>> handle_tcp_child(): handle_tcp_child:
>> CONN_RELEASE 0x7f61ced1b1b0 refcnt= 1
>> DEBUG: tm [t_reply.c:1304]:
>> t_should_relay_response(): ->>>>>>>>> T_code=0,
>> new_code=408
>> DEBUG: tm [t_reply.c:2086]: local_reply(): DEBUG:
>> local_reply: branch=0, save=0, winner=0
>> DEBUG: tm [t_reply.c:2123]: local_reply(): DEBUG:
>> local transaction completed
>> DEBUG: tm [t_hooks.c:288]:
>> run_trans_callbacks_internal(): DBG:
>> trans=0x7f61ced1d868, callback type 1024, id 0
>> entered
>> DEBUG: msilo [msilo.c:1317]: m_tm_callback():
>> *completed with status 408* [mid: 140058058335472/13]
>> DEBUG: msilo [msilo.c:1325]: m_tm_callback():
>> message <13> was not sent successfully
>> DEBUG: msilo [ms_msg_list.c:236]:
>> msg_list_set_flag(): mid:13 fl:8
>> DEBUG: tm [t_reply.c:1304]:
>> t_should_relay_response(): ->>>>>>>>> T_code=0,
>> new_code=408
>> DEBUG: tm [t_reply.c:2086]: local_reply(): DEBUG:
>> local_reply: branch=0, save=0, winner=0
>> DEBUG: tm [t_reply.c:2123]: local_reply(): DEBUG:
>> local transaction completed
>> DEBUG: tm [t_hooks.c:288]:
>> run_trans_callbacks_internal(): DBG:
>> trans=0x7f61ced5f098, callback type 1024, id 0
>> entered
>> DEBUG: msilo [msilo.c:1317]: m_tm_callback():
>> completed with status 408 [mid: 140058058329184/12]
>> DEBUG: msilo [msilo.c:1325]: m_tm_callback():
>> message <12> was not sent successfully
>> DEBUG: msilo [ms_msg_list.c:236]:
>> msg_list_set_flag(): mid:12 fl:8
>>
>>
>>
>>
>> _______________________________________________
>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
>> sr-users at lists.sip-router.org <mailto:sr-users at lists.sip-router.org>
>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>
> --
> Daniel-Constantin Mierla -http://www.asipto.com
> http://twitter.com/#!/miconda <http://twitter.com/#%21/miconda> -http://www.linkedin.com/in/miconda
>
>
> _______________________________________________
> SIP Express Router (SER) and Kamailio (OpenSER) -
> sr-users mailing list
> sr-users at lists.sip-router.org
> <mailto:sr-users at lists.sip-router.org>
> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>
>
>
>
>
>
--
Daniel-Constantin Mierla - http://www.asipto.com
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20140717/eaa5ec4f/attachment.html>
More information about the sr-users
mailing list