I have a problem where a call comes in and causing a 404 Not Found to be replied.
the script does not have a 404 reply set in it anywhere
unfortunately I cannot get the script to produce logs, hence my other post. But its making it tricky to find the source of the 404. following the script it should not be producing a 404, but doing a relay. I suspect there is something wrong with the tm or its not getting a result it needs.
It looks like the transaction module is triggering thge 404 somehow and Its showing up in debug below.
Does anyone have any ideas why i'm getting a 404?
version 5.6.6
Regards, Richard
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=3/28700 T start=0xffffffffffffffff Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430] Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1172]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T end=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430] Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=0x7faf932f42c0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 626) called from tm: t_reply.c: _reply_light(488) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0468: SIP/2.0 404 Not Foun Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
Try add this string to yout config file
loadmodule "debugger.so" modparam("debugger", "cfgtrace", 1) modparam("debugger", "log_level_name", "exec")
And start Kamailio from the command line using command like ``` kamailio -DD -E -ddd ``` This will produce a lot of debug info.
On Tue, Apr 22, 2025 at 6:42 PM Richard Robson via sr-users < sr-users@lists.kamailio.org> wrote:
I have a problem where a call comes in and causing a 404 Not Found to be replied.
the script does not have a 404 reply set in it anywhere
unfortunately I cannot get the script to produce logs, hence my other post. But its making it tricky to find the source of the 404. following the script it should not be producing a 404, but doing a relay. I suspect there is something wrong with the tm or its not getting a result it needs.
It looks like the transaction module is triggering thge 404 somehow and Its showing up in debug below.
Does anyone have any ideas why i'm getting a 404?
version 5.6.6
Regards, Richard
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=3/28700 T start=0xffffffffffffffff Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430] Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1172]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T end=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430] Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=0x7faf932f42c0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 626) called from tm: t_reply.c: _reply_light(488) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0468: SIP/2.0 404 Not Foun Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
Hello,
it should be no internal 404 response from tm, maybe from other modules that you use. You can load the debugger module and enable cfgtrace to see what actions from kamailio.cfg are executed and try to figure out which one is generating the 404.
Cheers, Daniel
On 22.04.25 17:00, Richard Robson via sr-users wrote:
I have a problem where a call comes in and causing a 404 Not Found to be replied.
the script does not have a 404 reply set in it anywhere
unfortunately I cannot get the script to produce logs, hence my other post. But its making it tricky to find the source of the 404. following the script it should not be producing a 404, but doing a relay. I suspect there is something wrong with the tm or its not getting a result it needs.
It looks like the transaction module is triggering thge 404 somehow and Its showing up in debug below.
Does anyone have any ideas why i'm getting a 404?
version 5.6.6
Regards, Richard
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=3/28700 T start=0xffffffffffffffff Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1172]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T end=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=0x7faf932f42c0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 626) called from tm: t_reply.c: _reply_light(488) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0468: SIP/2.0 404 Not Foun Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
this is the trace I get. I don't see much more than I posted earlire( I had filtered on tm)
in this one I see acc module, but no acc is loaded
acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404
this line is the first for noting a 404, could it be an accounting issue?
Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} registrar [lookup.c:327]: lookup_helper(): '+44{{redacted}}' Not found in usrloc Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=144/28700 global id=144/28700 T start=(nil) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=33510, isACK=0 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK-67937f9e-2572ae7-247f969e-7f247bd48818] Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) returns address 0x7faf932f42c0 frag. 0x7faf932f4288 (size=11976) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) returns address 0x7faf932f1ac8 frag. 0x7faf932f1a90 (size=40) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 5600) called from core: core/sip_msg_clone.c: sip_msg_shm_clone(495) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 5600) returns address 0x7faf932f2220 frag. 0x7faf932f21e8 (size=5848) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/crypto/md5utils.c:67]: MD5StringArray(): MD5 calculated: f3e25343cefec184ccc229927fced78b Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 10) called from core: core/ut.c: as_asciiz(187) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 16) returns address 0x7faf977bf660 frag. 0x7faf977bf628 (size=16) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=144/28700 global id=144/28700 T start=0x7faf932f42c0 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 59) called from core: core/msg_translator.c: received_builder(331) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 64) returns address 0x7faf977bf6d8 frag. 0x7faf977bf6a0 (size=64) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 12) called from core: core/msg_translator.c: rport_builder(363) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 16) returns address 0x7faf977bf780 frag. 0x7faf977bf748 (size=16) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 470) called from core: core/msg_translator.c: build_res_buf_from_sip_req(2525) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 472) returns address 0x7faf977bf7f8 frag. 0x7faf977bf7c0 (size=472) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf6d8), called from core: core/msg_translator.c: build_res_buf_from_sip_req(2697) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf6a0 alloc'ed from core: core/msg_translator.c: received_builder(331) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf780), called from core: core/msg_translator.c: build_res_buf_from_sip_req(2699) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf748 alloc'ed from core: core/msg_translator.c: rport_builder(363) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 629) called from tm: t_reply.c: _reply_light(488) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 632) returns address 0x7faf932f0c88 frag. 0x7faf932f0c50 (size=632) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:111]: env_set_reason(): reason[Not Found] Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:443]: should_acc_reply(): probing acc state - code: 404 flags: 0x2 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:449]: should_acc_reply(): failed acc is off Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0c88: SIP/2.0 404 Not Foun Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf7c0 alloc'ed from core: core/msg_translator.c: build_res_buf_from_sip_req(2525) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
On 22/04/2025 18:16, Daniel-Constantin Mierla wrote:
Hello,
it should be no internal 404 response from tm, maybe from other modules that you use. You can load the debugger module and enable cfgtrace to see what actions from kamailio.cfg are executed and try to figure out which one is generating the 404.
Cheers, Daniel
On 22.04.25 17:00, Richard Robson via sr-users wrote:
I have a problem where a call comes in and causing a 404 Not Found to be replied.
the script does not have a 404 reply set in it anywhere
unfortunately I cannot get the script to produce logs, hence my other post. But its making it tricky to find the source of the 404. following the script it should not be producing a 404, but doing a relay. I suspect there is something wrong with the tm or its not getting a result it needs.
It looks like the transaction module is triggering thge 404 somehow and Its showing up in debug below.
Does anyone have any ideas why i'm getting a 404?
version 5.6.6
Regards, Richard
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=3/28700 T start=0xffffffffffffffff Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1172]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T end=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=0x7faf932f42c0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 626) called from tm: t_reply.c: _reply_light(488) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0468: SIP/2.0 404 Not Foun Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
I have solved thanks.
there was a problem with the config files
thanks
On 22/04/2025 19:16, Richard Robson via sr-users wrote:
this is the trace I get. I don't see much more than I posted earlire( I had filtered on tm)
in this one I see acc module, but no acc is loaded
acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404
this line is the first for noting a 404, could it be an accounting issue?
Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} registrar [lookup.c:327]: lookup_helper(): '+44{{redacted}}' Not found in usrloc Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=144/28700 global id=144/28700 T start=(nil) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=33510, isACK=0 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-67937f9e-2572ae7-247f969e-7f247bd48818]
Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) returns address 0x7faf932f42c0 frag. 0x7faf932f4288 (size=11976) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) returns address 0x7faf932f1ac8 frag. 0x7faf932f1a90 (size=40) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 5600) called from core: core/sip_msg_clone.c: sip_msg_shm_clone(495) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 5600) returns address 0x7faf932f2220 frag. 0x7faf932f21e8 (size=5848) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/crypto/md5utils.c:67]: MD5StringArray(): MD5 calculated: f3e25343cefec184ccc229927fced78b Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 10) called from core: core/ut.c: as_asciiz(187) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 16) returns address 0x7faf977bf660 frag. 0x7faf977bf628 (size=16) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=144/28700 global id=144/28700 T start=0x7faf932f42c0 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 59) called from core: core/msg_translator.c: received_builder(331) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 64) returns address 0x7faf977bf6d8 frag. 0x7faf977bf6a0 (size=64) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 12) called from core: core/msg_translator.c: rport_builder(363) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 16) returns address 0x7faf977bf780 frag. 0x7faf977bf748 (size=16) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 470) called from core: core/msg_translator.c: build_res_buf_from_sip_req(2525) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 472) returns address 0x7faf977bf7f8 frag. 0x7faf977bf7c0 (size=472) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf6d8), called from core: core/msg_translator.c: build_res_buf_from_sip_req(2697) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf6a0 alloc'ed from core: core/msg_translator.c: received_builder(331) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf780), called from core: core/msg_translator.c: build_res_buf_from_sip_req(2699) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf748 alloc'ed from core: core/msg_translator.c: rport_builder(363) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 629) called from tm: t_reply.c: _reply_light(488) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 632) returns address 0x7faf932f0c88 frag. 0x7faf932f0c50 (size=632) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:111]: env_set_reason(): reason[Not Found] Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:443]: should_acc_reply(): probing acc state - code: 404 flags: 0x2 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:449]: should_acc_reply(): failed acc is off Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0c88: SIP/2.0 404 Not Foun Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf7c0 alloc'ed from core: core/msg_translator.c: build_res_buf_from_sip_req(2525) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
On 22/04/2025 18:16, Daniel-Constantin Mierla wrote:
Hello,
it should be no internal 404 response from tm, maybe from other modules that you use. You can load the debugger module and enable cfgtrace to see what actions from kamailio.cfg are executed and try to figure out which one is generating the 404.
Cheers, Daniel
On 22.04.25 17:00, Richard Robson via sr-users wrote:
I have a problem where a call comes in and causing a 404 Not Found to be replied.
the script does not have a 404 reply set in it anywhere
unfortunately I cannot get the script to produce logs, hence my other post. But its making it tricky to find the source of the 404. following the script it should not be producing a 404, but doing a relay. I suspect there is something wrong with the tm or its not getting a result it needs.
It looks like the transaction module is triggering thge 404 somehow and Its showing up in debug below.
Does anyone have any ideas why i'm getting a 404?
version 5.6.6
Regards, Richard
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=3/28700 T start=0xffffffffffffffff Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1172]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T end=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=0x7faf932f42c0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 626) called from tm: t_reply.c: _reply_light(488) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0468: SIP/2.0 404 Not Foun Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
Well, pray tell for posterity!
On Apr 23, 2025, at 8:46 AM, Richard Robson via sr-users sr-users@lists.kamailio.org wrote:
I have solved thanks.
there was a problem with the config files
thanks
On 22/04/2025 19:16, Richard Robson via sr-users wrote:
this is the trace I get. I don't see much more than I posted earlire( I had filtered on tm)
in this one I see acc module, but no acc is loaded
acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404
this line is the first for noting a 404, could it be an accounting issue?
Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} registrar [lookup.c:327]: lookup_helper(): '+44{{redacted}}' Not found in usrloc Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=144/28700 global id=144/28700 T start=(nil) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=33510, isACK=0 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK-67937f9e-2572ae7-247f969e-7f247bd48818] Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) returns address 0x7faf932f42c0 frag. 0x7faf932f4288 (size=11976) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) returns address 0x7faf932f1ac8 frag. 0x7faf932f1a90 (size=40) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 5600) called from core: core/sip_msg_clone.c: sip_msg_shm_clone(495) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 5600) returns address 0x7faf932f2220 frag. 0x7faf932f21e8 (size=5848) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/crypto/md5utils.c:67]: MD5StringArray(): MD5 calculated: f3e25343cefec184ccc229927fced78b Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 10) called from core: core/ut.c: as_asciiz(187) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 16) returns address 0x7faf977bf660 frag. 0x7faf977bf628 (size=16) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=144/28700 global id=144/28700 T start=0x7faf932f42c0 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 59) called from core: core/msg_translator.c: received_builder(331) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 64) returns address 0x7faf977bf6d8 frag. 0x7faf977bf6a0 (size=64) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 12) called from core: core/msg_translator.c: rport_builder(363) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 16) returns address 0x7faf977bf780 frag. 0x7faf977bf748 (size=16) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 470) called from core: core/msg_translator.c: build_res_buf_from_sip_req(2525) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 472) returns address 0x7faf977bf7f8 frag. 0x7faf977bf7c0 (size=472) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf6d8), called from core: core/msg_translator.c: build_res_buf_from_sip_req(2697) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf6a0 alloc'ed from core: core/msg_translator.c: received_builder(331) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf780), called from core: core/msg_translator.c: build_res_buf_from_sip_req(2699) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf748 alloc'ed from core: core/msg_translator.c: rport_builder(363) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 629) called from tm: t_reply.c: _reply_light(488) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 632) returns address 0x7faf932f0c88 frag. 0x7faf932f0c50 (size=632) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:111]: env_set_reason(): reason[Not Found] Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:443]: should_acc_reply(): probing acc state - code: 404 flags: 0x2 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:449]: should_acc_reply(): failed acc is off Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0c88: SIP/2.0 404 Not Foun Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf7c0 alloc'ed from core: core/msg_translator.c: build_res_buf_from_sip_req(2525) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
On 22/04/2025 18:16, Daniel-Constantin Mierla wrote:
Hello,
it should be no internal 404 response from tm, maybe from other modules that you use. You can load the debugger module and enable cfgtrace to see what actions from kamailio.cfg are executed and try to figure out which one is generating the 404.
Cheers, Daniel
On 22.04.25 17:00, Richard Robson via sr-users wrote:
I have a problem where a call comes in and causing a 404 Not Found to be replied.
the script does not have a 404 reply set in it anywhere
unfortunately I cannot get the script to produce logs, hence my other post. But its making it tricky to find the source of the 404. following the script it should not be producing a 404, but doing a relay. I suspect there is something wrong with the tm or its not getting a result it needs.
It looks like the transaction module is triggering thge 404 somehow and Its showing up in debug below.
Does anyone have any ideas why i'm getting a 404?
version 5.6.6
Regards, Richard
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=3/28700 T start=0xffffffffffffffff Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1172]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T end=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=0x7faf932f42c0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 626) called from tm: t_reply.c: _reply_light(488) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0468: SIP/2.0 404 Not Foun Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
For posterity's sake, the config file was symlinked to a different file and the symlink had been messed up so we were altering the wrong file.
R
On 23/04/2025 14:05, Alex Balashov wrote:
Well, pray tell for posterity!
On Apr 23, 2025, at 8:46 AM, Richard Robson via sr-users sr-users@lists.kamailio.org wrote:
I have solved thanks.
there was a problem with the config files
thanks
On 22/04/2025 19:16, Richard Robson via sr-users wrote:
this is the trace I get. I don't see much more than I posted earlire( I had filtered on tm)
in this one I see acc module, but no acc is loaded
acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404
this line is the first for noting a 404, could it be an accounting issue?
Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} registrar [lookup.c:327]: lookup_helper(): '+44{{redacted}}' Not found in usrloc Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=144/28700 global id=144/28700 T start=(nil) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=33510, isACK=0 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK-67937f9e-2572ae7-247f969e-7f247bd48818] Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) returns address 0x7faf932f42c0 frag. 0x7faf932f4288 (size=11976) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) returns address 0x7faf932f1ac8 frag. 0x7faf932f1a90 (size=40) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 5600) called from core: core/sip_msg_clone.c: sip_msg_shm_clone(495) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 5600) returns address 0x7faf932f2220 frag. 0x7faf932f21e8 (size=5848) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/crypto/md5utils.c:67]: MD5StringArray(): MD5 calculated: f3e25343cefec184ccc229927fced78b Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 10) called from core: core/ut.c: as_asciiz(187) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 16) returns address 0x7faf977bf660 frag. 0x7faf977bf628 (size=16) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=144/28700 global id=144/28700 T start=0x7faf932f42c0 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 59) called from core: core/msg_translator.c: received_builder(331) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 64) returns address 0x7faf977bf6d8 frag. 0x7faf977bf6a0 (size=64) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 12) called from core: core/msg_translator.c: rport_builder(363) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 16) returns address 0x7faf977bf780 frag. 0x7faf977bf748 (size=16) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf976b5010, 470) called from core: core/msg_translator.c: build_res_buf_from_sip_req(2525) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf976b5010, 472) returns address 0x7faf977bf7f8 frag. 0x7faf977bf7c0 (size=472) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf6d8), called from core: core/msg_translator.c: build_res_buf_from_sip_req(2697) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf6a0 alloc'ed from core: core/msg_translator.c: received_builder(331) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf780), called from core: core/msg_translator.c: build_res_buf_from_sip_req(2699) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf748 alloc'ed from core: core/msg_translator.c: rport_builder(363) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 629) called from tm: t_reply.c: _reply_light(488) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:432]: qm_malloc(): qm_malloc(0x7faf9301f000, 632) returns address 0x7faf932f0c88 frag. 0x7faf932f0c50 (size=632) on 1 -th hit Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:111]: env_set_reason(): reason[Not Found] Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:443]: should_acc_reply(): probing acc state - code: 404 flags: 0x2 Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} acc [acc_logic.c:449]: should_acc_reply(): failed acc is off Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0c88: SIP/2.0 404 Not Foun Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} <core> [core/mem/q_malloc.c:543]: qm_free(): freeing frag. 0x7faf977bf7c0 alloc'ed from core: core/msg_translator.c: build_res_buf_from_sip_req(2525) Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 18:03:27 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE 139a2a7e-4dedbaf8-2572ae7-7f246c685bc8-2403050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
On 22/04/2025 18:16, Daniel-Constantin Mierla wrote:
Hello,
it should be no internal 404 response from tm, maybe from other modules that you use. You can load the debugger module and enable cfgtrace to see what actions from kamailio.cfg are executed and try to figure out which one is generating the 404.
Cheers, Daniel
On 22.04.25 17:00, Richard Robson via sr-users wrote:
I have a problem where a call comes in and causing a 404 Not Found to be replied.
the script does not have a 404 reply set in it anywhere
unfortunately I cannot get the script to produce logs, hence my other post. But its making it tricky to find the source of the 404. following the script it should not be producing a 404, but doing a relay. I suspect there is something wrong with the tm or its not getting a result it needs.
It looks like the transaction module is triggering thge 404 somehow and Its showing up in debug below.
Does anyone have any ideas why i'm getting a 404?
version 5.6.6
Regards, Richard
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=3/28700 T start=0xffffffffffffffff Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1172]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T end=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1394]: t_newtran(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=(nil) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:503]: t_lookup_request(): start searching: hash=60776, isACK=0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:463]: matching_3261(): RFC3261 transaction matching failed
- via branch [z9hG4bK-2e6a90ab-1bfb9be-d4ed8e5d-7f0300025430]
Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:703]: t_lookup_request(): no transaction found Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 8336) called from tm: h_table.c: build_cell(336) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:342]: run_reqin_callbacks_internal(): trans=0x7faf932f42c0, callback type 1, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 40) called from tm: t_hooks.c: insert_tmcb(134) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1100]: t_check_msg(): msg (0x7faf977b64b0) id=4/28700 global id=4/28700 T start=0x7faf932f42c0 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_lookup.c:1176]: t_check_msg(): T (0x7faf932f42c0) already found for msg (0x7faf977b64b0)! Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:385]: qm_malloc(): qm_malloc(0x7faf9301f000, 626) called from tm: t_reply.c: _reply_light(488) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:1768]: cleanup_uac_timers(): RETR/FR timers reset Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7faf932f42c0, callback type 512, id 0 entered Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} acc [acc_logic.c:721]: tmcb_func(): acc callback called for t(0x7faf932f42c0) event type 512, reply code 404 Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:625]: _reply_light(): reply sent out - buf=0x7faf977bf7f8: SIP/2.0 404 Not Foun... shmem=0x7faf932f0468: SIP/2.0 404 Not Foun Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_funcs.c:122]: put_on_wait(): put T [0x7faf932f42c0] on wait Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} <core> [core/mem/q_malloc.c:495]: qm_free(): qm_free(0x7faf976b5010, 0x7faf977bf7f8), called from tm: t_reply.c: _reply_light(635) Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} tm [t_reply.c:636]: _reply_light(): finished Apr 22 14:50:51 kamai01 kamailio[28700]: DEBUG: {1 1 INVITE afc0c04-9d8bc3d-1bfb9be-7f02f033b5f0-2406050a-13d8-7225} sl [sl.c:288]: send_reply(): reply in stateful mode (tm)
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
Kamailio - Users Mailing List - Non Commercial Discussions -- sr-users@lists.kamailio.org To unsubscribe send an email to sr-users-leave@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender!
On Apr 23, 2025, at 9:43 AM, Richard Robson richard@rikrobson.co.uk wrote:
For posterity's sake, the config file was symlinked to a different file and the symlink had been messed up so we were altering the wrong file.
Ah, yes. My sympathies, been there many times. :-)
-
Alex Balashov -
Daniel-Constantin Mierla -
Richard Robson -
Sergey Safarov