<html><head>
<meta http-equiv="Content-Type" content="text/html; charset=utf-8">
</head>
<body>
<p>Hi Marco,</p>
<p>I started with the 9.5.5 VMWare image. Just added two subscribers
to the first customer (#7 <a class="moz-txt-link-abbreviated" href="mailto:customer.test@spce.test">customer.test@spce.test</a>). I registered
two devices and made a call without succes. It seems the kamailio
proxy crashed on INVITE with a memory dump:</p>
<p><font face="monospace"><font size="2">Oct 18 11:25:03 spce
proxy[27230]: NOTICE: DEFAULT_ROUTE <script>: New
request on proxy - M=INVITE
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
F=«<a class="moz-txt-link-freetext" href="sip:c2@test1654835032.example.org»">sip:c2@test1654835032.example.org»</a>
T=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
IP=«10.0.1.212»:«6062» («127.0.0.1»:«5060»)
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8' DESTIP=«127.0.0.1»:«5062»<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: DEFAULT_ROUTE
<script>: Sending reply S=100 Trying M=INVITE
fs='«127.0.0.1»:«5062»' du='«127.0.0.1»:«5060»' -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_AUTH
<script>: Authentication failed, no credentials -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8' Auth=«<null>»<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_AUTH
<script>: Sending reply S=407 fs='«127.0.0.1»:«5062»'
du='«127.0.0.1»:«5060»' - ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>»
UA='Grandstream DP750 1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: sl:filtered-ack
<script>: New request on proxy - M=ACK
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
F=«<a class="moz-txt-link-freetext" href="sip:c2@test1654835032.example.org»">sip:c2@test1654835032.example.org»</a>
T=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
IP=«<null>»:«<null>» («127.0.0.1»:«5060»)
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='<null>'
DESTIP=«127.0.0.1»:«5062»<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: DEFAULT_ROUTE
<script>: New request on proxy - M=INVITE
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
F=«<a class="moz-txt-link-freetext" href="sip:c2@test1654835032.example.org»">sip:c2@test1654835032.example.org»</a>
T=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
IP=«10.0.1.212»:«6062» («127.0.0.1»:«5060»)
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8' DESTIP=«127.0.0.1»:«5062»<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: DEFAULT_ROUTE
<script>: Sending reply S=100 Trying M=INVITE
fs='«127.0.0.1»:«5062»' du='«127.0.0.1»:«5060»' -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_FIND_CALLER
<script>: Sending reply S=101 Connecting
fs='«127.0.0.1»:«5062»' du='«127.0.0.1»:«5060»' -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_DLG_MANAGE
<script>: Dialog managed,
lua_dlg_callid:[«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>»] -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE:
ROUTE_GET_CALLER_CLI <script>: User-Provided CLI '«c2»'
taken from From-User -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE:
ROUTE_CHECK_CLI_ALLOWED <script>: User-provided CLI
'«c2»' rejected, using network-provided CLI '«1127654321»' -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_INVITE
<script>: Setting
'«1127654321»@«test1654835032.example.org»' as initiating
user-provided CLI -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_INVITE
<script>: Setting
'«1127654321»@«test1654835032.example.org»' as initiating
network-provided CLI -
R=«<a class="moz-txt-link-freetext" href="sip:1121234567@test1654835032.example.org»">sip:1121234567@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_FIND_CALLEE
<script>: Callee was aliased with base '«1121234567»' -
R=«<a class="moz-txt-link-freetext" href="sip:c1@test1654835032.example.org»">sip:c1@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_FIND_CALLEE
<script>: Callee is local,
uuid='«e5eb8a53-d91a-4460-83b0-eb84cbc9240a»' -
R=«<a class="moz-txt-link-freetext" href="sip:c1@test1654835032.example.org»">sip:c1@test1654835032.example.org»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_ACC_CALLER
<script>: Setting acc source-leg for uuid
'«a889b68d-9999-42a4-b897-e5c4bdb102f5»':
'«{"gpp":[null,null,null,null,null,null,null,null,null,null],"clir":0,"uuid":"a889b68d-9999-42a4-b897-e5c4bdb102f5","u":"c2","d":"test1654835032.example.org","cli":"1127654321","s_id":"","c_id":"","a_id":7,"pau":"","par":"","s":"call","ip":"10.0.1.212","t":1666085103.2204731,"lnp_p":"","cli_out":"1127654321","lnp_t":"","pai":"","div":"","last_hih":"","cid":"","u2u":"","lcr":0,"cc_quota":"","cc_sub":"1","cc_cust":"2"}»'
-
R=«<a class="moz-txt-link-freetext" href="sip:c1@77.160.100.40:61022;rinstance=38ecd83d4d88e500;transport=TCP»">sip:c1@77.160.100.40:61022;rinstance=38ecd83d4d88e500;transport=TCP»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE: ROUTE_ACC_CALLEE
<script>: Setting acc destination-leg for uuid
'«e5eb8a53-d91a-4460-83b0-eb84cbc9240a»':
'«{"gpp":[null,null,null,null,null,null,null,null,null,null],"plu":0,"s_id":"","c_id":"","a_id":7,"dialed":"1121234567","uuid":"e5eb8a53-d91a-4460-83b0-eb84cbc9240a","u":"c1","d":"test1654835032.example.org","u_in":"1121234567","d_in":"test1654835032.example.org","lcr":0,"lnp_p":"","u_out":"c1","lnp_t":"","fci":"","cc_quota":"","cc_sub":"1","cc_cust":"2"}»'
-
R=«<a class="moz-txt-link-freetext" href="sip:c1@77.160.100.40:61022;rinstance=38ecd83d4d88e500;transport=TCP»">sip:c1@77.160.100.40:61022;rinstance=38ecd83d4d88e500;transport=TCP»</a>
ID=«<a class="moz-txt-link-abbreviated" href="mailto:1991747540-6062-9@BA.A.B.CBC">1991747540-6062-9@BA.A.B.CBC</a>» UA='Grandstream DP750
1.0.17.8'<br>
Oct 18 11:25:03 spce proxy[27230]: CRITICAL:
ROUTE_REMOVE_TIMER_SUPP_REQ <core>
[core/mem/q_malloc.c:133]: qm_debug_check_frag(): BUG: qm:
fragm. 0x7f63d68230c0 (address 0x7f63d68230f8) end overwritten
(c0c0c000, abcdefed)! Memory allocator was called from
pv_headers: pvh_func.c:444. Fragment marked by pv_headers:
pvh_func.c:391. Exec from core/mem/q_malloc.c:511.<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE:
ROUTE_REMOVE_TIMER_SUPP_REQ qm_status: qm_status():
(0x7f63d5980010):<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE:
ROUTE_REMOVE_TIMER_SUPP_REQ qm_status: qm_status(): heap size=
134217728<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE:
ROUTE_REMOVE_TIMER_SUPP_REQ qm_status: qm_status(): used=
26496208, used+overhead=33382264, free=100835464<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE:
ROUTE_REMOVE_TIMER_SUPP_REQ qm_status: qm_status(): max used
(+overhead)= 33687296<br>
Oct 18 11:25:03 spce proxy[27230]: NOTICE:
ROUTE_REMOVE_TIMER_SUPP_REQ qm_status: qm_status(): dumping
all alloc'ed. fragments:</font><br>
</font></p>
<p><font face="monospace">.....<br>
</font></p>
<div class="moz-cite-prefix">So I ran ngcp-update which fixed the
issue, only now rate-o-mat crashes:</div>
<div class="moz-cite-prefix"><font face="monospace"><font size="2">Oct
18 11:33:48 spce (info) ngcp-rate-o-mat[65715]: DBD::mysql::db
do failed: Access denied; you need (at least one of) the
SUPER, BINLOG ADMIN privilege(s) for this operation at
/usr/sbin/ngcp-rate-o-mat line 288.<br>
Oct 18 11:33:48 spce (info) ngcp-rate-o-mat[65715]: FATAL:
error setting session binlog_format<br>
Oct 18 11:33:48 spce (info) ngcp-rate-o-mat[65715]: INFO:
Trying to connect to billing db...<br>
Oct 18 11:34:09 spce (info) ngcp-rate-o-mat[66252]:
DBD::mysql::db do failed: Access denied; you need (at least
one of) the SUPER, BINLOG ADMIN privilege(s) for this
operation at /usr/sbin/ngcp-rate-o-mat line 288.<br>
Oct 18 11:34:09 spce (info) ngcp-rate-o-mat[66252]: FATAL:
error setting session binlog_format<br>
Oct 18 11:34:09 spce (info) ngcp-rate-o-mat[66252]: INFO:
Trying to connect to billing db...<br>
Oct 18 11:34:42 spce (info) ngcp-rate-o-mat[67355]:
DBD::mysql::db do failed: Access denied; you need (at least
one of) the SUPER, BINLOG ADMIN privilege(s) for this
operation at /usr/sbin/ngcp-rate-o-mat line 288.<br>
Oct 18 11:34:42 spce (info) ngcp-rate-o-mat[67355]: FATAL:
error setting session binlog_format<br>
Oct 18 11:34:42 spce (info) ngcp-rate-o-mat[67355]: INFO:
Trying to connect to billing db...</font><br>
</font><br>
</div>
<div class="moz-cite-prefix">So, the image seems not very stable,
but I was able to finish my test.</div>
<div class="moz-cite-prefix">The call list for subscriber 1:</div>
<div class="moz-cite-prefix"><br>
</div>
<div class="moz-cite-prefix"><font size="2" face="monospace">Call
List for <a class="moz-txt-link-abbreviated" href="mailto:c1@test1654835032.example.org">c1@test1654835032.example.org</a> (1 12 1234567)</font><font size="2"><br>
</font></div>
<div class="moz-cite-prefix"><font size="2" face="monospace">115
1127654321 1121234567 0 cancel 2022-10-18
11:36:39.586 0:00:00
<a class="moz-txt-link-abbreviated" href="mailto:568301286-6062-11@BA.A.B.CBC">568301286-6062-11@BA.A.B.CBC</a> 0.00 <br>
116 1121234567 1127654321 0 ok 2022-10-18
11:37:39.065 0:00:05.501 0.0 0.0 0.0 0.0
DgOp_xBrhhPSWa5Df2TovQ.. 0.00 </font><font size="2"><br>
</font></div>
<div class="moz-cite-prefix"><br>
</div>
<div class="moz-cite-prefix">and for subscriber 2:</div>
<div class="moz-cite-prefix"><br>
</div>
<div class="moz-cite-prefix"><font size="2" face="monospace">Call
List for <a class="moz-txt-link-abbreviated" href="mailto:c2@test1654835032.example.org">c2@test1654835032.example.org</a> (1 12 7654321)</font><font size="2"><br>
</font></div>
<div class="moz-cite-prefix"><font size="2" face="monospace">115
1127654321 1121234567 0 cancel 2022-10-18
11:36:39.586 0:00:00
<a class="moz-txt-link-abbreviated" href="mailto:568301286-6062-11@BA.A.B.CBC">568301286-6062-11@BA.A.B.CBC</a> 0.00 <br>
116 1121234567 1127654321 0 ok 2022-10-18
11:37:39.065 0:00:05.501 0.0 0.0 0.0 0.0
DgOp_xBrhhPSWa5Df2TovQ.. 0.00 <br>
</font></div>
<div class="moz-cite-prefix"><br>
</div>
<div class="moz-cite-prefix">The relevant parts of the CDR record:<br>
</div>
<div class="moz-cite-prefix"><font size="2" face="monospace">MariaDB
[accounting]> select * from cdr where id=116\G;<br>
*************************** 1. row ***************************<br>
id: 116<br>
update_time: 2022-10-18 11:37:51<br>
source_user_id:
e5eb8a53-d91a-4460-83b0-eb84cbc9240a<br>
source_provider_id: 1<br>
source_external_subscriber_id:<br>
source_external_contract_id:<br>
source_account_id: 7<br>
source_user: c1<br>
source_domain: test1654835032.example.org<br>
source_cli: 1121234567<br>
source_clir: 0<br>
source_ip: 10.0.1.10<br>
<br>
source_user_out: 1121234567<br>
destination_user_id:
a889b68d-9999-42a4-b897-e5c4bdb102f5<br>
destination_provider_id: 1<br>
destination_external_subscriber_id:<br>
destination_external_contract_id:<br>
destination_account_id: 7<br>
destination_user: c2<br>
destination_domain: test1654835032.example.org<br>
destination_user_dialed: 1127654321<br>
destination_user_in: 1127654321<br>
destination_domain_in: test1654835032.example.org<br>
<br>
destination_user_out: c2<br>
peer_auth_user:<br>
peer_auth_realm:<br>
call_type: call<br>
call_status: ok<br>
call_code: 200<br>
init_time: 1666085853.906<br>
start_time: 1666085859.065<br>
duration: 5.501<br>
call_id: DgOp_xBrhhPSWa5Df2TovQ..</font><br>
<br>
</div>
<div class="moz-cite-prefix">I hope this is enough to investigate,
if you need any more logs just let me know.</div>
<div class="moz-cite-prefix"><br>
</div>
<div class="moz-cite-prefix">Regards,</div>
<div class="moz-cite-prefix"><br>
</div>
<div class="moz-cite-prefix">Henk Plessius<br>
</div>
<div class="moz-cite-prefix"><br>
</div>
<div class="moz-cite-prefix">On 17-Oct-22 17:23, Marco Capetta via
Spce-user wrote:<br>
</div>
<blockquote type="cite" cite="mid:e1e6ac2e-7416-22b8-b4e8-ff3fcdca4026@sipwise.com">Hi
Henk,
<br>
<br>
Can you please detail a bit more?
<br>
In the subscribers' call list you should get on one user an entry
as outgoing call and on the other user an entry as incoming call.
<br>
If you check in the DB or in the exported records, how many CDRs
do you get in total for a single call?
<br>
<br>
Thank you
<br>
Regards
<br>
Marco
<br>
<br>
<br>
On 17/10/22 12:52, Henk wrote:
<br>
<blockquote type="cite">Hi all,
<br>
<br>
I'm on mr9.5.4 and found that calls between subscribers from the
same customer are appearing in both call lists, in my test the
callee gets to see the record of the caller.
<br>
<br>
The total cost for the callee in the CDR list will also not be
correct, as the amount from the caller is added.
<br>
<br>
Regards,
<br>
<br>
Henk Plessius
<br>
<br>
<br>
</blockquote>
<br>
<br>
</blockquote>
</body>
</html>