[Spce-user] mr3.4.1 sems: "no matching Contact - deregistered" / kamailio-lb contact (un)masking

Arjan Hakkesteegt arjan at hakwerk.com
Sun Aug 24 13:27:59 EDT 2014


I have been playing around some more, and if I force the Contact header 
of the peer reply in my kamailio.cfg.customtt.tt2 (near the end in 
onreply_route():
     remove_hf("Contact");
     append_hf("Contact: <sip:127.0.0.1:5080>;expires=200\r\n");
), then it works (at least for this particular scenario, and it is 
ignored when handling the 401 unauthorized):

U 2014/08/24 16:21:10.488831 72.251.241.166:5060 -> 178.X.Y.Z:5060
SIP/2.0 200 OK'
Via: SIP/2.0/UDP 
178.X.Y.Z;branch=z9hG4bK09ac.08655b889dff04f59a713f0f644d643d.0;rport=5060'
Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bK2jcbeaSi;rport=5080'
From: 
<sip:12345 at sip.nl.didlogic.net>;tag=478F2D6E-53F9F40C000A5CB8-335A3700'
To: 
<sip:12345 at sip.nl.didlogic.net>;tag=b27e1a1d33761e85846fc98f5f3a7e58.434f'
CSeq: 55 REGISTER'
Call-ID: 32D4C33B-53F9F40C000A5D59-336A4700'
Contact: 
<sip:ngcp-lb at 178.X.Y.Z:5060;ngcpct=7369703a3132372e302e302e313a35303830>;expires=3600'
Content-Length: 0'
'


U 2014/08/24 16:21:10.489967 127.0.0.1:5060 -> 127.0.0.1:5080
SIP/2.0 200 OK'
Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bK2jcbeaSi;rport=5080'
From: 
<sip:12345 at sip.nl.didlogic.net>;tag=478F2D6E-53F9F40C000A5CB8-335A3700'
To: 
<sip:12345 at sip.nl.didlogic.net>;tag=b27e1a1d33761e85846fc98f5f3a7e58.434f'
CSeq: 55 REGISTER'
Call-ID: 32D4C33B-53F9F40C000A5D59-336A4700'
Content-Length: 0'
P-NGCP-Src-Ip: 72.251.241.166'
P-NGCP-Src-Port: 5060'
P-NGCP-Src-Proto: udp'
P-NGCP-Src-Af: 4'
Contact: <sip:127.0.0.1:5080>;expires=200'
'

That is, then I see the SEMS registering the reply and counting down 
from 200sec (see attached SEMS log).
At 50% of the expires value it will reregister, as per the following 
method I guess:

bool AmSIPRegistration::timeToReregister(time_t now_sec) {
     return (((unsigned long)reg_begin+ reg_expires/2) < (unsigned 
long)now_sec);
}


I'm still trying to get the hang of all the logic in the kamailio.cfg 
routing and I have no idea of all the SIP scenarios involved, but in 
general I would expect that for incoming messages to the loadbalancer 
that have ngcpct set, kamailio should unmask the contact. Or is that too 
simplistic?


Regards,
Arjan


