[SR-Users] Kamailio error code 500 (6/SL)

Pavel Klochan pavel.klochan at gmail.com
Wed Feb 29 21:52:40 CET 2012


Hi.
I'm a newbie to Kamailio (so do not judge strictly). I have experience only
with Asterisk and now I'm trying to configure Kamailio. With default config
all is working as expected, but I'm trying to configure it from zero with
minimal functions, but using parts from default config (those parts that I
figured out).
With my present config local calls are working great, but I can't make
outbound calls.
I've attached config file, sip-trace and debug log.
I think there is a route loop somewhere but I can't figure out where.
I would be grateful if you pointed out to me where exactly my mistake or
just point me in the right direction =)

Thanks in advance
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20120301/0c96cce9/attachment-0001.htm>
-------------- next part --------------
?request_route {
  ## Per request initial checks
  route(REQINIT);

  ## NAT detection
  route(NATDETECT);

  ## Handle requests within SIP dialogs
  route(WITHINDLG);

  ## CANCEL processing
  if (is_method("CANCEL")) {
    if (t_check_trans()) {
      t_relay();
    }
    exit;
  }

  t_check_trans();

  ## Authentication
  route(AUTH);

  remove_hf("Route");
  if (is_method("INVITE"))
    record_route();

  # account only INVITEs
  if (is_method("INVITE")) {
    setflag(FLT_ACC); # do accounting
  }

  ## Outgoing calls
  route(SIPOUT);
  ## 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] {
# enable additional event routes for forwarded requests
# - serial forking, RTP relaying handling, a.s.o.
  if (is_method("INVITE")) {
    t_on_branch("MANAGE_BRANCH");
    t_on_reply("MANAGE_REPLY");
  }
  if (is_method("INVITE")) {
    t_on_failure("MANAGE_FAILURE");
  }
  if (!t_relay()) {
    sl_reply_error();
  }
}

route[REQINIT] {
  ## No more than 10 hops away
  if (!mf_process_maxfwd_header("10")) {
    sl_send_reply("483","Too Many Hops");
    exit;
  }
  ## No more than 2K message
  if (msg:len >= 2048 ) {
    sl_send_reply("513", "Message too big");
  }

}

route[NATDETECT] {
  force_rport();
  if (!search("^Contact:[ ]*\*") && nat_uac_test("19")) {
    if (is_method("REGISTER")) {
      fix_nated_register();
    } else {
      fix_nated_contact();
    }
    setflag(FLT_NATS);
  }
  return;
}

route[NATMANAGE] {
  if (is_request()) {
    if(has_totag()) {
      if(check_route_param("nat=yes")) {
        setbflag(FLB_NATB);
      }
    }
  }
  if (!(isflagset(FLT_NATS) || isbflagset(FLB_NATB)))
    return;

  rtpproxy_manage();

  if (is_request()) {
    if (!has_totag()) {
      add_rr_param(";nat=yes");
    }
  }
  if (is_reply()) {
    if(isbflagset(FLB_NATB)) {
      fix_nated_contact();
    }
  }
  return;
}

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
      }
      if ( is_method("ACK") ) {
        # ACK is forwarded statelessy
        route(NATMANAGE);
      }
      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;
  }
}

route[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 {
    ## Authenticate if from local subscriber
    if (from_uri==myself)
    {
      if (!proxy_authorize("$fd", "subscriber")) {
        proxy_challenge("$fd", "0");
        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;
      }
    }
  }
  return;
}

route[SIPOUT] {
  if (!uri==myself) {
    append_hf("P-hint: outgoing call\r\n");
    route(RELAY);
  }
}

route[REGISTRAR] {
  if (is_method("REGISTER")) {
    if(isflagset(FLT_NATS)) {
      setbflag(FLB_NATB);
    }
    if (!save("location"))
      sl_reply_error();
    exit;
  }
}

