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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)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