<html>
<head>
<meta http-equiv="Content-Type" content="text/html; charset=utf-8">
</head>
<body text="#000000" bgcolor="#FFFFFF">
<tt>Hi Daniel,</tt><tt><br>
<br>
sorry for the misunderstanding.<br>
Attached to the mail you can find the full log of the affected
call with debug level 3 as requested.<br>
<br>
Thank you again<br>
<br>
Cheers,<br>
Marco<br>
</tt><tt><br>
</tt><tt><br>
</tt>
<div class="moz-cite-prefix">On 09/10/2018 12:29 PM,
Daniel-Constantin Mierla wrote:<br>
</div>
<blockquote type="cite"
cite="mid:1d0f33b3-31a9-91d5-64e9-c0951532ed01@gmail.com">
<meta http-equiv="Content-Type" content="text/html; charset=utf-8">
<p>Hello,</p>
<p>I need **all the logs**, not only those from acc module and
script, but all the modules and core.</p>
<p>Add also:</p>
<p>log_prefix="{$mt $hdr(CSeq) $ci} "</p>
<p>to be able to match log message with the request time.</p>
<p>And again, send the logs only for the case that fails.<br>
</p>
Cheers,<br>
Daniel<br>
<br>
<div class="moz-cite-prefix">On 10.09.18 10:57, Marco Capetta
wrote:<br>
</div>
<blockquote type="cite"
cite="mid:2e5b23b4-25e3-0ccf-a6a0-8646b8bd88a3@sipwise.com">
<meta http-equiv="Content-Type" content="text/html;
charset=utf-8">
<tt>Hi Daniel,</tt><tt><br>
</tt><tt><br>
I applied your patch to our kamailio v5.1.4 because this is
the current version we are using.<br>
<br>
<br>
This is the log + debug of the call when the two INVITEs are
leaving kamailio.<br>
This part is common for both the call cases:<br>
<br>
Sep 10 10:33:21 sp1 proxy[7627]: NOTICE: <script>:
Request leaving server, M=INVITE fs='127.0.0.1:5062'
du='127.0.0.1:5080' - <a class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.16.134:5063"
moz-do-not-send="true">R=sip:43993003@10.50.16.134:5063</a>
<a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='YATE/5.4.0'<br>
Sep 10 10:33:21 sp1 proxy[7627]: NOTICE: <script>:
Request leaving server, M=INVITE fs='127.0.0.1:5062'
du='127.0.0.1:5080' - <a class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.16.134:5063"
moz-do-not-send="true">R=sip:43993003@10.50.16.134:5063</a>
<a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='YATE/5.4.0'<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 2, reply code 100<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 100 flags:
0x802a<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:443]:
should_acc_reply(): early media acc is off<br>
Sep 10 10:33:21 sp1 proxy[7627]: NOTICE: <script>:
NAT-Reply - S=100 - Trying M=INVITE IP=10.50.3.126:35901
(127.0.0.1:5080) <a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 2, reply code 100<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 100 flags:
0x802a<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:443]:
should_acc_reply(): early media acc is off<br>
Sep 10 10:33:21 sp1 proxy[7627]: NOTICE: <script>:
NAT-Reply - S=100 - Trying M=INVITE IP=10.50.3.126:35901
(127.0.0.1:5080) <a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 2, reply code 180<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 180 flags:
0x802a<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:443]:
should_acc_reply(): early media acc is off<br>
Sep 10 10:33:21 sp1 proxy[7627]: NOTICE: <script>:
NAT-Reply - S=180 - Ringing M=INVITE IP=10.50.3.126:35901
(127.0.0.1:5080) <a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
Sep 10 10:33:21 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=180 M=INVITE fs='127.0.0.1:5062'
du='127.0.0.1:<null>' - <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>'<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 512, reply code 180<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 180 flags:
0x802a<br>
Sep 10 10:33:21 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:443]:
should_acc_reply(): early media acc is off<br>
Sep 10 10:33:22 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 2, reply code 180<br>
Sep 10 10:33:22 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 180 flags:
0x802a<br>
Sep 10 10:33:22 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:443]:
should_acc_reply(): early media acc is off<br>
Sep 10 10:33:22 sp1 proxy[7627]: NOTICE: <script>:
NAT-Reply - S=180 - Ringing M=INVITE IP=10.50.3.126:35901
(127.0.0.1:5080) <a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='Yealink SIP-T32G 32.70.1.33' DESTIP=127.0.0.1:5062<br>
Sep 10 10:33:22 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=180 M=INVITE fs='127.0.0.1:5062'
du='127.0.0.1:<null>' - <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='Yealink SIP-T32G 32.70.1.33'<br>
Sep 10 10:33:22 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 512, reply code 180<br>
Sep 10 10:33:22 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 180 flags:
0x802a<br>
Sep 10 10:33:22 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:443]:
should_acc_reply(): early media acc is off</tt><tt><br>
<br>
<br>
</tt><tt><br>
In CASE 1, this is the log + debug printed when the call is
canceled from the caller:<br>
<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>: New
request on proxy - M=CANCEL <a
class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.3.130"
moz-do-not-send="true">R=sip:43993003@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:F=sip:43993004@10.50.3.130"
moz-do-not-send="true">F=sip:43993004@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:T=sip:43993003@10.50.3.130"
moz-do-not-send="true">T=sip:43993003@10.50.3.130</a>
IP=127.0.0.1:5060 (127.0.0.1:5060) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=100 Trying fs='127.0.0.1:5062'
du='127.0.0.1:5060' - <a class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.3.130"
moz-do-not-send="true">R=sip:43993003@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>'<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12c3318) event
type 512, reply code 200<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 200 flags: 0x0<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:436]:
should_acc_reply(): acc is off<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 2, reply code 200<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 200 flags:
0x802a<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:447]:
should_acc_reply(): acc is on<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 2, reply code 487<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 487 flags:
0x802a<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:431]:
should_acc_reply(): failed acc is on<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>:
NAT-Reply - S=487 - Request terminated M=INVITE
IP=10.50.3.126:35901 (127.0.0.1:5080) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=487 M=INVITE fs='127.0.0.1:5062'
du='127.0.0.1:<null>' - <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>'<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 2, reply code 200<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 200 flags:
0x802a<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:447]:
should_acc_reply(): acc is on<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 2, reply code 487<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 487 flags:
0x802a<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:431]:
should_acc_reply(): failed acc is on<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>:
NAT-Reply - S=487 - Request terminated M=INVITE
IP=10.50.3.126:35901 (127.0.0.1:5080) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=487 M=INVITE fs='127.0.0.1:5062'
du='127.0.0.1:<null>' - <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>'<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 128, reply code 487<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:477]:
on_missed(): preparing to report the record<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>:
Failure route for local call, code='487' - <a
class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.16.134:5063"
moz-do-not-send="true">R=sip:43993003@10.50.16.134:5063</a>
<a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='YATE/5.4.0'<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>: Final
reply - <a class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.16.134:5063"
moz-do-not-send="true">R=sip:43993003@10.50.16.134:5063</a>
<a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='YATE/5.4.0'<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12b4220) event
type 512, reply code 487<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 487 flags:
0x802a<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:431]:
should_acc_reply(): failed acc is on<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8,
index i 0<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8,
index i 1<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8,
index i 2<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8,
index i 3<br>
Sep 10 10:33:33 sp1 proxy[7627]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8,
index i 4<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>: New
request on proxy - M=BYE
R=<a class="moz-txt-link-freetext"
href="sip:127.0.0.1:5062;lr=on;ftag=1714653723;did=2cc.ee71;ice_caller=strip;ice_callee=strip;aset=50;rtpprx=yes;vsf=bUViZ2VDb2dwTTNFYU5lTDRmbVVtRWJn"
moz-do-not-send="true">sip:127.0.0.1:5062;lr=on;ftag=1714653723;did=2cc.ee71;ice_caller=strip;ice_callee=strip;aset=50;rtpprx=yes;vsf=bUViZ2VDb2dwTTNFYU5lTDRmbVVtRWJn</a>
<a class="moz-txt-link-abbreviated"
href="mailto:F=sip:43993004@10.50.3.130"
moz-do-not-send="true">F=sip:43993004@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:T=sip:43993003@10.50.3.130"
moz-do-not-send="true">T=sip:43993003@10.50.3.130</a>
IP=127.0.0.1:5060 (127.0.0.1:5060) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='YATE/5.4.0' DESTIP=127.0.0.1:5062<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=100 Trying fs='127.0.0.1:5062'
du='127.0.0.1:5060' -
R=<a class="moz-txt-link-freetext"
href="sip:127.0.0.1:5062;lr=on;ftag=1714653723;did=2cc.ee71;ice_caller=strip;ice_callee=strip;aset=50;rtpprx=yes;vsf=bUViZ2VDb2dwTTNFYU5lTDRmbVVtRWJn"
moz-do-not-send="true">sip:127.0.0.1:5062;lr=on;ftag=1714653723;did=2cc.ee71;ice_caller=strip;ice_callee=strip;aset=50;rtpprx=yes;vsf=bUViZ2VDb2dwTTNFYU5lTDRmbVVtRWJn</a>
<a class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='YATE/5.4.0'<br>
Sep 10 10:33:33 sp1 proxy[7627]: NOTICE: <script>: New
request on proxy - M=ACK <a class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.3.130"
moz-do-not-send="true">R=sip:43993003@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:F=sip:43993004@10.50.3.130"
moz-do-not-send="true">F=sip:43993004@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:T=sip:43993003@10.50.3.130"
moz-do-not-send="true">T=sip:43993003@10.50.3.130</a>
IP=127.0.0.1:5060 (127.0.0.1:5060) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1125655181@10.50.3.130"
moz-do-not-send="true">ID=1125655181@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062</tt><tt><br>
<br>
<br>
<br>
In CASE 2, this is the log + debug printed when the call is
rejected from one of the callee and then canceled from the
caller:<br>
<br>
Sep 10 10:35:35 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12bbec0) event
type 2, reply code 486<br>
Sep 10 10:35:35 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 486 flags:
0x802a<br>
Sep 10 10:35:35 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:431]:
should_acc_reply(): failed acc is on<br>
Sep 10 10:35:35 sp1 proxy[7627]: NOTICE: <script>:
NAT-Reply - S=486 - Busy Here M=INVITE IP=10.50.3.126:35901
(127.0.0.1:5080) <a class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='Yealink SIP-T32G 32.70.1.33' DESTIP=127.0.0.1:5062<br>
Sep 10 10:35:35 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=486 M=INVITE fs='127.0.0.1:5062'
du='127.0.0.1:<null>' - <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='Yealink SIP-T32G 32.70.1.33'<br>
<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>: New
request on proxy - M=CANCEL <a
class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.3.130"
moz-do-not-send="true">R=sip:43993003@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:F=sip:43993004@10.50.3.130"
moz-do-not-send="true">F=sip:43993004@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:T=sip:43993003@10.50.3.130"
moz-do-not-send="true">T=sip:43993003@10.50.3.130</a>
IP=127.0.0.1:5060 (127.0.0.1:5060) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=100 Trying fs='127.0.0.1:5062'
du='127.0.0.1:5060' - <a class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.3.130"
moz-do-not-send="true">R=sip:43993003@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='<null>'<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12c6b30) event
type 512, reply code 200<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 200 flags: 0x0<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:436]:
should_acc_reply(): acc is off<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>: New
request on proxy - M=BYE
R=<a class="moz-txt-link-freetext"
href="sip:127.0.0.1:5062;lr=on;ftag=1174240048;did=7bd.7c3;ice_caller=strip;ice_callee=strip;aset=50;rtpprx=yes;vsf=bUViZ2VDb2dwTTNFYU5lTDRmbVVtRWJn"
moz-do-not-send="true">sip:127.0.0.1:5062;lr=on;ftag=1174240048;did=7bd.7c3;ice_caller=strip;ice_callee=strip;aset=50;rtpprx=yes;vsf=bUViZ2VDb2dwTTNFYU5lTDRmbVVtRWJn</a>
<a class="moz-txt-link-abbreviated"
href="mailto:F=sip:43993004@10.50.3.130"
moz-do-not-send="true">F=sip:43993004@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:T=sip:43993003@10.50.3.130"
moz-do-not-send="true">T=sip:43993003@10.50.3.130</a>
IP=127.0.0.1:5060 (127.0.0.1:5060) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='YATE/5.4.0' DESTIP=127.0.0.1:5062<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=100 Trying fs='127.0.0.1:5062'
du='127.0.0.1:5060' -
R=<a class="moz-txt-link-freetext"
href="sip:127.0.0.1:5062;lr=on;ftag=1174240048;did=7bd.7c3;ice_caller=strip;ice_callee=strip;aset=50;rtpprx=yes;vsf=bUViZ2VDb2dwTTNFYU5lTDRmbVVtRWJn"
moz-do-not-send="true">sip:127.0.0.1:5062;lr=on;ftag=1174240048;did=7bd.7c3;ice_caller=strip;ice_callee=strip;aset=50;rtpprx=yes;vsf=bUViZ2VDb2dwTTNFYU5lTDRmbVVtRWJn</a>
<a class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='YATE/5.4.0'<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12bbec0) event
type 2, reply code 200<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 200 flags:
0x802a<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:447]:
should_acc_reply(): acc is on<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12bbec0) event
type 2, reply code 487<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:414]:
should_acc_reply(): probing acc state - code: 487 flags:
0x802a<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:431]:
should_acc_reply(): failed acc is on<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>:
NAT-Reply - S=487 - Request terminated M=INVITE
IP=10.50.3.126:35901 (127.0.0.1:5080) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>:
Sending reply S=487 M=INVITE fs='127.0.0.1:5062'
du='127.0.0.1:<null>' - <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='<null>'<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:684]:
tmcb_func(): acc callback called for t(0x7f7ac12bbec0) event
type 128, reply code 486<br>
Sep 10 10:35:43 sp1 proxy[7627]: DEBUG: acc [acc_logic.c:477]:
on_missed(): preparing to report the record<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>:
Failure route for local call, code='486' - <a
class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.16.134:5063"
moz-do-not-send="true">R=sip:43993003@10.50.16.134:5063</a>
<a class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='YATE/5.4.0'<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>: Final
reply - <a class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.16.134:5063"
moz-do-not-send="true">R=sip:43993003@10.50.16.134:5063</a>
<a class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='YATE/5.4.0'<br>
Sep 10 10:35:43 sp1 proxy[7627]: NOTICE: <script>: New
request on proxy - M=ACK <a class="moz-txt-link-abbreviated"
href="mailto:R=sip:43993003@10.50.3.130"
moz-do-not-send="true">R=sip:43993003@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:F=sip:43993004@10.50.3.130"
moz-do-not-send="true">F=sip:43993004@10.50.3.130</a> <a
class="moz-txt-link-abbreviated"
href="mailto:T=sip:43993003@10.50.3.130"
moz-do-not-send="true">T=sip:43993003@10.50.3.130</a>
IP=127.0.0.1:5060 (127.0.0.1:5060) <a
class="moz-txt-link-abbreviated"
href="mailto:ID=1929423861@10.50.3.130"
moz-do-not-send="true">ID=1929423861@10.50.3.130</a>
UA='<null>' DESTIP=127.0.0.1:5062<br>
<br>
<br>
Thank you<br>
Marco<br>
<br>
<br>
<br>
</tt>
<div class="moz-cite-prefix">On 09/10/2018 09:21 AM,
Daniel-Constantin Mierla wrote:<br>
</div>
<blockquote type="cite"
cite="mid:7fa37e29-7e47-bd37-a4c4-7106f67547c6@gmail.com">
<meta http-equiv="Content-Type" content="text/html;
charset=utf-8">
<p>Hello,</p>
<p>I pushed a patch to print more debug messages when testing
if accounting should be done or not:</p>
<p> - <a class="moz-txt-link-freetext"
href="https://github.com/kamailio/kamailio/commit/f21554c6befaddbc82016d5d498e11ab3720c404"
moz-do-not-send="true">https://github.com/kamailio/kamailio/commit/f21554c6befaddbc82016d5d498e11ab3720c404</a></p>
<p>Can you test with it and send me all debug messages for the
respective transaction in the case that doesn't work?</p>
<p>Cheers,<br>
Daniel<br>
</p>
<br>
<div class="moz-cite-prefix">On 04.09.18 14:03, Marco Capetta
wrote:<br>
</div>
<blockquote type="cite"
cite="mid:b8bb6d21-7d22-c7d9-4b89-2ad4c7b20437@sipwise.com">
<meta http-equiv="Content-Type" content="text/html;
charset=utf-8">
<tt>Hello,</tt><tt><br>
</tt><tt><br>
Yes Flag 3 is set at the very beginning when we start
handling the received INVITE message.<br>
I checked and it is still active in both the branches of
the outgoing INVITEs.<br>
</tt><tt><tt><br>
</tt><br>
<br>
In the 1st case the following ACC is generated:<br>
id: 311<br>
method: INVITE<br>
from_tag: <br>
to_tag: <br>
callid: <a class="moz-txt-link-abbreviated"
href="mailto:121700311@X.X.X.X" moz-do-not-send="true">121700311@X.X.X.X</a><br>
sip_code: 487<br>
sip_reason: Request terminated<br>
time: 2018-09-04 11:55:24<br>
time_hires: 1536054924.342<br>
<br>
<br>
</tt><br>
<tt><tt><tt>I missed to say in my previous email that in
kamailio ACC configuration we have also:<br>
modparam("acc", "acc_prepare_always", 1)<br>
<br>
</tt></tt><br>
Thanks<br>
Marco<br>
<br>
<br>
</tt><br>
<div class="moz-cite-prefix">On 09/04/2018 11:39 AM,
Daniel-Constantin Mierla wrote:<br>
</div>
<blockquote type="cite"
cite="mid:38ce8cfb-b380-e736-6662-4aca84fefe04@gmail.com">
<pre wrap="">Hello,
is flag 3 set for the INVITE transaction?
In the 1st case, is the CANCEL accounted or the INVITE transaction or both?
Cheers,
Daniel
On 04.09.18 11:18, Marco Capetta wrote:
</pre>
<blockquote type="cite">
<pre wrap="">HiAll,
As additional step I tested the scenario with kamailio v5.1.5 but the
problem seems still there.
Best regards.
Marco
On 08/28/2018 03:10 PM, Marco Capetta wrote:
</pre>
<blockquote type="cite">
<pre wrap="">Hi All,
I'm facing a strange problem of missing ACC record in case of
parallel call forking.
The scenario is the following:
- A subscriber with 1 device registered
- B subscriber with 2 device registered (B1 and B2)
CASE 1:
- A calls B
- B1 and B2 start ringing
- A hangups the call before B1 or B2 answers
Kamailio generates an ACC record.
CASE 2:
- A calls B
- B1 and B2 start ringing
- B1 rejects the call sending back a 486
- B2 is still ringing
- A hangups the call before B2 answers
Kamailio DOESN'T generate an ACC record.
We have Kamailio v5.1.4 with TM module enabled.
ACC configuration is the following:
modparam("acc", "early_media", 0)
modparam("acc", "report_ack", 0)
modparam("acc", "report_cancels", 1)
modparam("acc", "detect_direction", 1)
modparam("acc", "db_flag", 1)
modparam("acc", "db_missed_flag", 2)
modparam("acc", "failed_transaction_flag", 3)
I increased debug level of TM and ACC modules and I added some debug
lines as well.
The difference between the two calls is that, after the CANCEL sent
by A is processed by Kamailio, in CASE 1 I have the following lines:
Aug 28 14:21:11 spce proxy[13188]: DEBUG: tm [t_hooks.c:258]:
run_trans_callbacks_internal(): DBG: trans=0x7f5a88049198, callback
type 512, id 0 entered
Aug 28 14:21:11 spce proxy[13188]: DEBUG: acc [acc_logic.c:670]:
tmcb_func(): acc callback called for t(0x7f5a88049198) event type
512, reply code 487
Aug 28 14:21:11 spce proxy[13188]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8, index i 0
Aug 28 14:21:11 spce proxy[13188]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8, index i 1
Aug 28 14:21:11 spce proxy[13188]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8, index i 2
Aug 28 14:21:11 spce proxy[13188]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8, index i 3
Aug 28 14:21:11 spce proxy[13188]: DEBUG: acc [acc_extra.h:63]:
free_strar_mem(): Freeing memory, type is 2, message_index 8, index i 4
Those debug lines are not printed for CASE 2.
Is there any configuration I'm missing or is it a bug?
Thank you very much for your support.
Marco
</pre>
</blockquote>
<pre wrap="">_______________________________________________
Kamailio (SER) - Users Mailing List
<a class="moz-txt-link-abbreviated" href="mailto:sr-users@lists.kamailio.org" moz-do-not-send="true">sr-users@lists.kamailio.org</a>
<a class="moz-txt-link-freetext" href="https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users" moz-do-not-send="true">https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users</a>
</pre>
</blockquote>
</blockquote>
<br>
<div class="moz-signature">-- <br>
<div style="line-height:16px; margin:6px 0; padding:8px
8px 8px 8px; border-top:1px #aeb1a6 dotted;
border-bottom:1px #aeb1a6 dotted; font-family: 'Lucida
Sans', Lucida Grande, Verdana, Arial, Sans-Serif;
font-size:11px; color:#555555;"> <strong
style="color:#333333; text-transform:uppercase;
font-size:10px;"> Marco Capetta </strong> <br>
Operations Engineer
<p> <a href="http://www.sipwise.com"
style="color:rgb(0, 136, 204) !important;
text-decoration:none !important; border-bottom:1px
dotted #AAA;" moz-do-not-send="true"> Sipwise GmbH </a>
, Campus 21/Europaring F15<br>
AT-2345 Brunn am Gebirge </p>
<p> Phone: <a href="tel:+4313012044"
style="color:rgb(0, 136, 204) !important;
text-decoration:none !important; border-bottom:1px
dotted #AAA;" moz-do-not-send="true"> +43(0)1 301
2044 </a> <br>
Email: <a href="mailto:mcapetta@sipwise.com"
style="color:rgb(0, 136, 204) !important;
text-decoration:none !important; border-bottom:1px
dotted #AAA;" moz-do-not-send="true">
mcapetta@sipwise.com </a> <br>
Website: <a href="http://www.sipwise.com"
style="color:rgb(0, 136, 204) !important;
text-decoration:none !important; border-bottom:1px
dotted #AAA;" moz-do-not-send="true">
www.sipwise.com </a> </p>
<p> Particulars according Austrian Companies Code
paragraph 14<br>
"Sipwise GmbH" - Europaring F15 - 2345 Brunn am
Gebirge<br>
FN:305595f, Commercial Court Vienna, ATU64002206 </p>
</div>
</div>
</blockquote>
<br>
<pre class="moz-signature" cols="72">--
Daniel-Constantin Mierla -- <a class="moz-txt-link-abbreviated" href="http://www.asipto.com" moz-do-not-send="true">www.asipto.com</a>
<a class="moz-txt-link-abbreviated" href="http://www.twitter.com/miconda" moz-do-not-send="true">www.twitter.com/miconda</a> -- <a class="moz-txt-link-abbreviated" href="http://www.linkedin.com/in/miconda" moz-do-not-send="true">www.linkedin.com/in/miconda</a>
Kamailio World Conference -- <a class="moz-txt-link-abbreviated" href="http://www.kamailioworld.com" moz-do-not-send="true">www.kamailioworld.com</a>
Kamailio Advanced Training, Nov 12-14, 2018, in Berlin -- <a class="moz-txt-link-abbreviated" href="http://www.asipto.com" moz-do-not-send="true">www.asipto.com</a></pre>
</blockquote>
<br>
<div class="moz-signature">-- <br>
<div style="line-height:16px; margin:6px 0; padding:8px 8px
8px 8px; border-top:1px #aeb1a6 dotted; border-bottom:1px
#aeb1a6 dotted; font-family: 'Lucida Sans', Lucida Grande,
Verdana, Arial, Sans-Serif; font-size:11px; color:#555555;">
<strong style="color:#333333; text-transform:uppercase;
font-size:10px;"> Marco Capetta </strong> <br>
Operations Engineer
<p> <a href="http://www.sipwise.com" style="color:rgb(0,
136, 204) !important; text-decoration:none !important;
border-bottom:1px dotted #AAA;" moz-do-not-send="true">
Sipwise GmbH </a> , Campus 21/Europaring F15<br>
AT-2345 Brunn am Gebirge </p>
<p> Phone: <a href="tel:+4313012044" style="color:rgb(0,
136, 204) !important; text-decoration:none !important;
border-bottom:1px dotted #AAA;" moz-do-not-send="true">
+43(0)1 301 2044 </a> <br>
Email: <a href="mailto:mcapetta@sipwise.com"
style="color:rgb(0, 136, 204) !important;
text-decoration:none !important; border-bottom:1px
dotted #AAA;" moz-do-not-send="true">
mcapetta@sipwise.com </a> <br>
Website: <a href="http://www.sipwise.com"
style="color:rgb(0, 136, 204) !important;
text-decoration:none !important; border-bottom:1px
dotted #AAA;" moz-do-not-send="true"> www.sipwise.com </a>
</p>
<p> Particulars according Austrian Companies Code paragraph
14<br>
"Sipwise GmbH" - Europaring F15 - 2345 Brunn am Gebirge<br>
FN:305595f, Commercial Court Vienna, ATU64002206 </p>
</div>
</div>
</blockquote>
<br>
<pre class="moz-signature" cols="72">--
Daniel-Constantin Mierla -- <a class="moz-txt-link-abbreviated" href="http://www.asipto.com" moz-do-not-send="true">www.asipto.com</a>
<a class="moz-txt-link-abbreviated" href="http://www.twitter.com/miconda" moz-do-not-send="true">www.twitter.com/miconda</a> -- <a class="moz-txt-link-abbreviated" href="http://www.linkedin.com/in/miconda" moz-do-not-send="true">www.linkedin.com/in/miconda</a>
Kamailio World Conference -- <a class="moz-txt-link-abbreviated" href="http://www.kamailioworld.com" moz-do-not-send="true">www.kamailioworld.com</a>
Kamailio Advanced Training, Nov 12-14, 2018, in Berlin -- <a class="moz-txt-link-abbreviated" href="http://www.asipto.com" moz-do-not-send="true">www.asipto.com</a></pre>
</blockquote>
<br>
<div class="moz-signature">-- <br>
<div style="line-height:16px; margin:6px 0; padding:8px 8px 8px
8px; border-top:1px #aeb1a6 dotted; border-bottom:1px #aeb1a6
dotted; font-family: 'Lucida Sans', Lucida Grande, Verdana,
Arial, Sans-Serif; font-size:11px; color:#555555;"> <strong
style="color:#333333; text-transform:uppercase;
font-size:10px;"> Marco Capetta </strong> <br>
Operations Engineer
<p> <a href="http://www.sipwise.com" style="color:rgb(0, 136,
204) !important; text-decoration:none !important;
border-bottom:1px dotted #AAA;"> Sipwise GmbH </a> , Campus
21/Europaring F15<br>
AT-2345 Brunn am Gebirge </p>
<p> Phone: <a href="tel:+4313012044" style="color:rgb(0, 136,
204) !important; text-decoration:none !important;
border-bottom:1px dotted #AAA;"> +43(0)1 301 2044 </a> <br>
Email: <a href="mailto:mcapetta@sipwise.com"
style="color:rgb(0, 136, 204) !important;
text-decoration:none !important; border-bottom:1px dotted
#AAA;"> mcapetta@sipwise.com </a> <br>
Website: <a href="http://www.sipwise.com"
style="color:rgb(0, 136, 204) !important;
text-decoration:none !important; border-bottom:1px dotted
#AAA;"> www.sipwise.com </a> </p>
<p>
Particulars according Austrian Companies Code paragraph 14<br>
"Sipwise GmbH" - Europaring F15 - 2345 Brunn am Gebirge<br>
FN:305595f, Commercial Court Vienna, ATU64002206
</p>
</div>
</div>
</body>
</html>