route[LOCATION] {
  if (!lookup("location")) {
    t_newtran();
    switch ($var(rc)) {
      case -1:
      case -3:
        send_reply("404", "Not Found");
        exit;
      case -2:
        send_reply("405", "Method Not Allowed");
        exit;
    }
  }
  if (is_method("INVITE")) {
    setflag(FLT_ACCMISSED);
  }
}

# manage outgoing branches
branch_route[MANAGE_BRANCH] {
  xdbg("new branch [$T_branch_idx] to $ru\n");
  route(NATMANAGE);
}

# manage incoming replies
onreply_route[MANAGE_REPLY] {
  xdbg("incoming reply\n");
  if(status=~"[12][0-9][0-9]")
    route(NATMANAGE);
}

# manage failure routing cases
failure_route[MANAGE_FAILURE] {
  route(NATMANAGE);
  if (t_is_canceled()) {
    exit;
  }
}
-------------- next part --------------
?Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:628]: SIP Request:
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:630]:  method:  <INVITE>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:632]:  uri:     <sip:201 at sip.flygroup.st>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:634]:  version: <SIP/2.0>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK-d8754z-eb11a44754657a6b-1---d8754z->; state=6
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 235, <rport> = <n/a>; state=17
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [receive.c:146]: After parse_msg...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [receive.c:187]: preparing to run routing scripts...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=10
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [32]; uri=[sip:201 at sip.flygroup.st]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body ["201"<sip:201 at sip.flygroup.st>#015#012]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:167]: get_hdr_field: cseq <CSeq>: <1> <INVITE>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:201]: DEBUG: get_hdr_body : content_length=371
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:103]: found end of header
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=df2e65c2
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=29
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: sanity [mod_sanity.c:251]: sanity checks result: 1
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: siputils [checks.c:104]: no totag
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=36 global id=35 T start=(nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=45991, isACK=0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=36 global id=36 T end=(nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 13==13 &&  [88.198.48.183] == [88.198.48.183]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth_db [authorize.c:271]: realm value [88.198.48.183]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth [api.c:85]: auth:pre_auth: Credentials with realm '88.198.48.183' not found
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth_db [authorize.c:177]: no credentials
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth [challenge.c:102]: build_challenge_hf: realm='88.198.48.183'
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: auth [challenge.c:244]: auth: 'Proxy-Authenticate: Digest realm="88.198.48.183", nonce="T06A0k9Of6YNkz/gCQ39kNtH6XOCeWhM"#015#012'
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: sl [sl.c:278]: reply in stateless mode (sl)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [receive.c:290]: receive_msg: cleaning up
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:628]: SIP Request:
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:630]:  method:  <ACK>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:632]:  uri:     <sip:201 at sip.flygroup.st>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:634]:  version: <SIP/2.0>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK-d8754z-eb11a44754657a6b-1---d8754z->; state=6
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 235, <rport> = <n/a>; state=17
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [receive.c:146]: After parse_msg...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [receive.c:187]: preparing to run routing scripts...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=4cf90b7f894f6efcb610e24626b3f90c.d1e8
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=29
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [74]; uri=[sip:201 at sip.flygroup.st]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body ["201"<sip:201 at sip.flygroup.st>]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: sl [sl_funcs.c:326]: SL local ACK found -> dropping it!
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [receive.c:290]: receive_msg: cleaning up
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:628]: SIP Request:
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:630]:  method:  <INVITE>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:632]:  uri:     <sip:201 at sip.flygroup.st>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:634]:  version: <SIP/2.0>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK-d8754z-c2d04a87758121e0-1---d8754z->; state=6
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 235, <rport> = <n/a>; state=17
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [receive.c:146]: After parse_msg...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [receive.c:187]: preparing to run routing scripts...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: maxfwd [mf_funcs.c:85]: value = 70
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=10
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [32]; uri=[sip:201 at sip.flygroup.st]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body ["201"<sip:201 at sip.flygroup.st>#015#012]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:167]: get_hdr_field: cseq <CSeq>: <2> <INVITE>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:201]: DEBUG: get_hdr_body : content_length=371
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:103]: found end of header
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=df2e65c2
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=29
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: sanity [mod_sanity.c:251]: sanity checks result: 1
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: siputils [checks.c:104]: no totag
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=34 global id=33 T start=(nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=45988, isACK=0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=34 global id=34 T end=(nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 13==13 &&  [88.198.48.183] == [88.198.48.183]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth_db [authorize.c:271]: realm value [88.198.48.183]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth [api.c:95]: auth: digest-algo: MD5 parsed value: 1
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb721b528
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:68]: 2 columns returned from the query
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0xb722c1b0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:165]: allocate 8 bytes for result types at 0xb721b574
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb7231d4c
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7231d4c)[0]=[password]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[1] at 0xb722c1e8
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb722c1e8)[1]=[rpid]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb7219900
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_row.c:119]: allocate 40 bytes for row values at 0xb7230bc8
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_val.c:117]: converting STRING [1004]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_val.c:56]: converting NULL value
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth_db [authorize.c:121]: HA1 string calculated: e0ed591727640aed8640e723e04772e7
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth [api.c:210]: check_response: Our result = '703637383073b56ac6447d4622649a93'
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: auth [api.c:217]: check_response: Authorization is OK
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:81]: freeing 2 columns
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb7231d4c
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0xb722c1e8
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:94]: freeing result names at 0xb722c1b0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:99]: freeing result types at 0xb721b574
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:54]: freeing 1 rows
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_row.c:97]: freeing row values at 0xb7230bc8
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:62]: freeing rows at 0xb7219900
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [db_res.c:136]: freeing result set at 0xb721b528
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 15==13 &&  [sip.flygroup.st] == [88.198.48.183]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [forward.c:448]: check_self: host != me
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=34 , global msg id=34 , T on entrance=(nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=45988, isACK=0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_funcs.c:351]: SER: new INVITE
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0) called
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_reply.c:667]: DEBUG: reply sent out. buf=0xb7239670: SIP/2.0 100 trying -..., shmem=0xaeeaf824: SIP/2.0 100 trying -
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_reply.c:677]: DEBUG: _reply_light: finished
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <script>: new branch [1] to sip:201 at sip.flygroup.st
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: siputils [checks.c:104]: no totag
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/sdp/sdp_helpr_funcs.c:472]: located IP address [192.168.1.2] in `o=' field
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/sdp/sdp_helpr_funcs.c:472]: located IP address [192.168.1.2] in `c=' field
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/sdp/sdp.c:558]: ignoring unknown type in a= line: `a=candidate:1 1 UDP 659136 192.168.1.2 62306 typ host#015#012a=candidate:1 2 UDP 659134 192.168.1.2 62307 typ host#015#012'
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/sdp/sdp.c:558]: ignoring unknown type in a= line: `a=candidate:1 2 UDP 659134 192.168.1.2 62307 typ host#015#012'
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: rtpproxy [rtpproxy_funcs.c:148]: type <application/sdp> found valid
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: rtpproxy [rtpproxy.c:2237]: proxy reply: 59414 88.198.48.183#012
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: siputils [checks.c:104]: no totag
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [dns_cache.c:567]: dns_hash_find(_sip._udp.sip.flygroup.st(25), 33), h=393
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [resolve.c:727]: get_record: lookup(_sip._udp.sip.flygroup.st, 33) failed
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [dns_cache.c:895]: dns_cache_mk_bad_entry(_sip._udp.sip.flygroup.st, 33, 60, 1)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [dns_cache.c:828]: dns_cache_add: adding _sip._udp.sip.flygroup.st(25) 33 (flags=1) at 393
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [dns_cache.c:567]: dns_hash_find(sip.flygroup.st(15), 1), h=111
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [msg_translator.c:457]: clen_builder: content-length: 393 (393)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_funcs.c:388]: SER: new transaction fwd'ed
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: registrar [lookup.c:84]: '201' Not found in usrloc
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=34 , global msg id=34 , T on entrance=0xaeeb1da0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1384]: DEBUG: t_newtran: transaction already in process 0xaeeb1da0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: ERROR: tm [tm.c:1327]: ERROR: t_newtran: transaction already in process 0xaeeb1da0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=34 , global msg id=34 , T on entrance=0xaeeb1da0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1384]: DEBUG: t_newtran: transaction already in process 0xaeeb1da0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: ERROR: tm [t_fwd.c:1523]: ERROR: t_forward_nonack: no branches for forwarding
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_funcs.c:361]: ERROR:tm:t_relay_to:  t_forward_nonack returned error
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_funcs.c:369]: -6 error reply generation delayed
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [msg_translator.c:204]: check_via_address(178.140.2.133, 192.168.1.2, 0)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: ERROR: sl [sl_funcs.c:282]: ERROR: sl_reply_error used: I'm terribly sorry, server error occurred (6/SL)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [receive.c:290]: receive_msg: cleaning up
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/sdp/sdp.c:771]: _sdp = 0xb721e2a4
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/sdp/sdp.c:773]: sdp = 0xb7239e10
Feb 29 20:42:31 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/sdp/sdp.c:775]: session = 0xb7239e54
Feb 29 20:42:31 vss /usr/sbin/kamailio[16837]: DEBUG: <core> [udp_server.c:498]: udp_rcv_loop: probing packet received from 178.140.2.133 23095
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:638]: SIP Reply  (status):
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:640]:  version: <SIP/2.0>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:642]:  status:  <100>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:644]:  reason:  <Trying>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK4a3b.2c691b46.0>; state=6
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 234, <received> = <88.198.48.183>; state=16
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [receive.c:146]: After parse_msg...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=37 global id=36 T start=(nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 234, <received> = <178.140.2.133>; state=6
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK-d8754z-c2d04a87758121e0-1---d8754z->; state=6
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 235, <rport> = <14170>; state=16
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=22
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:528]: parse_headers: this is the second via
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=10
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [32]; uri=[sip:201 at sip.flygroup.st]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body ["201"<sip:201 at sip.flygroup.st>#015#012]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:167]: get_hdr_field: cseq <CSeq>: <2> <INVITE>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 45988 label 1689360066 branch 0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xaeeb1da0)!
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=37 global id=37 T end=0xaeeb1da0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_reply.c:2040]: DEBUG: reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <script>: incoming reply
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:201]: DEBUG: get_hdr_body : content_length=0
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/msg_parser.c:103]: found end of header
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [parser/sdp/sdp.c:696]: message body has length zero
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_reply.c:1155]: ->>>>>>>>> T_code=100, new_code=100
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: tm [t_reply.c:1657]: DEBUG: relay_reply: branch=0, save=0, relay=-1
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16836]: DEBUG: <core> [receive.c:290]: receive_msg: cleaning up
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:628]: SIP Request:
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:630]:  method:  <ACK>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:632]:  uri:     <sip:201 at sip.flygroup.st>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:634]:  version: <SIP/2.0>
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK-d8754z-c2d04a87758121e0-1---d8754z->; state=6
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 235, <rport> = <n/a>; state=17
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [receive.c:146]: After parse_msg...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [receive.c:187]: preparing to run routing scripts...
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=4cf90b7f894f6efcb610e24626b3f90c.bbfa
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=29
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [74]; uri=[sip:201 at sip.flygroup.st]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body ["201"<sip:201 at sip.flygroup.st>]
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: sl [sl_funcs.c:326]: SL local ACK found -> dropping it!
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Feb 29 20:42:31 vss /usr/sbin/kamailio[16835]: DEBUG: <core> [receive.c:290]: receive_msg: cleaning up
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:638]: SIP Reply  (status):
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:640]:  version: <SIP/2.0>
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:642]:  status:  <180>
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:644]:  reason:  <Ringing>
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK4a3b.2c691b46.0>; state=6
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 234, <received> = <88.198.48.183>; state=16
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [receive.c:146]: After parse_msg...
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=35 global id=34 T start=0xffffffff
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 234, <received> = <178.140.2.133>; state=6
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK-d8754z-c2d04a87758121e0-1---d8754z->; state=6
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:1288]: Found param type 235, <rport> = <14170>; state=16
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=22
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:528]: parse_headers: this is the second via
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=1231479872
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=29
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [47]; uri=[sip:201 at sip.flygroup.st]
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body ["201"<sip:201 at sip.flygroup.st>]
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:167]: get_hdr_field: cseq <CSeq>: <2> <INVITE>
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 45988 label 1689360066 branch 0
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched (T=0xaeeb1da0)!
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=35 global id=35 T end=0xaeeb1da0
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_reply.c:2040]: DEBUG: reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1)
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <script>: incoming reply
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:201]: DEBUG: get_hdr_body : content_length=0
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/msg_parser.c:103]: found end of header
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [parser/sdp/sdp.c:696]: message body has length zero
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_reply.c:1155]: ->>>>>>>>> T_code=100, new_code=180
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: tm [t_reply.c:1657]: DEBUG: relay_reply: branch=0, save=0, relay=0
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [msg_translator.c:1880]:  old size: 596, new size: 511
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [msg_translator.c:1898]: build_res_from_sip_res: copied size: orig:106, new: 21, rest: 490 msg=#012SIP/2.0 180 Ringing#015#012Via: SIP/2.0/UDP 192.168.1.2:14170;received=178.140.2.133;branch=z9hG4bK-d8754z-c2d04a87758121e0-1---d8754z-;rport=14170#015#012Record-Route: <sip:88.198.48.183;lr=on;nat=yes>#015#012From: "1004"<sip:1004 at 88.198.48.183>;tag=df2e65c2#015#012To: "201"<sip:201 at sip.flygroup.st>;tag=1231479872#015#012Call-ID: OTg0ZTlmN2M0MDc5OGE0ZDA3MzUzODhjZjgwNTg1NjE.#015#012CSeq: 2 INVITE#015#012Server: YATE/3.3.3#015#012Contact: <sip:201 at 213.141.134.77:5060>#015#012Allow: ACK, INVITE, BYE, CANCEL, REGISTER, REFER, OPTIONS, INFO#015#012Content-Length: 0#015#012#015#012
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Feb 29 20:42:33 vss /usr/sbin/kamailio[16834]: DEBUG: <core> [receive.c:290]: receive_msg: cleaning up
-------------- next part --------------
?U 178.140.2.133:14170 -> 88.198.48.183:5060
INVITE sip:201 at sip.flygroup.st SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.2:14170;branch=z9hG4bK-d8754z-57994a7b21367a07-1---d8754z-;rport.
Max-Forwards: 70.
Contact: <sip:1004 at 178.140.2.133:14170>.
To: "201"<sip:201 at sip.flygroup.st>.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 1 INVITE.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO.
Content-Type: application/sdp.
Supported: replaces.
User-Agent: X-Lite 4 release 4.1 stamp 63214.
Content-Length: 371.
.
v=0.
o=- 12975018042958094 1 IN IP4 192.168.1.2.
s=CounterPath X-Lite 4.1.
c=IN IP4 192.168.1.2.
t=0 0.
a=ice-ufrag:0d7dc8.
a=ice-pwd:6f9c146d982426d7fb87c05abc5c1415.
m=audio 52476 RTP/AVP 8 101.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.
a=candidate:1 1 UDP 659136 192.168.1.2 52476 typ host.
a=candidate:1 2 UDP 659134 192.168.1.2 52477 typ host.


