Hi all,

I am using kamailio 3.1.2 and I have an issue with failure route. Every-time kamailio enters the failure route an internal error is triggered and kamailio sends a  500 SIP error message:  SIP/2.0 500 I'm terribly sorry, server error occurred (1/TM).
Just for testing purpose I "downgraded" my failure route configuration to a very simple one. But even so I am always getting the same error and it doesn't matter if it is a busy, a cancel, a moved temporarily...
If I don't arm the failure route I don't get any error.

Any idea what might be wrong? Is this a bug?

This is the relevant part of my configuration :

route[RELAY] {
        if (is_method("INVITE")) {
                t_on_failure("FAILTWO");
        }
        if (!t_relay()) {
                sl_reply_error();
        }
        exit;
}

failure_route[FAILTWO] {
        xlog("L_INFO", "FAILUREFROMEXTERNAL: Entered\n");

        if (t_check_status("302")) {
                xlog("L_INFO", "FAILUREFROMEXTERNAL: 302 detected\n");
        } else {
                xlog("L_INFO", "FAILUREFROMEXTERNAL: other detected\n");
        }

 xlog("L_INFO", "FAILUREFROMEXTERNAL: Exiting\n");

}


Here is the Debug output from a busy message got by kamailio and the internal error generated:


Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 860) called from <core>: receive.c: receive_msg(120)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 860) returns address 0x837e300 frag. 0x837e2e8 (size=860) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:640]: SIP Reply  (status):
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:642]:  version: <SIP/2.0>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:644]:  status:  <486>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:646]:  reason:  <Busy Here>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/msg_parser.c: parse_headers(341)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f29c frag. 0x837f284 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 116) called from <core>: parser/msg_parser.c: get_hdr_field(130)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 116) returns address 0x837e68c frag. 0x837e674 (size=116) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/parse_via.c: parse_via(2168)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f2ec frag. 0x837f2d4 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/parse_via.c:1287]: Found param type 232, <branch> = <z9hG4bK54f1.2f69b061.0>; state=16
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/parse_via.c:2300]: end of header reached, state=5
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [receive.c:145]: After parse_msg...
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_lookup.c:1081]: DEBUG: t_check_msg: msg id=19 global id=18 T start=0xffffffff
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/msg_parser.c: parse_headers(341)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f33c frag. 0x837f324 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 116) called from <core>: parser/msg_parser.c: get_hdr_field(130)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 116) returns address 0x837e820 frag. 0x837e808 (size=224) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/parse_via.c: parse_via(2168)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f38c frag. 0x837f374 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/parse_via.c:1287]: Found param type 232, <branch> = <z9hG4bK1f04fb19>; state=6
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/parse_via.c: parse_via(2168)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f6e0 frag. 0x837f6c8 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/parse_via.c:1287]: Found param type 235, <rport> = <5060>; state=16
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/parse_via.c:2300]: end of header reached, state=5
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=22
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:528]: parse_headers: this is the second via
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/msg_parser.c: parse_headers(341)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f730 frag. 0x837f718 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/msg_parser.c: parse_headers(341)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f820 frag. 0x837f808 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 224) called from <core>: parser/msg_parser.c: get_hdr_field(170)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 224) returns address 0x837e9c8 frag. 0x837e9b0 (size=224) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 24) called from <core>: parser/parse_to.c: parse_to_param(283)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 24) returns address 0x837e930 frag. 0x837e918 (size=24) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/parse_to.c:174]: DEBUG: add_param: tag=i76l2xu48i
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/parse_to.c:803]: end of header reached, state=29
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [41]; uri=[sip:+4773411459@umb.no
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body [<sip:+4773411459@umb.no>]
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/msg_parser.c: parse_headers(341)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f780 frag. 0x837f768 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/msg_parser.c: parse_headers(341)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837f7d0 frag. 0x837f7b8 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 24) called from <core>: parser/msg_parser.c: get_hdr_field(149)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 24) returns address 0x837eb78 frag. 0x837eb60 (size=24) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [parser/msg_parser.c:167]: get_hdr_field: cseq <CSeq>: <102> <INVITE>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_lookup.c:967]: DEBUG: t_reply_matching: hash 8005 label 369858290 branch 0
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_lookup.c:1018]: DEBUG: t_reply_matching: reply matched (T=0xb59ec41c)!
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_lookup.c:1150]: DEBUG: t_check_msg: msg id=19 global id=19 T end=0xb59ec41c
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_reply.c:2014]: DEBUG: reply_received: org. status uas=180, uac[0]=180 local=0 is_invite=1)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0xb5881000, 1010) called from tm: t_msgbuilder.c: build_local_reparse(368)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0xb5881000, 1012) returns address 0xb59ed5a0 frag. 0xb59ed588 (size=1012) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0xb5881000, 0xb59ed5a0), called from tm: t_reply.c: reply_received(2064)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0xb59ed588 alloc'ed from tm: t_msgbuilder.c: build_local_reparse(368)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_reply.c:1129]: ->>>>>>>>> T_code=180, new_code=486
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 43) called from tm: t_reply.c: fake_req(854)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 44) returns address 0x837f8c0 frag. 0x837f8a8 (size=44) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: INFO: <script>: FAILUREFROMEXTERNAL: Entered
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_lookup.c:1081]: DEBUG: t_check_msg: msg id=16 global id=16 T start=0xb59ec41c
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_lookup.c:1153]: DEBUG: t_check_msg: T already found!
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [tm.c:967]: DEBUG:t_check_status: checked status is <486>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: INFO: <script>: FAILUREFROMEXTERNAL: other detected
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: INFO: <script>: FAILUREFROMEXTERNAL: Exiting
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0x82bcfe0, 0x837f8c0), called from tm: t_reply.c: free_faked_req(885)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0x837f8a8 alloc'ed from tm: t_reply.c: fake_req(854)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [msg_translator.c:204]: check_via_address(158.36.141.13, 158.36.141.13, 0)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 12) called from <core>: msg_translator.c: rport_builder(390)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 12) returns address 0x837f008 frag. 0x837eff0 (size=12) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 392) called from <core>: msg_translator.c: build_res_buf_from_sip_req(2032)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 392) returns address 0x838007c frag. 0x8380064 (size=564) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0x82bcfe0, 0x837f008), called from <core>: msg_translator.c: build_res_buf_from_sip_req(2192)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0x837eff0 alloc'ed from <core>: msg_translator.c: rport_builder(390)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0xb5881000, 0xb59ed29c), called from <core>: mem/shm_mem.c: sh_realloc(82)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0xb59ed284 alloc'ed from <core>: mem/shm_mem.c: sh_realloc(83)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:366]: qm_malloc(0xb5881000, 391) called from <core>: mem/shm_mem.c: sh_realloc(83)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:406]: qm_malloc(0xb5881000, 392) returns address 0xb59ea720 frag. 0xb59ea708 (size=500) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_reply.c:1470]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_reply.c:659]: DEBUG: reply sent out. buf=0x838007c: SIP/2.0 500 I'm terr..., shmem=0xb59ea720: SIP/2.0 500 I'm terr
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0x82bcfe0, 0x838007c), called from tm: t_reply.c: _reply_light(668)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 860) called from <core>: receive.c: receive_msg(120)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0x8380064 alloc'ed from <core>: msg_translator.c: build_res_buf_from_sip_req(2032)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 860) returns address 0x837e300 frag. 0x837e2e8 (size=860) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_reply.c:669]: DEBUG: _reply_light: finished
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/msg_parser.c:630]: SIP Request:
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_reply.c:1631]: DEBUG: relay_reply: branch=0, save=0, relay=-1
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/msg_parser.c:632]:  method:  <ACK>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: tm [t_reply.c:1470]: DEBUG: cleanup_uac_timers: RETR/FR timers reset
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/msg_parser.c:634]:  uri:     <sip:+4773411459@umb.no>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [usr_avp.c:646]: DEBUG:destroy_avp_list: destroying list (nil)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/msg_parser.c:636]:  version: <SIP/2.0>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [usr_avp.c:646]: DEBUG:destroy_avp_list: destroying list (nil)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/msg_parser.c: parse_headers(341)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [usr_avp.c:646]: DEBUG:destroy_avp_list: destroying list (nil)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837ed00 frag. 0x837ece8 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [usr_avp.c:646]: DEBUG:destroy_avp_list: destroying list (nil)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 116) called from <core>: parser/msg_parser.c: get_hdr_field(130)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [usr_avp.c:646]: DEBUG:destroy_avp_list: destroying list (nil)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 116) returns address 0x837e68c frag. 0x837e674 (size=116) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [usr_avp.c:646]: DEBUG:destroy_avp_list: destroying list (nil)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/parse_via.c: parse_via(2168)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: DEBUG: <core> [receive.c:289]: receive_msg: cleaning up
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837ecb0 frag. 0x837ec98 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0x82bcfe0, 0x837f2ec), called from <core>: parser/parse_via.c: free_via_param_list(2387)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/parse_via.c:1287]: Found param type 232, <branch> = <z9hG4bK1f04fb19>; state=6
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0x837f2d4 alloc'ed from <core>: parser/parse_via.c: parse_via(2168)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 32) called from <core>: parser/parse_via.c: parse_via(2168)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0x82bcfe0, 0x837e68c), called from <core>: parser/parse_via.c: free_via_list(2399)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 32) returns address 0x837ec60 frag. 0x837ec48 (size=32) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0x837e674 alloc'ed from <core>: parser/msg_parser.c: get_hdr_field(130)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/parse_via.c:1287]: Found param type 235, <rport> = <n/a>; state=17
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0x82bcfe0, 0x837f29c), called from <core>: parser/hf.c: free_hdr_field_lst(251)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15014]: <core> [mem/q_malloc.c:366]: qm_malloc(0x82bcfe0, 860) called from <core>: receive.c: receive_msg(120)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/parse_via.c:2300]: end of header reached, state=5
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0x837f284 alloc'ed from <core>: parser/msg_parser.c: parse_headers(341)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15014]: <core> [mem/q_malloc.c:406]: qm_malloc(0x82bcfe0, 860) returns address 0x8380078 frag. 0x8380060 (size=860) on 1 -th hit
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0x82bcfe0, 0x837f38c), called from <core>: parser/parse_via.c: free_via_param_list(2387)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15014]: DEBUG: <core> [parser/msg_parser.c:640]: SIP Reply  (status):
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0x837f374 alloc'ed from <core>: parser/parse_via.c: parse_via(2168)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15014]: DEBUG: <core> [parser/msg_parser.c:642]:  version: <SIP/2.0>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [receive.c:145]: After parse_msg...
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:428]: qm_free(0x82bcfe0, 0x837f6e0), called from <core>: parser/parse_via.c: free_via_param_list(2387)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15014]: DEBUG: <core> [parser/msg_parser.c:644]:  status:  <486>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: <core> [receive.c:186]: preparing to run routing scripts...
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15016]: <core> [mem/q_malloc.c:450]: qm_free: freeing frag. 0x837f6c8 alloc'ed from <core>: parser/parse_via.c: parse_via(2168)
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15014]: DEBUG: <core> [parser/msg_parser.c:646]:  reason:  <Busy Here>
Mar 22 13:48:57 sip /usr/ipbx/kamailio/sbin/kamailio[15015]: DEBUG: sl [sl_funcs.c:307]: DEBUG : sl_filter_ACK: to late to be a local ACK!


Regards,

Miguel Baptista