> Arjan Hakkesteegt <mailto:arjan at hakwerk.com>
> Sunday, August 24, 2014 15:44
> I attached the output from "ngrep-sip didlogic" for a minute or so, 
> showing the 10 sec interval. I also included the sems.log for that 
> same period.
>
> I did a grep on expire in all logfiles in /var/log/ngcp/ but there 
> never is an expires: 0 in the logs, only 3600. Any other idea how to 
> find such an unREGISTER?
>
> Arjan
> _______________________________________________
> Spce-user mailing list
> Spce-user at lists.sipwise.com
> https://lists.sipwise.com/listinfo/spce-user
> Daniel Grotti <mailto:dgrotti at sipwise.com>
> Sunday, August 24, 2014 14:26
> Hi,
> sorry in the trace below I just see the registration process at 09:39:43.
> Where do you see the second REGISTER after 10sec ? It should 
> Re-REGISTER again after 3600 sec.
>
> Also I don't see the unREGISTER message (with Expires: 0).
> Can you provide such messages ?
>
> Daniel
>
>
>
>
>
>
>
> On 08/24/2014 11:05 AM, Arjan Hakkesteegt wrote:
>
> Arjan Hakkesteegt <mailto:arjan at hakwerk.com>
> Sunday, August 24, 2014 11:05
> Hi all,
>
> I have a fresh install of mr3.4.1 (actually I messed up upgrading from 
> 3.3.1 on a mysql error and didn't have a proper backup; my bad. 
> Completey wiped the VM and started from scratch).
> SEMS is successfully registering with my SIP trunk provider, I can 
> call to and be called from the PSTN.
> However, looking at the sems logs ("debug: 'yes'" in config.yml, 
> attached), it is re-registering every 10 seconds because of a "no 
> matching Contact - deregistered" error.
>
> Below is the output of ngrep-sip for one attempt. I'm no expert but it 
> looks like the unmasking of the contact fails in kamailio-lb.
> 178.X.Y.Z = public IP address
> 12345 = didlogic user id
>
> I'd appreciate any help.
>
> Thanks,
> Arjan
>
>
>
> U 2014/08/24 09:39:43.210372 127.0.0.1:5080 -> 127.0.0.1:5060
> REGISTER sip:sip.nl.didlogic.net SIP/2.0'
> Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKIG5LNa8x;rport'
> From: 
> <sip:12345 at sip.nl.didlogic.net>;tag=227C9105-53F996BF00032EEE-6060F700'
> To: <sip:12345 at sip.nl.didlogic.net>'
> CSeq: 50 REGISTER'
> Call-ID: 015670DF-53F996BF00033117-60710700'
> Route: <sip:127.0.0.1:5060;lr>'
> Max-Forwards: 70'
> Expires: 3600'
> User-Agent: Sipwise NGCP Application Server 3.X'
> Contact: <sip:178.X.Y.Z:5060;transport=udp>'
> Content-Length: 0'
> '
>
>
> U 2014/08/24 09:39:43.211469 178.X.Y.Z:5060 -> 72.251.241.166:5060
> REGISTER sip:sip.nl.didlogic.net SIP/2.0'
> Via: SIP/2.0/UDP 
> 178.X.Y.Z;branch=z9hG4bKc603.4d33604a362202e4562c26f0f8afb615.0'
> Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKIG5LNa8x;rport=5080'
> From: 
> <sip:12345 at sip.nl.didlogic.net>;tag=227C9105-53F996BF00032EEE-6060F700'
> To: <sip:12345 at sip.nl.didlogic.net>'
> CSeq: 50 REGISTER'
> Call-ID: 015670DF-53F996BF00033117-60710700'
> Max-Forwards: 69'
> Expires: 3600'
> User-Agent: Sipwise NGCP Application Server 3.X'
> Contact: <sip:178.X.Y.Z:5060;transport=udp>'
> Content-Length: 0'
> '
>
>
> U 2014/08/24 09:39:43.258182 72.251.241.166:5060 -> 178.X.Y.Z:5060
> SIP/2.0 401 Unauthorized'
> Via: SIP/2.0/UDP 
> 178.X.Y.Z;branch=z9hG4bKc603.4d33604a362202e4562c26f0f8afb615.0;rport=5060' 
>
> Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKIG5LNa8x;rport=5080'
> From: 
> <sip:12345 at sip.nl.didlogic.net>;tag=227C9105-53F996BF00032EEE-6060F700'
> To: 
> <sip:12345 at sip.nl.didlogic.net>;tag=b27e1a1d33761e85846fc98f5f3a7e58.fae6' 
>
> CSeq: 50 REGISTER'
> Call-ID: 015670DF-53F996BF00033117-60710700'
> WWW-Authenticate: Digest realm="sip.nl.didlogic.net", 
> nonce="U/mX61P5lr9U1NSl+99whZC51Z20733QKpDIeoA=", qop="auth"'
> Content-Length: 0'
> '
>
>
> U 2014/08/24 09:39:43.258552 127.0.0.1:5060 -> 127.0.0.1:5080
> SIP/2.0 401 Unauthorized'
> Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKIG5LNa8x;rport=5080'
> From: 
> <sip:12345 at sip.nl.didlogic.net>;tag=227C9105-53F996BF00032EEE-6060F700'
> To: 
> <sip:12345 at sip.nl.didlogic.net>;tag=b27e1a1d33761e85846fc98f5f3a7e58.fae6' 
>
> CSeq: 50 REGISTER'
> Call-ID: 015670DF-53F996BF00033117-60710700'
> WWW-Authenticate: Digest realm="sip.nl.didlogic.net", 
> nonce="U/mX61P5lr9U1NSl+99whZC51Z20733QKpDIeoA=", qop="auth"'
> Content-Length: 0'
> P-NGCP-Src-Ip: 72.251.241.166'
> P-NGCP-Src-Port: 5060'
> P-NGCP-Src-Proto: udp'
> P-NGCP-Src-Af: 4'
> '
>
>
> U 2014/08/24 09:39:43.260249 127.0.0.1:5080 -> 127.0.0.1:5060
> REGISTER sip:sip.nl.didlogic.net SIP/2.0'
> Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKRaVVYaD1;rport'
> From: 
> <sip:12345 at sip.nl.didlogic.net>;tag=227C9105-53F996BF00032EEE-6060F700'
> To: <sip:12345 at sip.nl.didlogic.net>'
> CSeq: 51 REGISTER'
> Call-ID: 015670DF-53F996BF00033117-60710700'
> Route: <sip:127.0.0.1:5060;lr>'
> Max-Forwards: 70'
> Expires: 3600'
> Authorization: Digest username="12345", realm="sip.nl.didlogic.net", 
> nonce="U/mX61P5lr9U1NSl+99whZC51Z20733QKpDIeoA=", 
> uri="sip:sip.nl.didlogic.net", qop=auth, cnonce="570de1e7", 
> nc=00000001, response="2b54500d9b70647866d0190affd850a0", algorithm=MD5'
> Contact: <sip:127.0.0.1:5080;transport=udp>'
> Contact: <sip:178.X.Y.Z:5060;transport=udp>'
> Content-Length: 0'
> '
>
>
> U 2014/08/24 09:39:43.260751 178.X.Y.Z:5060 -> 72.251.241.166:5060
> REGISTER sip:sip.nl.didlogic.net SIP/2.0'
> Via: SIP/2.0/UDP 
> 178.X.Y.Z;branch=z9hG4bKd603.3582e31aa520aa31282983c13533f2b7.0'
> Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKRaVVYaD1;rport=5080'
> From: 
> <sip:12345 at sip.nl.didlogic.net>;tag=227C9105-53F996BF00032EEE-6060F700'
> To: <sip:12345 at sip.nl.didlogic.net>'
> CSeq: 51 REGISTER'
> Call-ID: 015670DF-53F996BF00033117-60710700'
> Max-Forwards: 69'
> Expires: 3600'
> Authorization: Digest username="12345", realm="sip.nl.didlogic.net", 
> nonce="U/mX61P5lr9U1NSl+99whZC51Z20733QKpDIeoA=", 
> uri="sip:sip.nl.didlogic.net", qop=auth, cnonce="570de1e7", 
> nc=00000001, response="2b54500d9b70647866d0190affd850a0", algorithm=MD5'
> Content-Length: 0'
> Contact: 
> <sip:ngcp-lb at 178.X.Y.Z:5060;ngcpct=7369703a3132372e302e302e313a35303830>'
> '
>
>
> U 2014/08/24 09:39:43.288129 72.251.241.166:5060 -> 178.X.Y.Z:5060
> SIP/2.0 200 OK'
> Via: SIP/2.0/UDP 
> 178.X.Y.Z;branch=z9hG4bKd603.3582e31aa520aa31282983c13533f2b7.0;rport=5060' 
>
> Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKRaVVYaD1;rport=5080'
> From: 
> <sip:12345 at sip.nl.didlogic.net>;tag=227C9105-53F996BF00032EEE-6060F700'
> To: 
> <sip:12345 at sip.nl.didlogic.net>;tag=b27e1a1d33761e85846fc98f5f3a7e58.95fd' 
>
> CSeq: 51 REGISTER'
> Call-ID: 015670DF-53F996BF00033117-60710700'
> Contact: 
> <sip:ngcp-lb at 178.X.Y.Z:5060;ngcpct=7369703a3132372e302e302e313a35303830>;expires=3600' 
>
> Content-Length: 0'
> '
>
>
> U 2014/08/24 09:39:43.288567 127.0.0.1:5060 -> 127.0.0.1:5080
> SIP/2.0 200 OK'
> Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKRaVVYaD1;rport=5080'
> From: 
> <sip:12345 at sip.nl.didlogic.net>;tag=227C9105-53F996BF00032EEE-6060F700'
> To: 
> <sip:12345 at sip.nl.didlogic.net>;tag=b27e1a1d33761e85846fc98f5f3a7e58.95fd' 
>
> CSeq: 51 REGISTER'
> Call-ID: 015670DF-53F996BF00033117-60710700'
> Contact: 
> <sip:ngcp-lb at 178.X.Y.Z:5060;ngcpct=7369703a3132372e302e302e313a35303830>;expires=3600' 
>
> Content-Length: 0'
> P-NGCP-Src-Ip: 72.251.241.166'
> P-NGCP-Src-Port: 5060'
> P-NGCP-Src-Proto: udp'
> P-NGCP-Src-Af: 4'
> '
>
> _______________________________________________
> Spce-user mailing list
> Spce-user at lists.sipwise.com
> https://lists.sipwise.com/listinfo/spce-user
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20140824/6b96274b/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: postbox-contact.jpg
Type: image/jpeg
Size: 1321 bytes
Desc: not available
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20140824/6b96274b/attachment-0002.jpg>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: postbox-contact.jpg
Type: image/jpeg
Size: 1214 bytes
Desc: not available
URL: <http://lists.sipwise.com/pipermail/spce-user_lists.sipwise.com/attachments/20140824/6b96274b/attachment-0003.jpg>
-------------- next part --------------
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, AmSipRegistration.cpp:287] DEBUG:  positive reply to REGISTER!
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:479] DEBUG:  --- Uri Info ---
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:480] DEBUG:   uri           '<sip:127.0.0.1:5080>..'
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:481] DEBUG:   display_name  'Contact:'
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:482] DEBUG:   uri_user      ''
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:483] DEBUG:   uri_host      '127.0.0.1'
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:484] DEBUG:   uri_port      '5080'
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:485] DEBUG:   uri_hdr       ''
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:486] DEBUG:   uri_param     ''
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:495] DEBUG:  --------------------
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:479] DEBUG:  --- Uri Info ---
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:480] DEBUG:   uri           '<sip:127.0.0.1:5080>'
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:481] DEBUG:   display_name  ''
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:482] DEBUG:   uri_user      ''
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:483] DEBUG:   uri_host      '127.0.0.1'
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:484] DEBUG:   uri_port      '5080'
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:485] DEBUG:   uri_hdr       ''
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:486] DEBUG:   uri_param     ''
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:493] DEBUG:   param     'expires'='200'
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:495] DEBUG:  --------------------
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, AmSipRegistration.cpp:314] DEBUG:  contact found
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, AmSipRegistration.cpp:321] DEBUG:  got an expires of 200
Aug 24 16:22:51 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, AmSipRegistration.cpp:326] DEBUG:  posting SIPRegistrationEvent to 'reg_agent'
Aug 24 16:22:56 sip ngcp-sems[7535]: [#7f2b31e43700] [fire, sip_trans.cpp:205] DEBUG:  Transaction timer expired: type=K, trans=0x1110e10, eta=356010, t=356010
Aug 24 16:22:56 sip ngcp-sems[7535]: [#7f2b31e43700] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old timer of type K (this=0x7f2b340b8d20)
Aug 24 16:22:56 sip ngcp-sems[7535]: [#7f2b31e43700] [fire, sip_trans.cpp:205] DEBUG:  Transaction timer expired: type=K, trans=0x1111fa0, eta=356012, t=356012
Aug 24 16:22:56 sip ngcp-sems[7535]: [#7f2b31e43700] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old timer of type K (this=0x110ed30)
Aug 24 16:22:58 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 193s for registration.
Aug 24 16:23:08 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 183s for registration.
Aug 24 16:23:18 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 173s for registration.
Aug 24 16:23:28 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 163s for registration.
Aug 24 16:23:38 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 153s for registration.
Aug 24 16:23:48 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 143s for registration.
Aug 24 16:23:58 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 133s for registration.
Aug 24 16:24:08 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 123s for registration.
Aug 24 16:24:18 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 113s for registration.
Aug 24 16:24:28 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 103s for registration.
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [do_auth, UACAuth.cpp:401] DEBUG:  realm='sip.nl.didlogic.net', nonce='U/n2Z1P59TukBiMOs8y3EnmnaXMr2gXlKvJxwoA=', qop='auth'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [do_auth, UACAuth.cpp:447] DEBUG:  calculated response = 91c7f87b81f92da2a04481c356e7abad
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [do_auth, UACAuth.cpp:468] DEBUG:  Auth req hdr: 'Authorization: Digest username="12345", realm="sip.nl.didlogic.net", nonce="U/n2Z1P59TukBiMOs8y3EnmnaXMr2gXlKvJxwoA=", uri="sip:sip.nl.didlogic.net", qop=auth, cnonce="6413544c", nc=00000001, response="91c7f87b81f92da2a04481c356e7abad", algorithm=MD5.'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSendRequest, UACAuth.cpp:271] DEBUG:  adding 58 to list of sent requests.
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI port (5060) to int (5060)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [set_next_hop, trans_layer.cpp:921] DEBUG:  no transport specified, setting default one (udp)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [set_next_hop, trans_layer.cpp:928] DEBUG:  next_hop:next_port is <127.0.0.1:5060/udp>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [resolve_targets, resolver.cpp:1080] DEBUG:  sip_destination: 127.0.0.1:5060/udp
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [set_destination_ip, resolver.cpp:1005] DEBUG:  checking whether '127.0.0.1' is IP address...
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [set_destination_ip, resolver.cpp:1063] DEBUG:  set destination to 127.0.0.1:5060
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [debug, resolver.cpp:794] DEBUG:  target list:
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [debug, resolver.cpp:801] DEBUG:  #011127.0.0.1:5060/udp to target list
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send_request, trans_layer.cpp:1264] DEBUG:  send_request to R-URI <sip:sip.nl.didlogic.net>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [patch_contact_transport, trans_layer.cpp:160] DEBUG:  contact: <<sip:178.X.Y.Z:5060>>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI port (5060) to int (5060)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI port () to int (5060)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_via_params, parse_via.cpp:521] DEBUG:  parsed branch: z9hG4bKvtPSpazn
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_via_params, parse_via.cpp:544] DEBUG:  has_rport: 1
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send_request, trans_layer.cpp:1300] DEBUG:  Sending to 127.0.0.1:5060 <REGISTER sip:sip.nl.didlogic.net SIP/2.0..Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKvtPSpazn;rport..From: <sip:12345 at sip.nl.didlogic.net>;tag=478F2D6E-53F9F40C000A5CB8-335A3700..To: <sip:12345 at sip.nl.didlogic.net>..CSeq: 58 REGISTER..Call-ID: 32D4C33B-53F9F40C000A5D59-336A4700..Route: <sip:127.0.0.1:5060;lr>..Max-Forwards: 70..Expires: 3600..Authorization: Digest username="12345", realm="sip.nl.didlogic.net", nonce="U/n2Z1P59TukBiMOs8y3EnmnaXMr2gXlKvJxwoA=", uri="sip:sip.nl.didlogic.net", qop=auth, cnonce="6413544c", nc=00000001, response="91c7f87b81f92da2a04481c356e7abad", algorithm=MD5..User-Agent: Sipwise NGCP Application Server 3.X..Contact: <sip:178.X.Y.Z:5060;transport=udp>..Content-Length: 0.......>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send, udp_trsp.cpp:259] DEBUG: send  msg to 127.0.0.1:5060.--++--.REGISTER sip:sip.nl.didlogic.net SIP/2.0..Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKvtPSpazn;rport..From: <sip:12345 at sip.nl.didlogic.net>;tag=478F2D6E-53F9F40C000A5CB8-335A3700..To: <sip:12345 at sip.nl.didlogic.net>..CSeq: 58 REGISTER..Call-ID: 32D4C33B-53F9F40C000A5D59-336A4700..Route: <sip:127.0.0.1:5060;lr>..Max-Forwards: 70..Expires: 3600..Authorization: Digest username="12345", realm="sip.nl.didlogic.net", nonce="U/n2Z1P59TukBiMOs8y3EnmnaXMr2gXlKvJxwoA=", uri="sip:sip.nl.didlogic.net", qop=auth, cnonce="6413544c", nc=00000001, response="91c7f87b81f92da2a04481c356e7abad", algorithm=MD5..User-Agent: Sipwise NGCP Application Server 3.X..Contact: <sip:178.X.Y.Z:5060;transport=udp>..Content-Length: 0....--++--
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send_request, trans_layer.cpp:1327] DEBUG:  update_uac_request tt->_t =(nil)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [update_uac_request, trans_layer.cpp:2040] DEBUG:  update_uac_request(t=0x1110dd0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [update_uac_request, trans_layer.cpp:2076] DEBUG:  transport = 'udp'; is_reliable = 0
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type E at time=360839 (repeated=0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type F at time=362414 (repeated=0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send_request, trans_layer.cpp:1349] DEBUG:  logger = (nil)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via UDP from 127.0.0.1:5060 vv.--++--.SIP/2.0 401 Unauthorized..Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKvtPSpazn;rport=5080..From: <sip:12345 at sip.nl.didlogic.net>;tag=478F2D6E-53F9F40C000A5CB8-335A3700..To: <sip:12345 at sip.nl.didlogic.net>;tag=b27e1a1d33761e85846fc98f5f3a7e58.76fb..CSeq: 58 REGISTER..Call-ID: 32D4C33B-53F9F40C000A5D59-336A4700..WWW-Authenticate: Digest realm="sip.nl.didlogic.net", nonce="U/n2zFP59aDbAQoblFDHHtAO1gmzfMTGKvLKGoA=", qop="auth"..Content-Length: 0..P-NGCP-Src-Ip: 72.251.241.166..P-NGCP-Src-Port: 5060..P-NGCP-Src-Proto: udp..P-NGCP-Src-Af: 4..Contact: <sip:127.0.0.1:5080>;expires=200....--++--
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [parse_via_params, parse_via.cpp:521] DEBUG:  parsed branch: z9hG4bKvtPSpazn
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [parse_via_params, parse_via.cpp:544] DEBUG:  has_rport: 1
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [process_rcvd_msg, trans_layer.cpp:1708] DEBUG:  Reply matched an existing transaction
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [update_uac_reply, trans_layer.cpp:1766] DEBUG:  update_uac_reply(reply code = 401, trans=0x1110dd0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old timer of type E (this=0x110ea70)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old timer of type F (this=0x1110540)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type K at time=361066 (repeated=0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:813] DEBUG:  Received reply: 401 Unauthorized
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:814] DEBUG:  reply.callid = <32D4C33B-53F9F40C000A5D59-336A4700>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:815] DEBUG:  reply.from_tag = <478F2D6E-53F9F40C000A5CB8-335A3700>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:816] DEBUG:  reply.to_tag = <b27e1a1d33761e85846fc98f5f3a7e58.76fb>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:817] DEBUG:  reply.contact = <<sip:127.0.0.1:5080>;expires=200>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:818] DEBUG:  reply.to_uri = <sip:127.0.0.1:5080>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:819] DEBUG:  cseq = <58>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:820] DEBUG:  reply.route = <>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:821] DEBUG:  hdrs = <WWW-Authenticate: Digest realm="sip.nl.didlogic.net", nonce="U/n2zFP59aDbAQoblFDHHtAO1gmzfMTGKvLKGoA=", qop="auth"..P-NGCP-Src-Ip: 72.251.241.166..P-NGCP-Src-Port: 5060..P-NGCP-Src-Proto: udp..P-NGCP-Src-Af: 4..>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:822] DEBUG:  body-ct = <>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31b40700] [handle_sip_reply, SipCtrlInterface.cpp:828] DEBUG:  ^^ M [32D4C33B-53F9F40C000A5D59-336A4700|478F2D6E-53F9F40C000A5CB8-335A3700] ru SIP reply 401 Unauthorized handled ^^
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [get_reg, SIPRegistrarClient.cpp:254] DEBUG:  get registration '478F2D6E-53F9F40C000A5CB8-335A3700'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [get_reg, SIPRegistrarClient.cpp:262] DEBUG:  get registration : res = '17878400' (this = 139823532783808)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onRxReply, AmBasicSipDialog.cpp:445] DEBUG:  onRxReply(rep = 401 Unauthorized): transaction found!
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, UACAuth.cpp:165] DEBUG:  SIP reply with code 401 cseq 58 .
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, UACAuth.cpp:171] DEBUG:   UACAuth - processing with reply code 401
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [do_auth, UACAuth.cpp:401] DEBUG:  realm='sip.nl.didlogic.net', nonce='U/n2zFP59aDbAQoblFDHHtAO1gmzfMTGKvLKGoA=', qop='auth'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [do_auth, UACAuth.cpp:447] DEBUG:  calculated response = e3368a1d26ff55d154c269486175428a
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [do_auth, UACAuth.cpp:468] DEBUG:  Auth req hdr: 'Authorization: Digest username="12345", realm="sip.nl.didlogic.net", nonce="U/n2zFP59aDbAQoblFDHHtAO1gmzfMTGKvLKGoA=", uri="sip:sip.nl.didlogic.net", qop=auth, cnonce="040e06f4", nc=00000001, response="e3368a1d26ff55d154c269486175428a", algorithm=MD5.'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSendRequest, UACAuth.cpp:271] DEBUG:  adding 59 to list of sent requests.
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI port (5060) to int (5060)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [set_next_hop, trans_layer.cpp:921] DEBUG:  no transport specified, setting default one (udp)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [set_next_hop, trans_layer.cpp:928] DEBUG:  next_hop:next_port is <127.0.0.1:5060/udp>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [resolve_targets, resolver.cpp:1080] DEBUG:  sip_destination: 127.0.0.1:5060/udp
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [set_destination_ip, resolver.cpp:1005] DEBUG:  checking whether '127.0.0.1' is IP address...
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [set_destination_ip, resolver.cpp:1063] DEBUG:  set destination to 127.0.0.1:5060
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [debug, resolver.cpp:794] DEBUG:  target list:
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [debug, resolver.cpp:801] DEBUG:  #011127.0.0.1:5060/udp to target list
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send_request, trans_layer.cpp:1264] DEBUG:  send_request to R-URI <sip:sip.nl.didlogic.net>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [patch_contact_transport, trans_layer.cpp:160] DEBUG:  contact: <<sip:127.0.0.1:5080>>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI port (5080) to int (5080)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [patch_contact_transport, trans_layer.cpp:160] DEBUG:  contact: <<sip:178.X.Y.Z:5060>>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI port (5060) to int (5060)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_sip_uri, parse_uri.cpp:338] DEBUG:  Converted URI port () to int (5060)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_via_params, parse_via.cpp:521] DEBUG:  parsed branch: z9hG4bKWQMR7aaK
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [parse_via_params, parse_via.cpp:544] DEBUG:  has_rport: 1
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send_request, trans_layer.cpp:1300] DEBUG:  Sending to 127.0.0.1:5060 <REGISTER sip:sip.nl.didlogic.net SIP/2.0..Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKWQMR7aaK;rport..From: <sip:12345 at sip.nl.didlogic.net>;tag=478F2D6E-53F9F40C000A5CB8-335A3700..To: <sip:12345 at sip.nl.didlogic.net>..CSeq: 59 REGISTER..Call-ID: 32D4C33B-53F9F40C000A5D59-336A4700..Route: <sip:127.0.0.1:5060;lr>..Max-Forwards: 70..Expires: 3600..Authorization: Digest username="12345", realm="sip.nl.didlogic.net", nonce="U/n2zFP59aDbAQoblFDHHtAO1gmzfMTGKvLKGoA=", uri="sip:sip.nl.didlogic.net", qop=auth, cnonce="040e06f4", nc=00000001, response="e3368a1d26ff55d154c269486175428a", algorithm=MD5..Contact: <sip:127.0.0.1:5080;transport=udp>..Contact: <sip:178.X.Y.Z:5060;transport=udp>..Content-Length: 0.......>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send, udp_trsp.cpp:259] DEBUG: send  msg to 127.0.0.1:5060.--++--.REGISTER sip:sip.nl.didlogic.net SIP/2.0..Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKWQMR7aaK;rport..From: <sip:12345 at sip.nl.didlogic.net>;tag=478F2D6E-53F9F40C000A5CB8-335A3700..To: <sip:12345 at sip.nl.didlogic.net>..CSeq: 59 REGISTER..Call-ID: 32D4C33B-53F9F40C000A5D59-336A4700..Route: <sip:127.0.0.1:5060;lr>..Max-Forwards: 70..Expires: 3600..Authorization: Digest username="12345", realm="sip.nl.didlogic.net", nonce="U/n2zFP59aDbAQoblFDHHtAO1gmzfMTGKvLKGoA=", uri="sip:sip.nl.didlogic.net", qop=auth, cnonce="040e06f4", nc=00000001, response="e3368a1d26ff55d154c269486175428a", algorithm=MD5..Contact: <sip:127.0.0.1:5080;transport=udp>..Contact: <sip:178.X.Y.Z:5060;transport=udp>..Content-Length: 0....--++--
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send_request, trans_layer.cpp:1327] DEBUG:  update_uac_request tt->_t =(nil)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [update_uac_request, trans_layer.cpp:2040] DEBUG:  update_uac_request(t=0x11125d0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [update_uac_request, trans_layer.cpp:2076] DEBUG:  transport = 'udp'; is_reliable = 0
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type E at time=360841 (repeated=0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type F at time=362416 (repeated=0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [send_request, trans_layer.cpp:1349] DEBUG:  logger = (nil)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, UACAuth.cpp:234] DEBUG:  authenticated request successfully sent.
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [run, udp_trsp.cpp:352] DEBUG: vv M [|] u recvd msg via UDP from 127.0.0.1:5060 vv.--++--.SIP/2.0 200 OK..Via: SIP/2.0/UDP 127.0.0.1:5080;branch=z9hG4bKWQMR7aaK;rport=5080..From: <sip:12345 at sip.nl.didlogic.net>;tag=478F2D6E-53F9F40C000A5CB8-335A3700..To: <sip:12345 at sip.nl.didlogic.net>;tag=b27e1a1d33761e85846fc98f5f3a7e58.0852..CSeq: 59 REGISTER..Call-ID: 32D4C33B-53F9F40C000A5D59-336A4700..Content-Length: 0..P-NGCP-Src-Ip: 72.251.241.166..P-NGCP-Src-Port: 5060..P-NGCP-Src-Proto: udp..P-NGCP-Src-Af: 4..Contact: <sip:127.0.0.1:5080>;expires=200....--++--
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [parse_via_params, parse_via.cpp:521] DEBUG:  parsed branch: z9hG4bKWQMR7aaK
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [parse_via_params, parse_via.cpp:544] DEBUG:  has_rport: 1
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [process_rcvd_msg, trans_layer.cpp:1708] DEBUG:  Reply matched an existing transaction
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [update_uac_reply, trans_layer.cpp:1766] DEBUG:  update_uac_reply(reply code = 200, trans=0x11125d0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old timer of type E (this=0x110d8b0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old timer of type F (this=0x11117a0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [reset_timer, sip_trans.cpp:246] DEBUG:  New timer of type K at time=361068 (repeated=0)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:813] DEBUG:  Received reply: 200 OK
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:814] DEBUG:  reply.callid = <32D4C33B-53F9F40C000A5D59-336A4700>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:815] DEBUG:  reply.from_tag = <478F2D6E-53F9F40C000A5CB8-335A3700>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:816] DEBUG:  reply.to_tag = <b27e1a1d33761e85846fc98f5f3a7e58.0852>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:817] DEBUG:  reply.contact = <<sip:127.0.0.1:5080>;expires=200>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:818] DEBUG:  reply.to_uri = <sip:127.0.0.1:5080>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:819] DEBUG:  cseq = <59>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:820] DEBUG:  reply.route = <>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:821] DEBUG:  hdrs = <P-NGCP-Src-Ip: 72.251.241.166..P-NGCP-Src-Port: 5060..P-NGCP-Src-Proto: udp..P-NGCP-Src-Af: 4..>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:822] DEBUG:  body-ct = <>
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b31c41700] [handle_sip_reply, SipCtrlInterface.cpp:828] DEBUG:  ^^ M [32D4C33B-53F9F40C000A5D59-336A4700|478F2D6E-53F9F40C000A5CB8-335A3700] ru SIP reply 200 OK handled ^^
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [get_reg, SIPRegistrarClient.cpp:254] DEBUG:  get registration '478F2D6E-53F9F40C000A5CB8-335A3700'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [get_reg, SIPRegistrarClient.cpp:262] DEBUG:  get registration : res = '17878400' (this = 139823532783808)
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onRxReply, AmBasicSipDialog.cpp:445] DEBUG:  onRxReply(rep = 200 OK): transaction found!
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, AmSipRegistration.cpp:287] DEBUG:  positive reply to REGISTER!
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:479] DEBUG:  --- Uri Info ---
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:480] DEBUG:   uri           '<sip:127.0.0.1:5080>..'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:481] DEBUG:   display_name  'Contact:'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:482] DEBUG:   uri_user      ''
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:483] DEBUG:   uri_host      '127.0.0.1'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:484] DEBUG:   uri_port      '5080'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:485] DEBUG:   uri_hdr       ''
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:486] DEBUG:   uri_param     ''
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:495] DEBUG:  --------------------
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:479] DEBUG:  --- Uri Info ---
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:480] DEBUG:   uri           '<sip:127.0.0.1:5080>'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:481] DEBUG:   display_name  ''
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:482] DEBUG:   uri_user      ''
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:483] DEBUG:   uri_host      '127.0.0.1'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:484] DEBUG:   uri_port      '5080'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:485] DEBUG:   uri_hdr       ''
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:486] DEBUG:   uri_param     ''
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:493] DEBUG:   param     'expires'='200'
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [dump, AmUriParser.cpp:495] DEBUG:  --------------------
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, AmSipRegistration.cpp:314] DEBUG:  contact found
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, AmSipRegistration.cpp:321] DEBUG:  got an expires of 200
Aug 24 16:24:32 sip ngcp-sems[7535]: [#7f2b336a4700] [onSipReply, AmSipRegistration.cpp:326] DEBUG:  posting SIPRegistrationEvent to 'reg_agent'
Aug 24 16:24:37 sip ngcp-sems[7535]: [#7f2b31e43700] [fire, sip_trans.cpp:205] DEBUG:  Transaction timer expired: type=K, trans=0x1110dd0, eta=361066, t=361066
Aug 24 16:24:37 sip ngcp-sems[7535]: [#7f2b31e43700] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old timer of type K (this=0x110d8f0)
Aug 24 16:24:37 sip ngcp-sems[7535]: [#7f2b31e43700] [fire, sip_trans.cpp:205] DEBUG:  Transaction timer expired: type=K, trans=0x11125d0, eta=361068, t=361068
Aug 24 16:24:37 sip ngcp-sems[7535]: [#7f2b31e43700] [reset_timer, sip_trans.cpp:188] DEBUG:  Clearing old timer of type K (this=0x110ff90)
Aug 24 16:24:38 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 194s for registration.
Aug 24 16:24:48 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 184s for registration.
Aug 24 16:24:58 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 174s for registration.
Aug 24 16:25:08 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 164s for registration.
Aug 24 16:25:18 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 154s for registration.
Aug 24 16:25:28 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 144s for registration.
Aug 24 16:25:38 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 134s for registration.
Aug 24 16:25:48 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 124s for registration.
Aug 24 16:25:58 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 114s for registration.
Aug 24 16:26:08 sip ngcp-sems[7535]: [#7f2b335a3700] [check_registration, RegistrationAgent.cpp:168] DEBUG:  Got state RegisterActive with expires 104s for registration.




More information about the Spce-user mailing list