U 88.198.48.183:5060 -> 178.140.2.133:14170
SIP/2.0 407 Proxy Authentication Required.
Via: SIP/2.0/UDP 192.168.1.2:14170;branch=z9hG4bK-d8754z-57994a7b21367a07-1---d8754z-;rport=14170;received=178.140.2.133.
To: "201"<sip:201 at sip.flygroup.st>;tag=4cf90b7f894f6efcb610e24626b3f90c.0a79.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 1 INVITE.
Proxy-Authenticate: Digest realm="88.198.48.183", nonce="T06Ab09Of0NOw+oyLx02bHSckh0HA33a".
Server: kamailio (3.2.0 (i386/linux)).
Content-Length: 0.
.


U 178.140.2.133:14170 -> 88.198.48.183:5060
ACK sip:201 at sip.flygroup.st SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.2:14170;branch=z9hG4bK-d8754z-57994a7b21367a07-1---d8754z-;rport.
Max-Forwards: 70.
To: "201"<sip:201 at sip.flygroup.st>;tag=4cf90b7f894f6efcb610e24626b3f90c.0a79.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 1 ACK.
Content-Length: 0.
.


U 178.140.2.133:14170 -> 88.198.48.183:5060
INVITE sip:201 at sip.flygroup.st SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.2:14170;branch=z9hG4bK-d8754z-bf1bfc1bb833d2e3-1---d8754z-;rport.
Max-Forwards: 70.
Contact: <sip:1004 at 178.140.2.133:14170>.
To: "201"<sip:201 at sip.flygroup.st>.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 2 INVITE.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO.
Content-Type: application/sdp.
Proxy-Authorization: Digest username="1004",realm="88.198.48.183",nonce="T06Ab09Of0NOw+oyLx02bHSckh0HA33a",uri="sip:201 at sip.flygroup.st",response="27da400b291e6ff4be3c6319badb5f3f",algorithm=MD5.
Supported: replaces.
User-Agent: X-Lite 4 release 4.1 stamp 63214.
Content-Length: 371.
.
v=0.
o=- 12975018042958094 1 IN IP4 192.168.1.2.
s=CounterPath X-Lite 4.1.
c=IN IP4 192.168.1.2.
t=0 0.
a=ice-ufrag:0d7dc8.
a=ice-pwd:6f9c146d982426d7fb87c05abc5c1415.
m=audio 52476 RTP/AVP 8 101.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.
a=candidate:1 1 UDP 659136 192.168.1.2 52476 typ host.
a=candidate:1 2 UDP 659134 192.168.1.2 52477 typ host.


