<html xmlns:v="urn:schemas-microsoft-com:vml" xmlns:o="urn:schemas-microsoft-com:office:office" xmlns:w="urn:schemas-microsoft-com:office:word" xmlns:m="http://schemas.microsoft.com/office/2004/12/omml" xmlns="http://www.w3.org/TR/REC-html40">
<head>
<meta http-equiv="Content-Type" content="text/html; charset=utf-8">
<meta name="Generator" content="Microsoft Word 15 (filtered medium)">
<style><!--
/* Font Definitions */
@font-face
{font-family:"Cambria Math";
panose-1:2 4 5 3 5 4 6 3 2 4;}
@font-face
{font-family:Calibri;
panose-1:2 15 5 2 2 2 4 3 2 4;}
@font-face
{font-family:wf_segoe-ui_normal;}
@font-face
{font-family:Consolas;
panose-1:2 11 6 9 2 2 4 3 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
{margin:0cm;
font-size:11.0pt;
font-family:"Calibri",sans-serif;}
a:link, span.MsoHyperlink
{mso-style-priority:99;
color:#0563C1;
text-decoration:underline;}
pre
{mso-style-priority:99;
mso-style-link:"HTML Preformatted Char";
margin:0cm;
font-size:10.0pt;
font-family:"Courier New";}
span.HTMLPreformattedChar
{mso-style-name:"HTML Preformatted Char";
mso-style-priority:99;
mso-style-link:"HTML Preformatted";
font-family:Consolas;}
span.EmailStyle23
{mso-style-type:personal-reply;
font-family:"Calibri",sans-serif;
color:windowtext;}
.MsoChpDefault
{mso-style-type:export-only;
font-size:10.0pt;}
@page WordSection1
{size:612.0pt 792.0pt;
margin:72.0pt 72.0pt 72.0pt 72.0pt;}
div.WordSection1
{page:WordSection1;}
--></style><!--[if gte mso 9]><xml>
<o:shapedefaults v:ext="edit" spidmax="1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
<o:shapelayout v:ext="edit">
<o:idmap v:ext="edit" data="1" />
</o:shapelayout></xml><![endif]-->
</head>
<body lang="EN-US" link="#0563C1" vlink="purple" style="word-wrap:break-word">
<div class="WordSection1">
<p class="MsoNormal">Hi Alberto,<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">Thanks for your answer.<o:p></o:p></p>
<p class="MsoNormal">I installed the last Kamailio release and it solves my issue.<o:p></o:p></p>
<p class="MsoNormal">Regards<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<p class="MsoNormal">Anthony<o:p></o:p></p>
<p class="MsoNormal"><o:p> </o:p></p>
<div>
<div style="border:none;border-top:solid #E1E1E1 1.0pt;padding:3.0pt 0cm 0cm 0cm">
<p class="MsoNormal"><b>From:</b> sr-users <sr-users-bounces@lists.kamailio.org> <b>
On Behalf Of </b>Alberto Diez<br>
<b>Sent:</b> Monday, 24 October 2022 09:54<br>
<b>To:</b> sr-users@lists.kamailio.org<br>
<b>Subject:</b> Re: [SR-Users] No REGISTER sent to third party AS from scscf<o:p></o:p></p>
</div>
</div>
<p class="MsoNormal"><o:p> </o:p></p>
<table class="MsoNormalTable" border="0" cellspacing="0" cellpadding="0" align="left" width="100%" style="width:100.0%">
<tbody>
<tr>
<td style="background:#A6A6A6;padding:5.25pt 1.5pt 5.25pt 1.5pt"></td>
<td width="100%" style="width:100.0%;background:#EAEAEA;padding:5.25pt 3.75pt 5.25pt 11.25pt">
<div>
<p class="MsoNormal" style="mso-element:frame;mso-element-frame-hspace:2.25pt;mso-element-wrap:around;mso-element-anchor-vertical:paragraph;mso-element-anchor-horizontal:column;mso-height-rule:exactly">
<span style="font-size:9.0pt;font-family:wf_segoe-ui_normal;color:#212121">Vous ne recevez pas souvent de courriers de la part de
<a href="mailto:alberto-lists@mobileplots.com">alberto-lists@mobileplots.com</a>.
<a href="https://aka.ms/LearnAboutSenderIdentification">Découvrez pourquoi cela est important</a><o:p></o:p></span></p>
</div>
</td>
<td width="75" style="width:56.25pt;background:#EAEAEA;padding:5.25pt 3.75pt 5.25pt 3.75pt;align:left">
</td>
</tr>
</tbody>
</table>
<div>
<p>Hi Anthony,<o:p></o:p></p>
<p>In a system I have 3rd party registrations work, so I was curious what happens in your case. My conclusion is that you have a version of the code prior to this patches here below:<o:p></o:p></p>
<p><a href="https://github.com/kamailio/kamailio/commit/2067f110336244826b183a649cb987fac94ffe1c">https://github.com/kamailio/kamailio/commit/2067f110336244826b183a649cb987fac94ffe1c</a><o:p></o:p></p>
<p><a href="https://github.com/kamailio/kamailio/commit/89421e184a8b47557dcfc6c348d60dde347a9417">https://github.com/kamailio/kamailio/commit/89421e184a8b47557dcfc6c348d60dde347a9417</a><o:p></o:p></p>
<p>You need those patches, actually just update the modules you use to the latest because I am not sure of all the dependencies and the tm module...<o:p></o:p></p>
<p>If you do have those patches your log makes no sense to me :D.<o:p></o:p></p>
<p>Here explained:<o:p></o:p></p>
<p>The S-CSCF in the file save.c line 1474 calls t_suspend from the TM module which sets the transaction to suspended (we are on the reply to the REGISTER here). When it receives the Successful Diameter answer for the SAR then it tries to recover that transaction
calling t_continue_skip_timer from the TM module (line 289 in file cxdx_sar.c), this one calls t_continue_helper (tm/t_suspend.c ) which ends up calling t_lookup_ident_filter ... this function is from 2019, before it was only t_lookup_ident which doesn't
exist anymore and calls t_lookup_ident_filter. <o:p></o:p></p>
<p>Best regards<o:p></o:p></p>
<p>alberto<o:p></o:p></p>
<p>El 20/10/2022 a las 12:25, Anthony Blandin escribió:<o:p></o:p></p>
<blockquote style="margin-top:5.0pt;margin-bottom:5.0pt">
<p class="MsoNormal">Hello,<o:p></o:p></p>
<p class="MsoNormal"> <o:p></o:p></p>
<p class="MsoNormal">Here are the logs. It seems that after the save("PRE_REG_SAR_REPLY","location") function, the route PRE_REG_SAR_REPLY is not applied.<o:p></o:p></p>
<p class="MsoNormal"> <o:p></o:p></p>
<p class="MsoNormal"> <o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) ERROR: <script>: Not REGISTERED<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: ims_registrar_scscf [save.c:1269]: save(): Looking for route block [PRE_REG_SAR_REPLY]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: ims_registrar_scscf [save.c:1326]: save(): preparing for SAR assignment for new REGISTRATION
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: tm [t_lookup.c:1328]: t_newtran(): msg (0x7f77335df348) id=3/45 global id=3/45 T start=(nil)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: tm [t_lookup.c:497]: t_lookup_request(): start searching: hash=5727, isACK=0<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: tm [t_lookup.c:455]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bKf561.0ec3b0f8ee4f189c6053ab447b478699.0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: tm [t_lookup.c:675]: t_lookup_request(): no transaction found<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: tm [t_hooks.c:336]: run_reqin_callbacks_internal(): trans=0x7f772c08aad0, callback type 1, id 0 entered<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) INFO: ims_registrar_scscf [cxdx_sar.c:84]: create_return_code(): created AVP successfully : [saa_return_code] - [-2]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: ims_registrar_scscf [save.c:1430]: save(): Suspending SIP TM transaction with index [0] and label [0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [session.c:284]: cdp_add_session(): adding a session with id scscf.ims.mnc081.mcc901.3gppnetwork.org;3181552081;2<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: tm [t_lookup.c:1034]: t_check_msg(): msg (0x7f77335df348) id=3/45 global id=3/45 T start=0x7f772c08aad0<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: tm [t_lookup.c:1109]: t_check_msg(): T (0x7f772c08aad0) already found for msg (0x7f77335df348)!<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [session.c:305]: cdp_get_session(): called get session with id scscf.ims.mnc081.mcc901.3gppnetwork.org;3181552081;2 and hash 12<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [session.c:314]: cdp_get_session(): no session found<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:202]: get_routing_peer(): getting diameter routing peer for realm: [ims.mnc081.mcc901.3gppnetwork.org]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:271]: get_routing_peer(): no routing peer found, trying default route<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:128]: get_first_connected_route(): The peer dra2_slice_par01.epc.mnc081.mcc901.3gppnetwork.org state is opened<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:83]: peer_handles_application(): Checking if peer dra2_slice_par01.epc.mnc081.mcc901.3gppnetwork.org handles application 16777216 for vendord 10415<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:131]: get_first_connected_route(): The peer dra2_slice_par01.epc.mnc081.mcc901.3gppnetwork.org matches - will forward there<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:128]: get_first_connected_route(): The peer dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org state is opened<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:83]: peer_handles_application(): Checking if peer dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org handles application 16777216 for vendord 10415<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:131]: get_first_connected_route(): The peer dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org matches - will forward there<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:149]: get_first_connected_route(): peer [dra2_slice_par01.epc.mnc081.mcc901.3gppnetwork.org] was last used @ 1666163272179072<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:152]: get_first_connected_route(): Peer [dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org] was last used at [0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [routing.c:162]: get_first_connected_route(): chosen peer [dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [diameter_comm.c:145]: AAASendMessage(): Found diameter peer [dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org] from routing table<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [peerstatemachine.c:89]: sm_process(): sm_process(): Peer dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org State I_Open Event Send_Message<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [peerstatemachine.c:1158]: Snd_Message(): Snd_Message called to peer [dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org] for request with code 301
<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [session.c:305]: cdp_get_session(): called get session with id scscf.ims.mnc081.mcc901.3gppnetwork.org;3181552081;2 and hash 12<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [session.c:314]: cdp_get_session(): no session found<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [diameter_msg.c:81]: AAABuildMsgBuffer(): AAABuildMsgBuffer(): len=528<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 3(45) DEBUG: cdp [receiver.c:1013]: peer_send_msg(): peer_send_msg(): Pipe push [0x7f772c085e10]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 26(68) DEBUG: cdp [receiver.c:756]: receive_loop(): select_recv(): There is something on the send pipe<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 26(68) DEBUG: cdp [receiver.c:769]: receive_loop(): select_recv(): Send pipe says [0x7f772c085e10] 8<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 26(68) DEBUG: cdp [diameter_msg.c:410]: AAAFreeMessage(): AAAFreeMessage: Freeing message (0x7f772c085e10) 301<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 26(68) DEBUG: cdp [receiver.c:575]: do_receive(): receive_loop(): [dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org] Recv Version 1 Length 1980<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 26(68) DEBUG: cdp [receiver.c:1104]: receive_message(): receive_message(): [dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org] Recv msg 301<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 26(68) DEBUG: cdp [peerstatemachine.c:89]: sm_process(): sm_process(): Peer dra1_slice_par01.epc.mnc081.mcc901.3gppnetwork.org State I_Open Event I_Rcv_Message<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 26(68) DEBUG: cdp [session.c:305]: cdp_get_session(): called get session with id scscf.ims.mnc081.mcc901.3gppnetwork.org;3181552081;2 and hash 12<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 26(68) DEBUG: cdp [session.c:314]: cdp_get_session(): no session found<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: cdp [worker.c:343]: worker_process(): worker_process(): [1] got task Q(2/2)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [cxdx_sar.c:142]: async_cdp_callback(): There is transaction data this must have been called from save or assign server unreg10(52) DEBUG: tm [t_lookup.c:1611]: t_lookup_ident_filter():
transaction found<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [cxdx_sar.c:189]: async_cdp_callback(): callid for found transaction is [<a href="mailto:1578740844_1601297404@192.168.101.3">1578740844_1601297404@192.168.101.3</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) INFO: ims_registrar_scscf [cxdx_avp.c:138]: cxdx_get_avp(): cxdx_get_experimental_result_code: Failed finding avp<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) INFO: ims_registrar_scscf [cxdx_avp.c:138]: cxdx_get_avp(): cxdx_get_charging_info: Failed finding avp<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [cxdx_sar.c:214]: async_cdp_callback(): received AAA success for SAR - SAA<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [cxdx_sar.c:233]: async_cdp_callback(): Parsing user data string from SAA<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) ERROR: ims_registrar_scscf [userdata_parser.c:935]: parse_user_data(): sum is 151162 calculated len 1667 xml.l 166710(52) DEBUG: ims_registrar_scscf [userdata_parser.c:760]: parse_service_profile():
child name is [PublicIdentity]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:760]: parse_service_profile(): child name is [PublicIdentity]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:760]: parse_service_profile(): child name is [PublicIdentity]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:760]: parse_service_profile(): child name is [InitialFilterCriteria]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:760]: parse_service_profile(): child name is [InitialFilterCriteria]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1022]: print_user_data(): IMSSubscription:<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1025]: print_user_data(): Private Identity:
<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1027]: print_user_data(): Service Profile:<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1029]: print_user_data(): Public Identity: Barring [1]
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>
<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1029]: print_user_data(): Public Identity: Barring [0]
<a href="sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org">sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org</a>
<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1029]: print_user_data(): Public Identity: Barring [0]
<a href="tel:+883390000010022">tel:+883390000010022</a> <o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1035]: print_user_data(): Filter Criteria: Priority [1]ProfilePartInd [-1]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1040]: print_user_data(): Trigger Point: CNF [X] (_|_)&(_|_)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1045]: print_user_data(): SPT: Grp[1] NOT[ ] RegType[0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1057]: print_user_data(): Method == <REGISTER><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1083]: print_user_data(): AS:
<a href="sip:ip-sm-gw.ims.mnc081.mcc901.3gppnetwork.org">sip:ip-sm-gw.ims.mnc081.mcc901.3gppnetwork.org</a> Handling [1] SrvInfo: <><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1035]: print_user_data(): Filter Criteria: Priority [2]ProfilePartInd [-1]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1040]: print_user_data(): Trigger Point: CNF [ ] (_&_)|(_&_)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1045]: print_user_data(): SPT: Grp[0] NOT[ ] RegType[0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1057]: print_user_data(): Method == <MESSAGE><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1045]: print_user_data(): SPT: Grp[0] NOT[ ] RegType[0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1062]: print_user_data(): Hdr(Content-Type(11)) == <application/vnd.3gpp.sms><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1045]: print_user_data(): SPT: Grp[0] NOT[ ] RegType[0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1070]: print_user_data(): SessionCase [0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [userdata_parser.c:1083]: print_user_data(): AS:
<a href="sip:ip-sm-gw.ims.mnc081.mcc901.3gppnetwork.org:5060">sip:ip-sm-gw.ims.mnc081.mcc901.3gppnetwork.org:5060</a> Handling [1] SrvInfo: <><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [cxdx_sar.c:240]: async_cdp_callback(): Successfully parse user data XML setting ref to 1 (we are referencing it)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:790]: update_contacts(): updating contacts in REGISTRATION state<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:943]: update_impurecord(): No subscription yet for [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]...
adding<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1274]: ref_subscription_unsafe(): Reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [1]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:971]: update_impurecord(): No existing impu record for
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>.... creating new one<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1274]: ref_subscription_unsafe(): Reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [2]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:238]: mem_insert_impurecord(): inserted new impurecord into memory [<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.h:119]: run_ul_callbacks(): impurecord=0x7f772c08f280, contact=(nil), callback type 256/256, id 0 entered<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:53]: ul_impu_inserted(): Received notification of UL IMPU insert for IMPU
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:55]: ul_impu_inserted(): Registering for callbacks on this IMPU for contact insert, update, delete or expire to send notifications if there are any subscriptions<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.c:168]: register_ulcb(): installing callback for SCSCF IMPU record with type [1024]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.c:168]: register_ulcb(): installing callback for SCSCF IMPU record with type [30720]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:59]: ul_impu_inserted(): Selectively asking for expire or no contact delete callbacks only on the anchor of the implicit set so that we only send one SAR per implicit
set<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1285]: unref_subscription_unsafe(): un-reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [3]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:519]: update_contacts_helper(): updating the contacts for IMPU
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:137]: calc_contact_expires(): Calculated expires for contact is 600000<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:545]: update_contacts_helper(): Need to update contact:
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>: q_value [-1],sos: [0],expires [600000]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:550]: update_contacts_helper(): packing contact information<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:556]: update_contacts_helper(): adding/updating contact based on prior existence<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:748]: get_scontact(): looking for contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>]
in slot 230<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:562]: update_contacts_helper(): inserting new contact<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:562]: insert_scontact(): INSERTing ucontact in usrloc module<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:118]: new_ucontact(): Checking param [+g.3gpp.accesstype]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:118]: new_ucontact(): Checking param [+sip.instance]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:118]: new_ucontact(): Checking param [audio]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:118]: new_ucontact(): Checking param [+g.3gpp.smsip]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:118]: new_ucontact(): Checking param [+g.3gpp.icsi-ref]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:173]: new_ucontact(): generating hash based on [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:280]: mem_insert_scontact(): Created new contact in memory with AOR: [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>]
and hash [230]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:843]: ref_contact_unsafe(): incrementing ref count on contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>],
was 1<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1226]: link_contact_to_impu(): number of contacts for IMPU [<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
is 1<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:852]: unref_contact_unsafe(): decrementing ref count on contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>],
was 2<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.h:119]: run_ul_callbacks(): impurecord=0x7f772c08f280, contact=0x7f772c08f690, callback type 1024/1024, id 0 entered<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:88]: ul_contact_changed(): Received notification of type 1024 on contact Address
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:91]: ul_contact_changed(): There are no subscriptions for this IMPU therefore breaking out now as nothing to do<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:792]: get_subscription(): found an existing subscription for IMPI [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1274]: ref_subscription_unsafe(): Reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [2]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:878]: compare_subscription(): Comparing subscription for IMPI [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:883]: compare_subscription(): new
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a> (53) vs. orig
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a> (53)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:971]: update_impurecord(): No existing impu record for
<a href="sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org">sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org</a>.... creating new one<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1274]: ref_subscription_unsafe(): Reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [3]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:238]: mem_insert_impurecord(): inserted new impurecord into memory [<a href="sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org">sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.h:119]: run_ul_callbacks(): impurecord=0x7f772c090410, contact=(nil), callback type 256/256, id 0 entered<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:53]: ul_impu_inserted(): Received notification of UL IMPU insert for IMPU
<a href="sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org">sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:55]: ul_impu_inserted(): Registering for callbacks on this IMPU for contact insert, update, delete or expire to send notifications if there are any subscriptions<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.c:168]: register_ulcb(): installing callback for SCSCF IMPU record with type [1024]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.c:168]: register_ulcb(): installing callback for SCSCF IMPU record with type [30720]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:59]: ul_impu_inserted(): Selectively asking for expire or no contact delete callbacks only on the anchor of the implicit set so that we only send one SAR per implicit
set<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.c:168]: register_ulcb(): installing callback for SCSCF IMPU record with type [80]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1285]: unref_subscription_unsafe(): un-reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [4]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:519]: update_contacts_helper(): updating the contacts for IMPU
<a href="sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org">sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:137]: calc_contact_expires(): Calculated expires for contact is 600000<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:545]: update_contacts_helper(): Need to update contact:
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>: q_value [-1],sos: [0],expires [600000]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:550]: update_contacts_helper(): packing contact information<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:556]: update_contacts_helper(): adding/updating contact based on prior existence<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:748]: get_scontact(): looking for contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>]
in slot 230<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:774]: get_scontact(): have partially found a contact<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:783]: get_scontact(): contact found p=[0x7f772c08f690], aor:[<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
and contact:[<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>], state [0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:843]: ref_contact_unsafe(): incrementing ref count on contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>],
was 1<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:570]: update_contacts_helper(): Contact already exists - updating - it's currently in state [0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:423]: update_scontact(): Updating contact aor: [<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
and contact uri: [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:351]: mem_update_ucontact(): Setting contact expires to 1666763272 which is in 600000 seconds time<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:843]: ref_contact_unsafe(): incrementing ref count on contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>],
was 2<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1226]: link_contact_to_impu(): number of contacts for IMPU [<a href="sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org">sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org</a>]
is 1<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:439]: update_scontact(): exists callback for type= UL_CONTACT_UPDATE<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.h:119]: run_ul_callbacks(): impurecord=0x7f772c090410, contact=0x7f772c08f690, callback type 2048/30720, id 1 entered<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:88]: ul_contact_changed(): Received notification of type 2048 on contact Address
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:91]: ul_contact_changed(): There are no subscriptions for this IMPU therefore breaking out now as nothing to do<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:852]: unref_contact_unsafe(): decrementing ref count on contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>],
was 3<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:792]: get_subscription(): found an existing subscription for IMPI [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1274]: ref_subscription_unsafe(): Reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [3]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:878]: compare_subscription(): Comparing subscription for IMPI [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:883]: compare_subscription(): new
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a> (53) vs. orig
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a> (53)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:971]: update_impurecord(): No existing impu record for
<a href="tel:+883390000010022">tel:+883390000010022</a>.... creating new one<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1274]: ref_subscription_unsafe(): Reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [4]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:238]: mem_insert_impurecord(): inserted new impurecord into memory [<a href="tel:+883390000010022">tel:+883390000010022</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.h:119]: run_ul_callbacks(): impurecord=0x7f772c090928, contact=(nil), callback type 256/256, id 0 entered<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:53]: ul_impu_inserted(): Received notification of UL IMPU insert for IMPU
<a href="tel:+883390000010022">tel:+883390000010022</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:55]: ul_impu_inserted(): Registering for callbacks on this IMPU for contact insert, update, delete or expire to send notifications if there are any subscriptions<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.c:168]: register_ulcb(): installing callback for SCSCF IMPU record with type [1024]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.c:168]: register_ulcb(): installing callback for SCSCF IMPU record with type [30720]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:59]: ul_impu_inserted(): Selectively asking for expire or no contact delete callbacks only on the anchor of the implicit set so that we only send one SAR per implicit
set<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1285]: unref_subscription_unsafe(): un-reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [5]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:519]: update_contacts_helper(): updating the contacts for IMPU
<a href="tel:+883390000010022">tel:+883390000010022</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:137]: calc_contact_expires(): Calculated expires for contact is 600000<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:545]: update_contacts_helper(): Need to update contact:
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>: q_value [-1],sos: [0],expires [600000]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:550]: update_contacts_helper(): packing contact information<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:556]: update_contacts_helper(): adding/updating contact based on prior existence<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:748]: get_scontact(): looking for contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>]
in slot 230<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:774]: get_scontact(): have partially found a contact<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:783]: get_scontact(): contact found p=[0x7f772c08f690], aor:[<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
and contact:[<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>], state [0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:843]: ref_contact_unsafe(): incrementing ref count on contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>],
was 2<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [save.c:570]: update_contacts_helper(): Contact already exists - updating - it's currently in state [0]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:423]: update_scontact(): Updating contact aor: [<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
and contact uri: [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:351]: mem_update_ucontact(): Setting contact expires to 1666763272 which is in 600000 seconds time<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:843]: ref_contact_unsafe(): incrementing ref count on contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>],
was 3<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1226]: link_contact_to_impu(): number of contacts for IMPU [<a href="tel:+883390000010022">tel:+883390000010022</a>] is 1<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ucontact.c:439]: update_scontact(): exists callback for type= UL_CONTACT_UPDATE<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [ul_callback.h:119]: run_ul_callbacks(): impurecord=0x7f772c090928, contact=0x7f772c08f690, callback type 2048/30720, id 1 entered<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:88]: ul_contact_changed(): Received notification of type 2048 on contact Address
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [usrloc_cb.c:91]: ul_contact_changed(): There are no subscriptions for this IMPU therefore breaking out now as nothing to do<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:852]: unref_contact_unsafe(): decrementing ref count on contact [<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>],
was 4<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [reply.c:541]: build_contact(): created Contact HF: Contact:
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>;expires=540000;+g.3gpp.accesstype="cellular2";+sip.instance="<urn:gsma:imei:86210804-091779-0>";audio;+g.3gpp.smsip;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [reply.c:584]: build_p_associated_uri(): Building P-Associated-URI<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [reply.c:633]: build_p_associated_uri(): Created P-Associated-URI HF P-Associated-URI:
<a href="sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org">sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org</a>,
<a href="tel:+883390000010022">tel:+883390000010022</a><o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [registrar_notify.c:451]: event_reg(): Sending Reg event notifies<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [registrar_notify.c:452]: event_reg(): Switching on event type: 7<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:690]: get_impus_from_subscription_as_string(): getting IMPU subscription set<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:706]: get_impus_from_subscription_as_string(): Got Record
<a href="sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">sip:901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a> (53)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:706]: get_impus_from_subscription_as_string(): Got Record
<a href="sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org">sip:+883390000010022@ims.mnc081.mcc901.3gppnetwork.org</a> (54)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:706]: get_impus_from_subscription_as_string(): Got Record
<a href="tel:+883390000010022">tel:+883390000010022</a> (20)<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [udomain.c:722]: get_impus_from_subscription_as_string(): num of records returned is 2 and we need 74 bytes<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [registrar_notify.c:523]: event_reg(): About to create notification<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [registrar_notify.c:1415]: create_notifications(): Creating notification<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [registrar_notify.c:1418]: create_notifications(): r_passed and c_passed are valid and presentity uri and watcher_contact is 0 - this must be a ul callback no need to lock
domain<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [cxdx_sar.c:259]: async_cdp_callback(): Updated contacts: Contact:
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>;expires=540000;+g.3gpp.accesstype="cellular2";+sip.instance="<urn:gsma:imei:86210804-091779-0>";audio;+g.3gpp.smsip;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [reply.c:648]: reg_send_reply_transactional(): Contacts: Contact:
<a href="sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2">
sip:75bb2112-e5ef-47cc-ad1c-872ae629c493@192.168.101.3:43715;alias=192.168.101.3~44457~2</a>;expires=540000;+g.3gpp.accesstype="cellular2";+sip.instance="<urn:gsma:imei:86210804-091779-0>";audio;+g.3gpp.smsip;+g.3gpp.icsi-ref="urn%3Aurn-7%3A3gpp-service.ims.icsi.mmtel"<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: tm [t_reply.c:1667]: cleanup_uac_timers(): RETR/FR timers reset<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: tm [t_reply.c:610]: _reply_light(): reply sent out. buf=0x7f77335e2030: SIP/2.0 200 OK<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par From..., shmem=0x7f772c090fa0: SIP/2.0 200 OK<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par From<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: tm [t_reply.c:621]: _reply_light(): finished<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_registrar_scscf [cxdx_sar.c:272]: async_cdp_callback(): DBG:SAR Async CDP callback: ... Done resuming transaction<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) INFO: ims_registrar_scscf [cxdx_sar.c:84]: create_return_code(): created AVP successfully : [saa_return_code] - [1]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: ims_usrloc_scscf [impurecord.c:1285]: unref_subscription_unsafe(): un-reffing subscription [<a href="mailto:901810000000022@ims.mnc081.mcc901.3gppnetwork.org">901810000000022@ims.mnc081.mcc901.3gppnetwork.org</a>]
- was [4]<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: cdp [diameter_msg.c:410]: AAAFreeMessage(): AAAFreeMessage: Freeing message (0x7f772c089f50) 301<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) DEBUG: tm [t_lookup.c:1603]: t_lookup_ident_filter(): transaction in terminated phase - skipping<o:p></o:p></p>
<p class="MsoNormal">scscf1-airnity-slice-data-par 10(52) WARNING: tm [t_suspend.c:192]: t_continue_helper(): active transaction not found<o:p></o:p></p>
<p class="MsoNormal"> <o:p></o:p></p>
<p class="MsoNormal">Could you help me on this issue?<o:p></o:p></p>
<p class="MsoNormal">Regards,<o:p></o:p></p>
<p class="MsoNormal"> <o:p></o:p></p>
<p class="MsoNormal">Anthony<o:p></o:p></p>
<p class="MsoNormal"> <o:p></o:p></p>
<div>
<div style="border:none;border-top:solid #E1E1E1 1.0pt;padding:3.0pt 0cm 0cm 0cm">
<p class="MsoNormal"><b>From:</b> Anthony Blandin <br>
<b>Sent:</b> Wednesday, 19 October 2022 15:15<br>
<b>To:</b> <a href="mailto:sr-users@lists.kamailio.org">sr-users@lists.kamailio.org</a><br>
<b>Subject:</b> No REGISTER sent to third party AS from scscf<o:p></o:p></p>
</div>
</div>
<p class="MsoNormal"> <o:p></o:p></p>
<p class="MsoNormal">Hi Community,<o:p></o:p></p>
<p class="MsoNormal"> <o:p></o:p></p>
<p class="MsoNormal">I am using scscf from Kamailio and I need that scscf sent a REGISTER to a third party AS during the registration phase.<o:p></o:p></p>
<p class="MsoNormal">When scscf received a new registration, no REGISTER is sent to the AS.<o:p></o:p></p>
<p class="MsoNormal">SAR-SAA is well processed but only 200 OK is sent for the REGISTER to the pcscf.<o:p></o:p></p>
<p class="MsoNormal">REGISTER is well sent to the AS only for Re-Registration or De-registration.<o:p></o:p></p>
<p class="MsoNormal">Do you have any idea to handle this case?<o:p></o:p></p>
<p class="MsoNormal">Regards<o:p></o:p></p>
<p class="MsoNormal"> <o:p></o:p></p>
<p class="MsoNormal">Anthony<o:p></o:p></p>
<p class="MsoNormal"><br>
<br>
<o:p></o:p></p>
<pre>__________________________________________________________<o:p></o:p></pre>
<pre>Kamailio - Users Mailing List - Non Commercial Discussions<o:p></o:p></pre>
<pre><a href="mailto:sr-users@lists.kamailio.org">sr-users@lists.kamailio.org</a><o:p></o:p></pre>
<pre>Important: keep the mailing list in the recipients, do not reply only to the sender!<o:p></o:p></pre>
<pre>Edit mailing list options or unsubscribe:<o:p></o:p></pre>
<pre><a href="https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users">https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users</a><o:p></o:p></pre>
</blockquote>
</div>
</div>
</body>
</html>