From shaheryarkh@gmail.com Tue Dec 23 21:46:03 2014
From: Muhammad Shahzad <shaheryarkh@gmail.com>
To: sr-dev@lists.kamailio.org
Subject: [sr-dev] Missing accounting events in ACC table
Date: Tue, 23 Dec 2014 21:45:36 +0100
Message-ID:
 <CAFZQphySVeV2UE_u+kUc7kO=KTvKWbqB1SW-WDEV0nByJ=t0Pg@mail.gmail.com>
MIME-Version: 1.0
Content-Type: multipart/mixed; boundary="===============0878628370=="

--===============0878628370==
Content-Type: text/plain; charset="utf-8"
Content-Transfer-Encoding: quoted-printable

Hi,

About 3 weeks ago i upgraded one of my production server with latest stable
kamailio version 4.2.1-fad00a. Now i am getting a lot of complaints about
missing CDR events in ACC table. I observe following problems,

1. There are only BYE records in acc table, no record for INVITE or ACK.
2. In kamailio logs when ACK is received against 200 OK response for
INVITE, i see following errors,

--
ERROR: <core> [parser/parse_from.c:113]: parse_from_uri(): failed to parse
>From uri
ERROR: pv [pv_core.c:434]: pv_get_xto_attr(): cannot parse From URI
NOTICE: <script>: [udp:<null>@1.0.0.127:5060]: Call from 'you(a)kamailio.org'
to 'you(a)kamailio.org' has been hanged up by '<null>' at '1419364717.255484'
--

Of course all these errors are bogus, I have checked all headers in ACK
(not just FROM header), they all seem perfectly fine and valid.

3. Then the dialog times out,

--
WARNING: dialog [dlg_handlers.c:1440]: dlg_ontimeout(): timeout for dlg
with CallID '6D8BD23CAC65AE3C1DE1D0B531F87B8CFEAA9CB9' and tags
'1D3ECD34F5731AB845BA3064AC95BB2D'
'7f55e81e0630-100007f-13c4-6009-2440a4-5fa31570-2440a4'
--

4. Any further sequential requests complain about "unable to find dialog",
e.g.

--
NOTICE: <script>: Sequencial 'BYE' request received from caller
ERROR: uac [replace.c:591]: restore_uri(): new URI [] shorter than old URI [
sip:00xxxxxxxxxx(a)sip.domain.com]
WARNING: dialog [dlg_handlers.c:1174]: dlg_onroute(): unable to find dialog
for BYE with route param '5ae1.d595' [7845:22877]
--

5. However the acc record for BYE is written to db and log file,

--
NOTICE: acc [acc.c:318]: acc_log_request(): ACC: transaction answered:
timestamp=3D1419364760;method=3DBYE;from_tag=3D7f55e81e0630-100007f-13c4-6009=
-2440a4-5fa31570-2440a4;to_tag=3D1D3ECD34F5731AB845BA3064AC95BB2D;call_id=3D6=
D8BD23CAC65AE3C1DE1D0B531F87B8CFEAA9CB9;code=3D200;reason=3DOK;src_user=3D00x=
xxxxxxxxx;src_domain=3D
sip.domain.com
;src_ip=3Dxx.xx.xx.xx;dst_ouser=3D+1xxxxxxxxxx;dst_user=3D1xxxxxxxxxx;dst_dom=
ain=3Dyy.yy.yy.yy
--


The same config was working fine with older version 4.2.0-97cab8. The
kamailio config i am using is pretty much standard,

--
#!define FLT_ACC 1
#!define FLT_ACCMISSED 2
#!define FLT_ACCFAILED 3
#!define FLT_DLG 4

...

modparam("acc", "early_media", 1)
modparam("acc", "report_ack", 1)
modparam("acc", "report_cancels", 1)
modparam("acc", "detect_direction", 1)
modparam("acc", "log_flag", FLT_ACC)
modparam("acc", "log_missed_flag", FLT_ACCMISSED)
modparam("acc", "failed_transaction_flag", FLT_ACCFAILED)
# log to db
modparam("acc", "db_flag", FLT_ACC)
modparam("acc", "db_missed_flag", FLT_ACCMISSED)
modparam("acc", "db_url", "DBURL")