U 88.198.48.183:5060 -> 178.140.2.133:14170
SIP/2.0 100 trying -- your call is important to us.
Via: SIP/2.0/UDP 192.168.1.2:14170;branch=z9hG4bK-d8754z-bf1bfc1bb833d2e3-1---d8754z-;rport=14170;received=178.140.2.133.
To: "201"<sip:201 at sip.flygroup.st>.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 2 INVITE.
Server: kamailio (3.2.0 (i386/linux)).
Content-Length: 0.
.


U 88.198.48.183:5060 -> 213.141.134.77:5060
INVITE sip:201 at sip.flygroup.st SIP/2.0.
Record-Route: <sip:88.198.48.183;lr=on;nat=yes>.
Via: SIP/2.0/UDP 88.198.48.183;branch=z9hG4bK701a.d21c5d52.0.
Via: SIP/2.0/UDP 192.168.1.2:14170;received=178.140.2.133;branch=z9hG4bK-d8754z-bf1bfc1bb833d2e3-1---d8754z-;rport=14170.
Max-Forwards: 69.
Contact: <sip:1004 at 178.140.2.133:14170>.
To: "201"<sip:201 at sip.flygroup.st>.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 2 INVITE.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO.
Content-Type: application/sdp.
Supported: replaces.
User-Agent: X-Lite 4 release 4.1 stamp 63214.
Content-Length: 393.
P-hint: outgoing call.
.
v=0.
o=- 12975018042958094 1 IN IP4 88.198.48.183.
s=CounterPath X-Lite 4.1.
c=IN IP4 88.198.48.183.
t=0 0.
a=ice-ufrag:0d7dc8.
a=ice-pwd:6f9c146d982426d7fb87c05abc5c1415.
m=audio 42150 RTP/AVP 8 101.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.
a=candidate:1 1 UDP 659136 192.168.1.2 52476 typ host.
a=candidate:1 2 UDP 659134 192.168.1.2 52477 typ host.
a=nortpproxy:yes.


