[SR-Users] log_prefix missing

[Joonas Kamailio]

Dear List,

I am having a problem where log_prefix is missing on acc_cdr log row 
when call is canceled.

Here is an example of two lines of acc_cdr log file, first one is 
completed normally and second one is canceled before 200 OK:

Mar  6 12:04:48 edge3 kamailio[27669]: NOTICE: {2 1 BYE 
23d4e04c0c79ff6950accbd1706b91b1 at example.com} acc [acc_cdr.c:367]: 
log_write_cdr(): start_time=1551873885.018; end_time=1551873888.379; 
duration=3.361;
Mar  6 12:03:40 edge3 kamailio[27707]: NOTICE: acc [acc_cdr.c:367]: 
log_write_cdr(): start_time=1551873809.148; end_time=1551873809.148; 
duration=0;

I tried to take a debug on this matter and noticed that log_prefix is 
lost at this point:

Mar  6 13:54:25 edge3 kamailio[10665]: DEBUG: {1 102 INVITE 
6917f51f0955bd8b3a1aa62361a473ce at example.com} tm [t_lookup.c:1019]: 
t_check_msg(): msg (0x7f1a889a8a30) id=0 global id=0 T start=0x7f1a8899b450
Mar  6 13:54:25 edge3 kamailio[10665]: DEBUG: {1 102 INVITE 
6917f51f0955bd8b3a1aa62361a473ce at example.com} tm [t_lookup.c:1092]: 
t_check_msg(): T (0x7f1a8899b450) already found for msg (0x7f1a889a8a30)!
Mar  6 13:54:25 edge3 kamailio[10665]: DEBUG: {1 102 INVITE 
6917f51f0955bd8b3a1aa62361a473ce at example.com} dialog [dlg_var.c:97]: 
cb_dlg_locals_reset(): resetting the local dialog shortcuts on script 
callback: 2147483650
Mar  6 13:54:25 edge3 kamailio[10665]: DEBUG: tm [t_reply.c:1806]: 
relay_reply(): branch=0, save=0, relay=0 icode=0 msg status=487
Mar  6 13:54:25 edge3 kamailio[10665]: DEBUG: <core> 
[core/msg_translator.c:2288]: generate_res_buf_from_sip_res(): old size: 
483, new size: 399

On normal call the prefix stays in intact:

Mar  6 13:57:29 edge3 kamailio[10670]: DEBUG: {2 103 BYE 
03cd8f3464d42b7b01966e6b1154eedc at example.com} tm [t_lookup.c:1088]: 
t_check_msg(): msg (0x7f1a95baad10) id=2 global id=2 T end=0x7f1a8899f8f0
Mar  6 13:57:29 edge3 kamailio[10670]: DEBUG: {2 103 BYE 
03cd8f3464d42b7b01966e6b1154eedc at example.com} tm [t_reply.c:2237]: 
reply_received(): org. status uas=0, uac[0]=0 local=0 is_invite=0)
Mar  6 13:57:29 edge3 kamailio[10670]: DEBUG: {2 103 BYE 
03cd8f3464d42b7b01966e6b1154eedc at example.com} tm [t_reply.c:1263]: 
t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
Mar  6 13:57:29 edge3 kamailio[10670]: DEBUG: {2 103 BYE 
03cd8f3464d42b7b01966e6b1154eedc at example.com} tm [t_reply.c:1806]: 
relay_reply(): branch=0, save=0, relay=0 icode=0 msg status=200
Mar  6 13:57:29 edge3 kamailio[10670]: DEBUG: {2 103 BYE 
03cd8f3464d42b7b01966e6b1154eedc at example.com} <core> 
[core/msg_translator.c:2288]: generate_res_buf_from_sip_res(): old size: 
491, new size: 407

The problem exists at least in the following version:

version: kamailio 5.2.0 (x86_64/linux) c5216b
flags: STATS: Off, USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, USE_RAW_SOCKS, 
DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MEM, SHM_MMAP, PKG_MALLOC, 
Q_MALLOC, F_MALLOC, TLSF_MALLOC, DBG_SR_MEMORY, USE_FUTEX, 
FAST_LOCK-ADAPTIVE_WAIT, USE_DNS_CACHE, USE_DNS_FAILOVER, USE_NAPTR, 
USE_DST_BLACKLIST, HAVE_RESOLV_RES
ADAPTIVE_WAIT_LOOPS=1024, MAX_RECV_BUFFER_SIZE 262144 MAX_URI_SIZE 1024, 
BUF_SIZE 65535, DEFAULT PKG_SIZE 8MB
poll method support: poll, epoll_lt, epoll_et, sigio_rt, select.
id: c5216b
compiled on 13:05:00 Jan  7 2019 with gcc 6.3.

Best Regards,
Joonas Keskitalo