...

request_route {
    # per request initial checks
    route(REQINIT);

    # NAT detection
    route(NATDETECT);

    # handle requests within SIP dialogs
    route(WITHINDLG);

    # CANCEL processing
    if (is_method("CANCEL")) {
        if (t_check_trans()) {
            t_relay();
        };
        exit;
    };

    #### only initial requests (no To tag) ####
    t_check_trans();

....

    # account only INVITEs
    if (is_method("INVITE")) {
        setflag(FLT_DLG); # create dialog
        setflag(FLT_ACC); # do accounting
        setflag(FLT_ACCFAILED); # ... even if the transaction fails

        $avp(dlg_timeout) =3D 60;
        dlg_manage();
....

}

--

Any ideas why its happening? Since it is 3 weeks old so may be problem has
already been spotted and fixed by someone else. Otherwise let me know how
can i provide more info to help fix this issue.

Thank you.



--===============0878628370==
Content-Type: text/html
Content-Transfer-Encoding: base64
Content-Disposition: attachment; filename="attachment.html"
MIME-Version: 1.0

PGRpdiBkaXI9Imx0ciI+PGRpdj5IaSw8YnI+PGJyPjwvZGl2PjxkaXY+QWJvdXQgMyB3ZWVrcyBh
Z28gaSB1cGdyYWRlZCBvbmUgb2YgbXkgcHJvZHVjdGlvbiBzZXJ2ZXIgd2l0aCBsYXRlc3Qgc3Rh
YmxlIGthbWFpbGlvIHZlcnNpb24gNC4yLjEtZmFkMDBhLiBOb3cgaSBhbSBnZXR0aW5nIGEgbG90
IG9mIGNvbXBsYWludHMgYWJvdXQgbWlzc2luZyBDRFIgZXZlbnRzIGluIEFDQyB0YWJsZS4gSSBv
YnNlcnZlIGZvbGxvd2luZyBwcm9ibGVtcyw8YnI+PGJyPjwvZGl2PjxkaXY+MS4gVGhlcmUgYXJl
IG9ubHkgQllFIHJlY29yZHMgaW4gYWNjIHRhYmxlLCBubyByZWNvcmQgZm9yIElOVklURSBvciBB
Q0suPGJyPjwvZGl2PjxkaXY+Mi4gSW4ga2FtYWlsaW8gbG9ncyB3aGVuIEFDSyBpcyByZWNlaXZl
ZCBhZ2FpbnN0IDIwMCBPSyByZXNwb25zZSBmb3IgSU5WSVRFLCBpIHNlZSBmb2xsb3dpbmcgZXJy
b3JzLDxicj48YnI+LS08YnI+RVJST1I6ICZsdDtjb3JlJmd0OyBbcGFyc2VyL3BhcnNlX2Zyb20u
YzoxMTNdOiBwYXJzZV9mcm9tX3VyaSgpOiBmYWlsZWQgdG8gcGFyc2UgRnJvbSB1cmkgPGJyPkVS
Uk9SOiBwdiBbcHZfY29yZS5jOjQzNF06IHB2X2dldF94dG9fYXR0cigpOiBjYW5ub3QgcGFyc2Ug
RnJvbSBVUkkgPGJyPk5PVElDRTogJmx0O3NjcmlwdCZndDs6IFt1ZHA6Jmx0O251bGwmZ3Q7QDxh
IGhyZWY9Imh0dHA6Ly8xLjAuMC4xMjc6NTA2MCI+MS4wLjAuMTI3OjUwNjA8L2E+XTogQ2FsbCBm
cm9tICYjMzk7PGEgaHJlZj0ibWFpbHRvOnlvdUBrYW1haWxpby5vcmciPnlvdUBrYW1haWxpby5v
cmc8L2E+JiMzOTsgdG8gJiMzOTs8YSBocmVmPSJtYWlsdG86eW91QGthbWFpbGlvLm9yZyI+eW91
QGthbWFpbGlvLm9yZzwvYT4mIzM5OyBoYXMgYmVlbiBoYW5nZWQgdXAgYnkgJiMzOTsmbHQ7bnVs
bCZndDsmIzM5OyBhdCAmIzM5OzE0MTkzNjQ3MTcuMjU1NDg0JiMzOTs8YnI+LS08YnI+PC9kaXY+
PGRpdj48YnI+PC9kaXY+PGRpdj5PZiBjb3Vyc2UgYWxsIHRoZXNlIGVycm9ycyBhcmUgYm9ndXMs
IEkgaGF2ZSBjaGVja2VkIGFsbCBoZWFkZXJzIGluIEFDSyAobm90IGp1c3QgRlJPTSBoZWFkZXIp
LCB0aGV5IGFsbCBzZWVtIHBlcmZlY3RseSBmaW5lIGFuZCB2YWxpZC48YnI+PGJyPjwvZGl2Pjxk
aXY+My4gVGhlbiB0aGUgZGlhbG9nIHRpbWVzIG91dCw8YnI+PGJyPi0tPGJyPldBUk5JTkc6IGRp
YWxvZyBbZGxnX2hhbmRsZXJzLmM6MTQ0MF06IGRsZ19vbnRpbWVvdXQoKTogdGltZW91dCBmb3Ig
ZGxnIHdpdGggQ2FsbElEICYjMzk7NkQ4QkQyM0NBQzY1QUUzQzFERTFEMEI1MzFGODdCOENGRUFB
OUNCOSYjMzk7IGFuZCB0YWdzICYjMzk7MUQzRUNEMzRGNTczMUFCODQ1QkEzMDY0QUM5NUJCMkQm
IzM5OyAmIzM5OzdmNTVlODFlMDYzMC0xMDAwMDdmLTEzYzQtNjAwOS0yNDQwYTQtNWZhMzE1NzAt
MjQ0MGE0JiMzOTvCoCA8YnI+LS08YnI+PGJyPjwvZGl2PjxkaXY+NC4gQW55IGZ1cnRoZXIgc2Vx
dWVudGlhbCByZXF1ZXN0cyBjb21wbGFpbiBhYm91dCAmcXVvdDt1bmFibGUgdG8gZmluZCBkaWFs
b2cmcXVvdDssIGUuZy48YnI+PGJyPi0tPGJyPk5PVElDRTogJmx0O3NjcmlwdCZndDs6IFNlcXVl
bmNpYWwgJiMzOTtCWUUmIzM5OyByZXF1ZXN0IHJlY2VpdmVkIGZyb20gY2FsbGVyPGJyPkVSUk9S
OiB1YWMgW3JlcGxhY2UuYzo1OTFdOiByZXN0b3JlX3VyaSgpOiBuZXcgVVJJIFtdIHNob3J0ZXIg
dGhhbiBvbGQgVVJJIFs8YSBocmVmPSJtYWlsdG86c2lwJTNBMDB4eHh4eHh4eHh4QHNpcC5kb21h
aW4uY29tIj5zaXA6MDB4eHh4eHh4eHh4QHNpcC5kb21haW4uY29tPC9hPl08YnI+V0FSTklORzog
ZGlhbG9nIFtkbGdfaGFuZGxlcnMuYzoxMTc0XTogZGxnX29ucm91dGUoKTogdW5hYmxlIHRvIGZp
bmQgZGlhbG9nIGZvciBCWUUgd2l0aCByb3V0ZSBwYXJhbSAmIzM5OzVhZTEuZDU5NSYjMzk7IFs3
ODQ1OjIyODc3XTxicj4tLTxicj48YnI+PC9kaXY+PGRpdj41LiBIb3dldmVyIHRoZSBhY2MgcmVj
b3JkIGZvciBCWUUgaXMgd3JpdHRlbiB0byBkYiBhbmQgbG9nIGZpbGUsPGJyPjxicj4tLTxicj5O
T1RJQ0U6IGFjYyBbYWNjLmM6MzE4XTogYWNjX2xvZ19yZXF1ZXN0KCk6IEFDQzogdHJhbnNhY3Rp
b24gYW5zd2VyZWQ6IHRpbWVzdGFtcD0xNDE5MzY0NzYwO21ldGhvZD1CWUU7ZnJvbV90YWc9N2Y1
NWU4MWUwNjMwLTEwMDAwN2YtMTNjNC02MDA5LTI0NDBhNC01ZmEzMTU3MC0yNDQwYTQ7dG9fdGFn
PTFEM0VDRDM0RjU3MzFBQjg0NUJBMzA2NEFDOTVCQjJEO2NhbGxfaWQ9NkQ4QkQyM0NBQzY1QUUz
QzFERTFEMEI1MzFGODdCOENGRUFBOUNCOTtjb2RlPTIwMDtyZWFzb249T0s7c3JjX3VzZXI9MDB4
eHh4eHh4eHh4O3NyY19kb21haW49PGEgaHJlZj0iaHR0cDovL3NpcC5kb21haW4uY29tIj5zaXAu
ZG9tYWluLmNvbTwvYT47c3JjX2lwPXh4Lnh4Lnh4Lnh4O2RzdF9vdXNlcj0rMXh4eHh4eHh4eHg7
ZHN0X3VzZXI9MXh4eHh4eHh4eHg7ZHN0X2RvbWFpbj15eS55eS55eS55eTxicj4tLTxicj48L2Rp
dj48ZGl2Pjxicj48YnI+PC9kaXY+PGRpdj5UaGUgc2FtZSBjb25maWcgd2FzIHdvcmtpbmcgZmlu
ZSB3aXRoIG9sZGVyIHZlcnNpb24gNC4yLjAtOTdjYWI4LiBUaGUga2FtYWlsaW8gY29uZmlnIGkg
YW0gdXNpbmcgaXMgcHJldHR5IG11Y2ggc3RhbmRhcmQsPGJyPjxicj4tLTxicj4jIWRlZmluZSBG
TFRfQUNDIDE8YnI+IyFkZWZpbmUgRkxUX0FDQ01JU1NFRCAyPGJyPiMhZGVmaW5lIEZMVF9BQ0NG
QUlMRUQgMzxicj4jIWRlZmluZSBGTFRfRExHIDQ8YnI+PGJyPi4uLjxicj48YnI+bW9kcGFyYW0o
JnF1b3Q7YWNjJnF1b3Q7LCAmcXVvdDtlYXJseV9tZWRpYSZxdW90OywgMSk8YnI+bW9kcGFyYW0o
JnF1b3Q7YWNjJnF1b3Q7LCAmcXVvdDtyZXBvcnRfYWNrJnF1b3Q7LCAxKTxicj5tb2RwYXJhbSgm
cXVvdDthY2MmcXVvdDssICZxdW90O3JlcG9ydF9jYW5jZWxzJnF1b3Q7LCAxKTxicj5tb2RwYXJh
bSgmcXVvdDthY2MmcXVvdDssICZxdW90O2RldGVjdF9kaXJlY3Rpb24mcXVvdDssIDEpPGJyPm1v
ZHBhcmFtKCZxdW90O2FjYyZxdW90OywgJnF1b3Q7bG9nX2ZsYWcmcXVvdDssIEZMVF9BQ0MpPGJy
Pm1vZHBhcmFtKCZxdW90O2FjYyZxdW90OywgJnF1b3Q7bG9nX21pc3NlZF9mbGFnJnF1b3Q7LCBG
TFRfQUNDTUlTU0VEKTxicj5tb2RwYXJhbSgmcXVvdDthY2MmcXVvdDssICZxdW90O2ZhaWxlZF90
cmFuc2FjdGlvbl9mbGFnJnF1b3Q7LCBGTFRfQUNDRkFJTEVEKTxicj48L2Rpdj48ZGl2PiMgbG9n
IHRvIGRiPGJyPjwvZGl2PjxkaXY+bW9kcGFyYW0oJnF1b3Q7YWNjJnF1b3Q7LCAmcXVvdDtkYl9m
bGFnJnF1b3Q7LCBGTFRfQUNDKTxicj5tb2RwYXJhbSgmcXVvdDthY2MmcXVvdDssICZxdW90O2Ri
X21pc3NlZF9mbGFnJnF1b3Q7LCBGTFRfQUNDTUlTU0VEKTxicj5tb2RwYXJhbSgmcXVvdDthY2Mm
cXVvdDssICZxdW90O2RiX3VybCZxdW90OywgJnF1b3Q7REJVUkwmcXVvdDspPGJyPjxicj4uLi48
YnI+PGJyPjwvZGl2PjxkaXY+cmVxdWVzdF9yb3V0ZSB7PGJyPsKgwqDCoCAjIHBlciByZXF1ZXN0
IGluaXRpYWwgY2hlY2tzPGJyPsKgwqDCoCByb3V0ZShSRVFJTklUKTs8YnI+PGJyPsKgwqDCoCAj
IE5BVCBkZXRlY3Rpb248YnI+wqDCoMKgIHJvdXRlKE5BVERFVEVDVCk7PGJyPjxicj7CoMKgwqAg
IyBoYW5kbGUgcmVxdWVzdHMgd2l0aGluIFNJUCBkaWFsb2dzPGJyPsKgwqDCoCByb3V0ZShXSVRI
SU5ETEcpOzxicj48YnI+wqDCoMKgICMgQ0FOQ0VMIHByb2Nlc3Npbmc8YnI+wqDCoMKgIGlmIChp
c19tZXRob2QoJnF1b3Q7Q0FOQ0VMJnF1b3Q7KSkgezxicj7CoMKgwqDCoMKgwqDCoCBpZiAodF9j
aGVja190cmFucygpKSB7PGJyPsKgwqDCoMKgwqDCoMKgwqDCoMKgwqAgdF9yZWxheSgpOzxicj7C
oMKgwqDCoMKgwqDCoCB9Ozxicj7CoMKgwqDCoMKgwqDCoCBleGl0Ozxicj7CoMKgwqAgfTs8YnI+
PGJyPsKgwqDCoCAjIyMjIG9ubHkgaW5pdGlhbCByZXF1ZXN0cyAobm8gVG8gdGFnKSAjIyMjPGJy
PsKgwqDCoCB0X2NoZWNrX3RyYW5zKCk7PGJyPjxicj4uLi4uPGJyPjxicj7CoMKgwqAgIyBhY2Nv
dW50IG9ubHkgSU5WSVRFczxicj7CoMKgwqAgaWYgKGlzX21ldGhvZCgmcXVvdDtJTlZJVEUmcXVv
dDspKSB7PGJyPsKgwqDCoMKgwqDCoMKgIHNldGZsYWcoRkxUX0RMRyk7ICMgY3JlYXRlIGRpYWxv
Zzxicj7CoMKgwqDCoMKgwqDCoCBzZXRmbGFnKEZMVF9BQ0MpOyAjIGRvIGFjY291bnRpbmc8YnI+
wqDCoMKgwqDCoMKgwqAgc2V0ZmxhZyhGTFRfQUNDRkFJTEVEKTsgIyAuLi4gZXZlbiBpZiB0aGUg
dHJhbnNhY3Rpb24gZmFpbHM8YnI+PGJyPsKgwqDCoMKgwqDCoMKgICRhdnAoZGxnX3RpbWVvdXQp
ID0gNjA7PGJyPsKgwqDCoMKgwqDCoMKgIGRsZ19tYW5hZ2UoKTs8YnI+Li4uLjxicj48YnI+fTxi
cj48L2Rpdj48ZGl2Pjxicj48L2Rpdj48ZGl2Pi0tPGJyPjxicj48L2Rpdj48ZGl2PkFueSBpZGVh
cyB3aHkgaXRzIGhhcHBlbmluZz8gU2luY2UgaXQgaXMgMyB3ZWVrcyBvbGQgc28gbWF5IGJlIHBy
b2JsZW0gaGFzIGFscmVhZHkgYmVlbiBzcG90dGVkIGFuZCBmaXhlZCBieSBzb21lb25lIGVsc2Uu
IE90aGVyd2lzZSBsZXQgbWUga25vdyBob3cgY2FuIGkgcHJvdmlkZSBtb3JlIGluZm8gdG8gaGVs
cCBmaXggdGhpcyBpc3N1ZS48YnI+PGJyPjwvZGl2PjxkaXY+VGhhbmsgeW91Ljxicj48L2Rpdj48
ZGl2Pjxicj48L2Rpdj48ZGl2Pjxicj48L2Rpdj48L2Rpdj4K

--===============0878628370==--