U 88.198.48.183:5060 -> 178.140.2.133:14170
SIP/2.0 500 I'm terribly sorry, server error occurred (6/SL).
Via: SIP/2.0/UDP 192.168.1.2:14170;branch=z9hG4bK-d8754z-bf1bfc1bb833d2e3-1---d8754z-;rport=14170;received=178.140.2.133.
To: "201"<sip:201 at sip.flygroup.st>;tag=4cf90b7f894f6efcb610e24626b3f90c.92f4.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 2 INVITE.
Server: kamailio (3.2.0 (i386/linux)).
Content-Length: 0.
.


U 213.141.134.77:5060 -> 88.198.48.183:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP 88.198.48.183;branch=z9hG4bK701a.d21c5d52.0;received=88.198.48.183.
Via: SIP/2.0/UDP 192.168.1.2:14170;received=178.140.2.133;branch=z9hG4bK-d8754z-bf1bfc1bb833d2e3-1---d8754z-;rport=14170.
Record-Route: <sip:88.198.48.183;lr=on;nat=yes>.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
To: "201"<sip:201 at sip.flygroup.st>.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 2 INVITE.
Server: YATE/3.3.3.
Content-Length: 0.
.


U 178.140.2.133:14170 -> 88.198.48.183:5060
ACK sip:201 at sip.flygroup.st SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.2:14170;branch=z9hG4bK-d8754z-bf1bfc1bb833d2e3-1---d8754z-;rport.
Max-Forwards: 70.
To: "201"<sip:201 at sip.flygroup.st>;tag=4cf90b7f894f6efcb610e24626b3f90c.92f4.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 2 ACK.
Content-Length: 0.
.


U 213.141.134.77:5060 -> 88.198.48.183:5060
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 88.198.48.183;branch=z9hG4bK701a.d21c5d52.0;received=88.198.48.183.
Via: SIP/2.0/UDP 192.168.1.2:14170;received=178.140.2.133;branch=z9hG4bK-d8754z-bf1bfc1bb833d2e3-1---d8754z-;rport=14170.
Record-Route: <sip:88.198.48.183;lr=on;nat=yes>.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
To: "201"<sip:201 at sip.flygroup.st>;tag=1098842424.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 2 INVITE.
Server: YATE/3.3.3.
Contact: <sip:201 at 213.141.134.77:5060>.
Allow: ACK, INVITE, BYE, CANCEL, REGISTER, REFER, OPTIONS, INFO.
Content-Length: 0.
.


U 88.198.48.183:5060 -> 178.140.2.133:14170
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 192.168.1.2:14170;received=178.140.2.133;branch=z9hG4bK-d8754z-bf1bfc1bb833d2e3-1---d8754z-;rport=14170.
Record-Route: <sip:88.198.48.183;lr=on;nat=yes>.
From: "1004"<sip:1004 at 88.198.48.183>;tag=51caeb43.
To: "201"<sip:201 at sip.flygroup.st>;tag=1098842424.
Call-ID: YTY1ZDFhOTNlNjM3NTM0OWFlMWE0NTljYzM5ZDRlMjA..
CSeq: 2 INVITE.
Server: YATE/3.3.3.
Contact: <sip:201 at 213.141.134.77:5060>.
Allow: ACK, INVITE, BYE, CANCEL, REGISTER, REFER, OPTIONS, INFO.
Content-Length: 0.


More information about the sr-users mailing list