<p>laforge <strong>merged</strong> this change.</p><p><a href="https://gerrit.osmocom.org/c/osmo-msc/+/15023">View Change</a></p><div style="white-space:pre-wrap">Approvals:
Jenkins Builder: Verified
laforge: Looks good to me, approved
</div><pre style="font-family: monospace,monospace; white-space: pre-wrap;">add msc_vlr tests for UMTS without ciphering<br><br>Following I04ecd7a3b1cc603b2e3feb630e8c7c93fc36ccd7, have tests for UMTS<br>authentication both for cases with and without encryption.<br><br>- Rename test_umts_authen_utran to test_umts_auth_ciph_utran() (uses<br> encryption).<br>- Again add test_umts_authen_utran() not using encryption.<br>- Likewise with test_umts_authen_resync_utran().<br><br>Some permutations are still missing, like UMTS AKA on GERAN with encryption<br>enabled; not bothering at the moment.<br><br>Related: OS#2783<br>Change-Id: I54227f1f08c38c0bf69b9c48924669c4829b04b9<br>---<br>M tests/msc_vlr/msc_vlr_test_umts_authen.c<br>M tests/msc_vlr/msc_vlr_test_umts_authen.err<br>2 files changed, 966 insertions(+), 79 deletions(-)<br><br></pre><pre style="font-family: monospace,monospace; white-space: pre-wrap;"><span>diff --git a/tests/msc_vlr/msc_vlr_test_umts_authen.c b/tests/msc_vlr/msc_vlr_test_umts_authen.c</span><br><span>index 6f8fa01..1bf6cd2 100644</span><br><span>--- a/tests/msc_vlr/msc_vlr_test_umts_authen.c</span><br><span>+++ b/tests/msc_vlr/msc_vlr_test_umts_authen.c</span><br><span>@@ -122,23 +122,30 @@</span><br><span> VERBOSE_ASSERT(auth_request_sent, == true, "%d");</span><br><span> VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span> </span><br><span style="color: hsl(0, 100%, 40%);">- if (via_ran == OSMO_RAT_GERAN_A) {</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR");</span><br><span style="color: hsl(0, 100%, 40%);">- gsup_expect_tx("04010809710000000156f0280102" VLR_TO_HLR);</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_msg("0554" "e229c19e" "2104" "791f2e41");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(gsup_tx_confirmed, == true, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- } else {</span><br><span style="color: hsl(0, 100%, 40%);">- /* On UTRAN */</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends Authen Response, VLR accepts and sends SecurityModeControl");</span><br><span style="color: hsl(0, 100%, 40%);">- expect_security_mode_ctrl(NULL, "27497388b6cb044648f396aa155b95ef");</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_msg("0554" "e229c19e" "2104" "791f2e41");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(security_mode_ctrl_sent, == true, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ if (net->a5_encryption_mask > 0x1) {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* Encryption enabled */</span><br><span style="color: hsl(120, 100%, 40%);">+ if (via_ran == OSMO_RAT_GERAN_A) {</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Test code not implemented");</span><br><span style="color: hsl(120, 100%, 40%);">+ OSMO_ASSERT(false);</span><br><span style="color: hsl(120, 100%, 40%);">+ } else {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* On UTRAN */</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Encryption enabled. MS sends Authen Response, VLR accepts and sends SecurityModeControl");</span><br><span style="color: hsl(120, 100%, 40%);">+ expect_security_mode_ctrl(NULL, "27497388b6cb044648f396aa155b95ef");</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_msg("0554" "e229c19e" "2104" "791f2e41");</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(security_mode_ctrl_sent, == true, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span> </span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends SecurityModeControl acceptance, VLR accepts and sends GSUP LU Req to HLR");</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("MS sends SecurityModeControl acceptance, VLR accepts and sends GSUP LU Req to HLR");</span><br><span style="color: hsl(120, 100%, 40%);">+ gsup_expect_tx("04010809710000000156f0280102" VLR_TO_HLR);</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_security_mode_complete();</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(gsup_tx_confirmed, == true, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ }</span><br><span style="color: hsl(120, 100%, 40%);">+ } else {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* Encryption disabled */</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Encryption disabled. MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR");</span><br><span> gsup_expect_tx("04010809710000000156f0280102" VLR_TO_HLR);</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_security_mode_complete();</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_msg("0554" "e229c19e" "2104" "791f2e41");</span><br><span> VERBOSE_ASSERT(gsup_tx_confirmed, == true, "%d");</span><br><span> VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span> }</span><br><span>@@ -190,22 +197,29 @@</span><br><span> EXPECT_ACCEPTED(false);</span><br><span> thwart_rx_non_initial_requests();</span><br><span> </span><br><span style="color: hsl(0, 100%, 40%);">- if (via_ran == OSMO_RAT_GERAN_A) {</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends Authen Response, VLR accepts with a CM Service Accept");</span><br><span style="color: hsl(120, 100%, 40%);">+ if (net->a5_encryption_mask > 0x1) {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* Encryption enabled */</span><br><span style="color: hsl(120, 100%, 40%);">+ if (via_ran == OSMO_RAT_GERAN_A) {</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Test code not implemented");</span><br><span style="color: hsl(120, 100%, 40%);">+ OSMO_ASSERT(false);</span><br><span style="color: hsl(120, 100%, 40%);">+ } else {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* On UTRAN */</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Encryption enabled. MS sends Authen Response, VLR accepts and sends SecurityModeControl");</span><br><span style="color: hsl(120, 100%, 40%);">+ expect_security_mode_ctrl(NULL, "1159ec926a50e98c034a6b7d7c9f418d");</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_msg("0554" "7db47cf7" "2104" "f81e4dc7"); /* 2nd vector's res, s.a. */</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(security_mode_ctrl_sent, == true, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(cm_service_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("MS sends SecurityModeControl acceptance, VLR accepts; above Ciphering is an implicit CM Service Accept");</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_security_mode_complete();</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(cm_service_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ }</span><br><span style="color: hsl(120, 100%, 40%);">+ } else {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* Encryption disabled */</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Encryption disabled. MS sends Authen Response, VLR accepts with a CM Service Accept");</span><br><span> gsup_expect_tx(NULL);</span><br><span> ms_sends_msg("0554" "7db47cf7" "2104" "f81e4dc7"); /* 2nd vector's res, s.a. */</span><br><span> VERBOSE_ASSERT(cm_service_result_sent, == RES_ACCEPT, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- } else {</span><br><span style="color: hsl(0, 100%, 40%);">- /* On UTRAN */</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends Authen Response, VLR accepts and sends SecurityModeControl");</span><br><span style="color: hsl(0, 100%, 40%);">- expect_security_mode_ctrl(NULL, "1159ec926a50e98c034a6b7d7c9f418d");</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_msg("0554" "7db47cf7" "2104" "f81e4dc7"); /* 2nd vector's res, s.a. */</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(security_mode_ctrl_sent, == true, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(cm_service_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">-</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends SecurityModeControl acceptance, VLR accepts; above Ciphering is an implicit CM Service Accept");</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_security_mode_complete();</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(cm_service_result_sent, == RES_NONE, "%d");</span><br><span> }</span><br><span> </span><br><span> /* Release connection */</span><br><span>@@ -251,21 +265,28 @@</span><br><span> EXPECT_ACCEPTED(false);</span><br><span> thwart_rx_non_initial_requests();</span><br><span> </span><br><span style="color: hsl(0, 100%, 40%);">- if (via_ran == OSMO_RAT_GERAN_A) {</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends Authen Response, VLR accepts and sends pending SMS");</span><br><span style="color: hsl(120, 100%, 40%);">+ if (net->a5_encryption_mask > 0x1) {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* Encryption enabled */</span><br><span style="color: hsl(120, 100%, 40%);">+ if (via_ran == OSMO_RAT_GERAN_A) {</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Test code not implemented");</span><br><span style="color: hsl(120, 100%, 40%);">+ OSMO_ASSERT(false);</span><br><span style="color: hsl(120, 100%, 40%);">+ } else {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* On UTRAN */</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Encryption enabled. MS sends Authen Response, VLR accepts and sends SecurityModeControl");</span><br><span style="color: hsl(120, 100%, 40%);">+ expect_security_mode_ctrl(NULL, "eb50e770ddcc3060101d2f43b6c2b884");</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_msg("0554" "706f9967" "2104" "19ba609c"); /* 3nd vector's res, s.a. */</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(security_mode_ctrl_sent, == true, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("MS sends SecurityModeControl acceptance, VLR accepts and sends SMS");</span><br><span style="color: hsl(120, 100%, 40%);">+ dtap_expect_tx(sms);</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_security_mode_complete();</span><br><span style="color: hsl(120, 100%, 40%);">+ }</span><br><span style="color: hsl(120, 100%, 40%);">+ } else {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* Encryption disabled */</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Encryption disabled. MS sends Authen Response, VLR accepts and sends pending SMS");</span><br><span> dtap_expect_tx(sms);</span><br><span> ms_sends_msg("0554" "706f9967" "2104" "19ba609c"); /* 3nd vector's res, s.a. */</span><br><span> VERBOSE_ASSERT(dtap_tx_confirmed, == true, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- } else {</span><br><span style="color: hsl(0, 100%, 40%);">- /* On UTRAN */</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends Authen Response, VLR accepts and sends SecurityModeControl");</span><br><span style="color: hsl(0, 100%, 40%);">- expect_security_mode_ctrl(NULL, "eb50e770ddcc3060101d2f43b6c2b884");</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_msg("0554" "706f9967" "2104" "19ba609c"); /* 3nd vector's res, s.a. */</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(security_mode_ctrl_sent, == true, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">-</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends SecurityModeControl acceptance, VLR accepts and sends SMS");</span><br><span style="color: hsl(0, 100%, 40%);">- dtap_expect_tx(sms);</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_security_mode_complete();</span><br><span> }</span><br><span> </span><br><span> btw("SMS was delivered, no requests pending for subscr");</span><br><span>@@ -315,6 +336,15 @@</span><br><span> static void test_umts_authen_utran()</span><br><span> {</span><br><span> comment_start();</span><br><span style="color: hsl(120, 100%, 40%);">+ /* A5/0 = no encryption; so far the A5 setting also triggers UTRAN encryption */</span><br><span style="color: hsl(120, 100%, 40%);">+ net->a5_encryption_mask = A5_0;</span><br><span style="color: hsl(120, 100%, 40%);">+ _test_umts_authen(OSMO_RAT_UTRAN_IU);</span><br><span style="color: hsl(120, 100%, 40%);">+ comment_end();</span><br><span style="color: hsl(120, 100%, 40%);">+}</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+static void test_umts_auth_ciph_utran()</span><br><span style="color: hsl(120, 100%, 40%);">+{</span><br><span style="color: hsl(120, 100%, 40%);">+ comment_start();</span><br><span> /* A5/0 + A5/3 = encryption enabled; so far the A5 setting also triggers UTRAN encryption */</span><br><span> net->a5_encryption_mask = A5_0_3;</span><br><span> _test_umts_authen(OSMO_RAT_UTRAN_IU);</span><br><span>@@ -489,23 +519,30 @@</span><br><span> VERBOSE_ASSERT(auth_request_sent, == true, "%d");</span><br><span> VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span> </span><br><span style="color: hsl(0, 100%, 40%);">- if (via_ran == OSMO_RAT_GERAN_A) {</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR");</span><br><span style="color: hsl(0, 100%, 40%);">- gsup_expect_tx("04010809710000000156f0280102" VLR_TO_HLR);</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_msg("0554" "1df5f0b4" "2104" "f22b696e");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(gsup_tx_confirmed, == true, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- } else {</span><br><span style="color: hsl(0, 100%, 40%);">- /* On UTRAN */</span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends Authen Response, VLR accepts and sends SecurityModeControl");</span><br><span style="color: hsl(0, 100%, 40%);">- expect_security_mode_ctrl(NULL, "8a90c769b7272f3bb7a1c1fbb1ea9349");</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_msg("0554" "1df5f0b4" "2104" "f22b696e");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(security_mode_ctrl_sent, == true, "%d");</span><br><span style="color: hsl(0, 100%, 40%);">- VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ if (net->a5_encryption_mask > 0x1) {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* Encryption enabled */</span><br><span style="color: hsl(120, 100%, 40%);">+ if (via_ran == OSMO_RAT_GERAN_A) {</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Test code not implemented");</span><br><span style="color: hsl(120, 100%, 40%);">+ OSMO_ASSERT(false);</span><br><span style="color: hsl(120, 100%, 40%);">+ } else {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* On UTRAN */</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Encryption enabled. MS sends Authen Response, VLR accepts and sends SecurityModeControl");</span><br><span style="color: hsl(120, 100%, 40%);">+ expect_security_mode_ctrl(NULL, "8a90c769b7272f3bb7a1c1fbb1ea9349");</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_msg("0554" "1df5f0b4" "2104" "f22b696e");</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(security_mode_ctrl_sent, == true, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span> </span><br><span style="color: hsl(0, 100%, 40%);">- btw("MS sends SecurityModeControl acceptance, VLR accepts and sends GSUP LU Req to HLR");</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("MS sends SecurityModeControl acceptance, VLR accepts and sends GSUP LU Req to HLR");</span><br><span style="color: hsl(120, 100%, 40%);">+ gsup_expect_tx("04010809710000000156f0280102" VLR_TO_HLR);</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_security_mode_complete();</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(gsup_tx_confirmed, == true, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span style="color: hsl(120, 100%, 40%);">+ }</span><br><span style="color: hsl(120, 100%, 40%);">+ } else {</span><br><span style="color: hsl(120, 100%, 40%);">+ /* Encryption disabled */</span><br><span style="color: hsl(120, 100%, 40%);">+ btw("Encryption disabled. MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR");</span><br><span> gsup_expect_tx("04010809710000000156f0280102" VLR_TO_HLR);</span><br><span style="color: hsl(0, 100%, 40%);">- ms_sends_security_mode_complete();</span><br><span style="color: hsl(120, 100%, 40%);">+ ms_sends_msg("0554" "1df5f0b4" "2104" "f22b696e");</span><br><span> VERBOSE_ASSERT(gsup_tx_confirmed, == true, "%d");</span><br><span> VERBOSE_ASSERT(lu_result_sent, == RES_NONE, "%d");</span><br><span> }</span><br><span>@@ -557,6 +594,15 @@</span><br><span> static void test_umts_authen_resync_utran()</span><br><span> {</span><br><span> comment_start();</span><br><span style="color: hsl(120, 100%, 40%);">+ /* A5/0 = no encryption; so far the A5 setting also triggers UTRAN encryption */</span><br><span style="color: hsl(120, 100%, 40%);">+ net->a5_encryption_mask = A5_0;</span><br><span style="color: hsl(120, 100%, 40%);">+ _test_umts_authen_resync(OSMO_RAT_UTRAN_IU);</span><br><span style="color: hsl(120, 100%, 40%);">+ comment_end();</span><br><span style="color: hsl(120, 100%, 40%);">+}</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+static void test_umts_auth_ciph_resync_utran()</span><br><span style="color: hsl(120, 100%, 40%);">+{</span><br><span style="color: hsl(120, 100%, 40%);">+ comment_start();</span><br><span> /* A5/0 + A5/3 = encryption enabled; so far the A5 setting also triggers UTRAN encryption */</span><br><span> net->a5_encryption_mask = A5_0_3;</span><br><span> _test_umts_authen_resync(OSMO_RAT_UTRAN_IU);</span><br><span>@@ -884,8 +930,10 @@</span><br><span> msc_vlr_test_func_t msc_vlr_tests[] = {</span><br><span> test_umts_authen_geran,</span><br><span> test_umts_authen_utran,</span><br><span style="color: hsl(120, 100%, 40%);">+ test_umts_auth_ciph_utran,</span><br><span> test_umts_authen_resync_geran,</span><br><span> test_umts_authen_resync_utran,</span><br><span style="color: hsl(120, 100%, 40%);">+ test_umts_auth_ciph_resync_utran,</span><br><span> test_umts_authen_too_short_res_geran,</span><br><span> test_umts_authen_too_short_res_utran,</span><br><span> test_umts_authen_too_long_res_geran,</span><br><span>diff --git a/tests/msc_vlr/msc_vlr_test_umts_authen.err b/tests/msc_vlr/msc_vlr_test_umts_authen.err</span><br><span>index 63f1bba..cda55f5 100644</span><br><span>--- a/tests/msc_vlr/msc_vlr_test_umts_authen.err</span><br><span>+++ b/tests/msc_vlr/msc_vlr_test_umts_authen.err</span><br><span>@@ -56,7 +56,7 @@</span><br><span> <-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span> auth_request_sent == 1</span><br><span> lu_result_sent == 0</span><br><span style="color: hsl(0, 100%, 40%);">-- MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption disabled. MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR</span><br><span> MSC <--GERAN-A-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span> DREF msc_a(IMSI-901700000010650:GERAN-A:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span> DBSSAP msc_a(IMSI-901700000010650:GERAN-A:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span>@@ -258,7 +258,7 @@</span><br><span> DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span> DBSSAP msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: SMS:0x01</span><br><span> DBSSAP msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(0, 100%, 40%);">-- MS sends Authen Response, VLR accepts with a CM Service Accept</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption disabled. MS sends Authen Response, VLR accepts with a CM Service Accept</span><br><span> MSC <--GERAN-A-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span> DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (cm_service_sms,rx_from_ms)</span><br><span> DBSSAP msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span>@@ -411,7 +411,7 @@</span><br><span> DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span> DBSSAP msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: SMS:0x01</span><br><span> DBSSAP msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(0, 100%, 40%);">-- MS sends Authen Response, VLR accepts and sends pending SMS</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption disabled. MS sends Authen Response, VLR accepts and sends pending SMS</span><br><span> MSC <--GERAN-A-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span> DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (paging-response,rx_from_ms)</span><br><span> DBSSAP msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:GERAN-A:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span>@@ -620,6 +620,611 @@</span><br><span> DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: + lu: now used by 3 (rx_from_ms,mm_rx_loc_upd_req,lu)</span><br><span> DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: Allocated</span><br><span> DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: is child of msc_a(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: rev=R99 net=UTRAN Auth (no Ciph)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr unknown + _lu_fsm_associate_vsub: now used by 1 (_lu_fsm_associate_vsub)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR set IMSI on subscriber; IMSI=901700000010650 id=901700000010650</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR New subscr, IMSI: 901700000010650</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 + active-conn: now used by 2 (_lu_fsm_associate_vsub,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_COMPLETE_LAYER_3_OK</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_AUTH_CIPH</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 - _lu_fsm_associate_vsub: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1_pre()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000000156f00a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: - mm_rx_loc_upd_req: now used by 2 (rx_from_ms,lu)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: - rx_from_ms: now used by 1 (lu)</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- from HLR, rx _SEND_AUTH_INFO_RESULT; VLR sends Auth Req to MS</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000000156f00362201039fa2f4e3d523d8619a73b4f65c3e14d21049b36efdf2208059a4f668f6fbe39231027497388b6cb044648f396aa155b95ef2410f64735036e5871319c679f4742a75ea125108704f5ba55f30000d2ee44b22c8ea9192708e229c19e791f2e4103622010c187a53a5e6b9d573cac7c74451fd46d210485aa31302208d3d50a000bf04f6e23101159ec926a50e98c034a6b7d7c9f418d2410df3a03d9ca5335641efc8e36d76cd20b25101843a645b98d00005b2d666af46c45d927087db47cf7f81e4dc703622010efa9c29a9742148d5c9070348716e1bb210469d5f9fb22083df176f0c29f1a3d2310eb50e770ddcc3060101d2f43b6c2b884241076542abce5ff9345b0e8947f4c6e019c2510f9375e6d41e1000096e7fe4ff1c27e392708706f996719ba609c03622010f023d5a3b24726e0631b64b3840f82532104d570c03f2208ec011be8919883d62310c4e58af4ba43f3bcd904e16984f086d724100593f65e752e5cb7f473862bda05aa0a2510541ff1f077270000c5ea00d658bc7e9a27083fd26072eaa2a04d036220102f8f90c780d6a9c0c53da7ac57b6707e2104b072446f220823f39f9f425ad6e6231065af0527fda95b0dc5ae4aa515cdf32f2410537c3b35a3b13b08d08eeb28098f45cc25104bf4e564f75300009bc796706bc6574427080edb0eadbea94ac20a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 + vlr_gsup_rx: now used by 2 (active-conn,vlr_gsup_rx)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650) Received 5 auth tuples</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use UMTS AKA (is_r99=yes, at->vec.auth_types=0x3)</span><br><span style="color: hsl(120, 100%, 40%);">+- sending UMTS Auth Request for IMSI-901700000010650:UTRAN-Iu:LU: tuple use_count=1 key_seq=0 auth_types=0x3 and...</span><br><span style="color: hsl(120, 100%, 40%);">+- ...rand=39fa2f4e3d523d8619a73b4f65c3e14d</span><br><span style="color: hsl(120, 100%, 40%);">+- ...autn=8704f5ba55f30000d2ee44b22c8ea919</span><br><span style="color: hsl(120, 100%, 40%);">+- ...expecting res=e229c19e791f2e41</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 - vlr_gsup_rx: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span style="color: hsl(120, 100%, 40%);">+ auth_request_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption disabled. MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: MM UMTS AUTHENTICATION RESPONSE (res = e229c19e791f2e41)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650) AUTH on UTRAN received RES: e229c19e791f2e41 (8 bytes)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650) AUTH established UMTS security context</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN encode: COMMON_ID on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:UTRAN-Iu:LU){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_WAIT_HLR_UPD</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_INIT}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000000156f02801020a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_AUTHENTICATED}: Deallocated</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: - rx_from_ms: now used by 1 (lu)</span><br><span style="color: hsl(120, 100%, 40%);">+ gsup_tx_confirmed == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000000156f00804032443f20a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 + vlr_gsup_rx: now used by 2 (active-conn,vlr_gsup_rx)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR IMSI:901700000010650 has MSISDN:42342</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342) VLR: update for IMSI=901700000010650 (MSISDN=42342)</span><br><span style="color: hsl(120, 100%, 40%);">+GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000000156f00a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342 - vlr_gsup_rx: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- HLR also sends GSUP _UPDATE_LOCATION_RESULT</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000000156f00a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342 + vlr_gsup_rx: now used by 2 (active-conn,vlr_gsup_rx)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_INIT}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: lu_compl_vlr_new_tmsi()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100) VLR: update for IMSI=901700000010650 (MSISDN=42342)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_WAIT_TMSI_CNF</span><br><span style="color: hsl(120, 100%, 40%);">+- sending LU Accept for IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU, with TMSI 0x03020100</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_DONE}: Deallocated</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100 - vlr_gsup_rx: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- a LU Accept with a new TMSI was sent, waiting for TMSI Realloc Compl</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 1</span><br><span style="color: hsl(120, 100%, 40%);">+msc_a_is_accepted() == false</span><br><span style="color: hsl(120, 100%, 40%);">+ requests shall be thwarted</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: CC GSM48_MT_CC_SETUP</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: GSM48_MT_CC_SETUP</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM unknown 0x33</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: unknown 0x33</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: RR GSM48_MT_RR_SYSINFO_1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+- even though the TMSI is not acked, we can already find the subscr with it</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100 + _test_umts_authen: now used by 2 (active-conn,_test_umts_authen)</span><br><span style="color: hsl(120, 100%, 40%);">+ vsub != NULL == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ strcmp(vsub->imsi, imsi) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+ vsub->tmsi_new == 0x03020100</span><br><span style="color: hsl(120, 100%, 40%);">+ vsub->tmsi == 0xffffffff</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100 - _test_umts_authen: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+- MS sends TMSI Realloc Complete</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_TMSI_REALL_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_TMSI_REALL_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: TMSI Reallocation Completed</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_NEW_TMSI_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_TMSI_CNF}: Received Event LU_COMPL_VLR_E_NEW_TMSI_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) VLR: update for IMSI=901700000010650 (MSISDN=42342)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + attached: now used by 2 (active-conn,attached)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_TMSI_CNF}: state_chg to LU_COMPL_VLR_S_DONE</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_DONE}: Deallocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: state_chg to MSC_A_ST_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTHENTICATED}: - lu: now used by 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTHENTICATED}: - rx_from_ms: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTHENTICATED}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTHENTICATED}: state_chg to MSC_A_ST_RELEASING</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: Releasing: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + msc_a_fsm_releasing_onenter: now used by 3 (active-conn,attached,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + vlr_subscr_cancel_attach_fsm: now used by 4 (active-conn,attached,msc_a_fsm_releasing_onenter,vlr_subscr_cancel_attach_fsm)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - vlr_subscr_cancel_attach_fsm: now used by 3 (active-conn,attached,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: + wait-Clear-Complete: now used by 1 (wait-Clear-Complete)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: RAN encode: CLEAR_COMMAND on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - msc_a_fsm_releasing_onenter: now used by 2 (active-conn,attached)</span><br><span style="color: hsl(120, 100%, 40%);">+ iu_release_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: RAN decode: CLEAR_COMPLETE</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: - wait-Clear-Complete: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: state_chg to MSC_A_ST_RELEASED</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: Released: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU))</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_DONE}: Removing from parent msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_DONE}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: max total use count was 3</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Received Event MSUB_EV_ROLE_TERMINATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) MSC-A terminated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) 1 MSC-I still active</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: state_chg to terminating</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_REGULAR, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){0}: Terminating in cascade, depth 3 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){0}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){0}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) Free</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - active-conn: now used by 1 (attached)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: Deallocated, including all deferred deallocations</span><br><span style="color: hsl(120, 100%, 40%);">+- msub gone</span><br><span style="color: hsl(120, 100%, 40%);">+- LU was successful, and the conn has already been closed</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+- after a while, a new conn sends a CM Service Request. VLR responds with Auth Req, 2nd auth vector</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_CM_SERV_REQ</span><br><span style="color: hsl(120, 100%, 40%);">+ new conn</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_VALIDATE_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: + rx_from_ms: now used by 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: RAN decode: COMPL_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: Dispatching 04.08 message: MM GSM48_MT_MM_CM_SERV_REQ</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_VALIDATE_L3}: Rx CM SERVICE REQUEST cm_service_type=Short-Messaging-Service</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_VALIDATE_L3}: + cm_service_sms: now used by 2 (rx_from_ms,cm_service_sms)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_INIT}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_INIT}: is child of msc_a(IMSI-901700000010650:UTRAN-Iu:CM_SERVICE_REQ)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_INIT}: rev=R99 net=UTRAN Auth (no Ciph)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + proc_arq_vlr_fn_init: now used by 2 (attached,proc_arq_vlr_fn_init)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + active-conn: now used by 3 (attached,proc_arq_vlr_fn_init,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_COMPLETE_LAYER_3_OK</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_AUTH_CIPH</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_WAIT_AUTH</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_NEEDS_AUTH}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_NEEDS_AUTH}: is child of Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_WAIT_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=1 -- will use UMTS AKA (is_r99=yes, at->vec.auth_types=0x3)</span><br><span style="color: hsl(120, 100%, 40%);">+- sending UMTS Auth Request for IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ: tuple use_count=1 key_seq=1 auth_types=0x3 and...</span><br><span style="color: hsl(120, 100%, 40%);">+- ...rand=c187a53a5e6b9d573cac7c74451fd46d</span><br><span style="color: hsl(120, 100%, 40%);">+- ...autn=1843a645b98d00005b2d666af46c45d9</span><br><span style="color: hsl(120, 100%, 40%);">+- ...expecting res=7db47cf7f81e4dc7</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - proc_arq_vlr_fn_init: now used by 2 (attached,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: - rx_from_ms: now used by 1 (cm_service_sms)</span><br><span style="color: hsl(120, 100%, 40%);">+ cm_service_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+ auth_request_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- needs auth, not yet accepted</span><br><span style="color: hsl(120, 100%, 40%);">+msc_a_is_accepted() == false</span><br><span style="color: hsl(120, 100%, 40%);">+ requests shall be thwarted</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: CC GSM48_MT_CC_SETUP</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: GSM48_MT_CC_SETUP</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM unknown 0x33</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: unknown 0x33</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: RR GSM48_MT_RR_SYSINFO_1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption disabled. MS sends Authen Response, VLR accepts with a CM Service Accept</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (cm_service_sms,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: MM UMTS AUTHENTICATION RESPONSE (res = 7db47cf7f81e4dc7)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) AUTH on UTRAN received RES: 7db47cf7f81e4dc7 (8 bytes)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) AUTH established UMTS security context</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_AUTHENTICATED}: Removing from parent Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: Received Event PR_ARQ_E_AUTH_RES</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2_post_ciph()</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN encode: COMMON_ID on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2_post_vlr()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_pres()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_trace()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_imei()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: proc_arq_fsm_done(PASSED)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_WAIT_AUTH}: state_chg to PR_ARQ_S_DONE</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Process Access Request result: PASSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN encode: DTAP on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+- DTAP --UTRAN-Iu--> MS: GSM48_MT_MM_CM_SERV_ACC: 0521</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: state_chg to MSC_A_ST_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){VLR_SUB_AS_AUTHENTICATED}: Deallocated</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTHENTICATED}: - rx_from_ms: now used by 1 (cm_service_sms)</span><br><span style="color: hsl(120, 100%, 40%);">+ cm_service_result_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- Concluding CM Service Request</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTHENTICATED}: - cm_service_sms: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTHENTICATED}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTHENTICATED}: state_chg to MSC_A_ST_RELEASING</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASING}: Releasing: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + msc_a_fsm_releasing_onenter: now used by 3 (attached,active-conn,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + vlr_subscr_cancel_attach_fsm: now used by 4 (attached,active-conn,msc_a_fsm_releasing_onenter,vlr_subscr_cancel_attach_fsm)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - vlr_subscr_cancel_attach_fsm: now used by 3 (attached,active-conn,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASING}: + wait-Clear-Complete: now used by 1 (wait-Clear-Complete)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASING}: RAN encode: CLEAR_COMMAND on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - msc_a_fsm_releasing_onenter: now used by 2 (attached,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+ iu_release_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASING}: RAN decode: CLEAR_COMPLETE</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASING}: - wait-Clear-Complete: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASING}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASING}: state_chg to MSC_A_ST_RELEASED</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASED}: Released: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ))</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Removing from parent msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASED}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASED}: max total use count was 2</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Received Event MSUB_EV_ROLE_TERMINATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) MSC-A terminated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) 1 MSC-I still active</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: state_chg to terminating</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_REGULAR, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){0}: Terminating in cascade, depth 3 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){0}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){0}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) Free</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - active-conn: now used by 1 (attached)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_RELEASED}: Deallocated, including all deferred deallocations</span><br><span style="color: hsl(120, 100%, 40%);">+- msub gone</span><br><span style="color: hsl(120, 100%, 40%);">+- all requests serviced, conn has been released</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+- an SMS is sent, MS is paged</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + _test_umts_authen: now used by 2 (attached,_test_umts_authen)</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&vsub->cs.requests) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + SMS-receiver: now used by 3 (attached,_test_umts_authen,SMS-receiver)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + SMS: now used by 4 (attached,_test_umts_authen,SMS-receiver,SMS)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000002 tid-0) New transaction</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) instance created for network</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) instance created for network.</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000002 tid-0) Going to send a MT SMS</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) message SM-RL-DATA_REQ received in state IDLE</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) TX SMS RP-DATA</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) new RP state IDLE -> WAIT_FOR_RP_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) message MNSMS-EST-REQ received in state IDLE</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) new CP state IDLE -> MM_CONN_PENDING</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000002 tid-0) Initiating Paging due to MMSMS_EST_REQ</span><br><span style="color: hsl(120, 100%, 40%);">+DPAG Paging: IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 for MT-SMS: Starting paging</span><br><span style="color: hsl(120, 100%, 40%);">+ paging request (SIGNALLING_LOW_PRIO) to IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+ strcmp(paging_expecting_imsi, vsub->imsi) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + Paging: now used by 5 (attached,_test_umts_authen,SMS-receiver,SMS,Paging)</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&vsub->cs.requests) == 1</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - _test_umts_authen: now used by 4 (attached,SMS-receiver,SMS,Paging)</span><br><span style="color: hsl(120, 100%, 40%);">+ paging_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- the subscriber and its pending request should remain</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + _test_umts_authen: now used by 5 (attached,SMS-receiver,SMS,Paging,_test_umts_authen)</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&vsub->cs.requests) == 1</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - _test_umts_authen: now used by 4 (attached,SMS-receiver,SMS,Paging)</span><br><span style="color: hsl(120, 100%, 40%);">+- MS replies with Paging Response, and VLR sends Auth Request with third key</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_RR_PAG_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+ new conn</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_VALIDATE_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: + rx_from_ms: now used by 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: RAN decode: COMPL_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: Dispatching 04.08 message: RR GSM48_MT_RR_PAG_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DRR msc_a(IMSI-901700000010650:UTRAN-Iu:PAGING_RESP){MSC_A_ST_VALIDATE_L3}: Rx PAGING RESPONSE</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:PAGING_RESP){MSC_A_ST_VALIDATE_L3}: + paging-response: now used by 2 (rx_from_ms,paging-response)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_INIT}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_INIT}: is child of msc_a(IMSI-901700000010650:UTRAN-Iu:PAGING_RESP)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_INIT}: rev=R99 net=UTRAN Auth (no Ciph)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + proc_arq_vlr_fn_init: now used by 5 (attached,SMS-receiver,SMS,Paging,proc_arq_vlr_fn_init)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + active-conn: now used by 6 (attached,SMS-receiver,SMS,Paging,proc_arq_vlr_fn_init,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_COMPLETE_LAYER_3_OK</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_AUTH_CIPH</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_WAIT_AUTH</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_NEEDS_AUTH}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_NEEDS_AUTH}: is child of Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_WAIT_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=2 -- will use UMTS AKA (is_r99=yes, at->vec.auth_types=0x3)</span><br><span style="color: hsl(120, 100%, 40%);">+- sending UMTS Auth Request for IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP: tuple use_count=1 key_seq=2 auth_types=0x3 and...</span><br><span style="color: hsl(120, 100%, 40%);">+- ...rand=efa9c29a9742148d5c9070348716e1bb</span><br><span style="color: hsl(120, 100%, 40%);">+- ...autn=f9375e6d41e1000096e7fe4ff1c27e39</span><br><span style="color: hsl(120, 100%, 40%);">+- ...expecting res=706f996719ba609c</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - proc_arq_vlr_fn_init: now used by 5 (attached,SMS-receiver,SMS,Paging,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: - rx_from_ms: now used by 1 (paging-response)</span><br><span style="color: hsl(120, 100%, 40%);">+ auth_request_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- needs auth, not yet accepted</span><br><span style="color: hsl(120, 100%, 40%);">+msc_a_is_accepted() == false</span><br><span style="color: hsl(120, 100%, 40%);">+ requests shall be thwarted</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: CC GSM48_MT_CC_SETUP</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: GSM48_MT_CC_SETUP</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM unknown 0x33</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: unknown 0x33</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: RR GSM48_MT_RR_SYSINFO_1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption disabled. MS sends Authen Response, VLR accepts and sends pending SMS</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (paging-response,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: MM UMTS AUTHENTICATION RESPONSE (res = 706f996719ba609c)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) AUTH on UTRAN received RES: 706f996719ba609c (8 bytes)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) AUTH established UMTS security context</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_AUTHENTICATED}: Removing from parent Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: Received Event PR_ARQ_E_AUTH_RES</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2_post_ciph()</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN encode: COMMON_ID on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2_post_vlr()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_pres()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_trace()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_imei()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: proc_arq_fsm_done(PASSED)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_WAIT_AUTH}: state_chg to PR_ARQ_S_DONE</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_DONE}: Process Access Request result: PASSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: state_chg to MSC_A_ST_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DPAG Paging: IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 for MT-SMS: Paging Response action (success)</span><br><span style="color: hsl(120, 100%, 40%);">+DPAG Paging: IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 for MT-SMS: Removing Paging Request</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000002 tid-0) mmsms_paging_cb(success)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: + sms: now used by 3 (paging-response,rx_from_ms,sms)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) message MMSMS-EST-CNF received in state MM_CONN_PENDING</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) send CP data</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) new CP state MM_CONN_PENDING -> WAIT_CP_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) sending CP message (trans=0)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) GSM4.11 TX 09 01 58 01 00 07 91 44 77 58 10 06 50 00 4c 00 05 80 24 43 f2 00 00 07 10 10 00 00 00 00 44 50 79 da 1e 1e e7 41 69 37 48 5e 9e a7 c9 65 37 3d 1d 66 83 c2 70 38 3b 3d 0e d3 d3 6f f7 1c 94 9e 83 c2 20 72 79 9e 96 87 c5 ec 32 a8 1d 96 af cb f4 b4 fb 0c 7a c3 e9 e9 b7 db 05 </span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: RAN encode: DTAP on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+- DTAP --UTRAN-Iu--> MS: SMS:0x01: 09015801000791447758100650004c0005802443f2000007101000000000445079da1e1ee7416937485e9ea7c965373d1d6683c270383b3d0ed3d36ff71c949e83c22072799e9687c5ec32a81d96afcbf4b4fb0c7ac3e9e9b7db05</span><br><span style="color: hsl(120, 100%, 40%);">+- DTAP matches expected message</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - Paging: now used by 4 (attached,SMS-receiver,SMS,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: - paging-response: now used by 2 (rx_from_ms,sms)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){VLR_SUB_AS_AUTHENTICATED}: Deallocated</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: - rx_from_ms: now used by 1 (sms)</span><br><span style="color: hsl(120, 100%, 40%);">+ dtap_tx_confirmed == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- SMS was delivered, no requests pending for subscr</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + _test_umts_authen: now used by 5 (attached,SMS-receiver,SMS,active-conn,_test_umts_authen)</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&vsub->cs.requests) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - _test_umts_authen: now used by 4 (attached,SMS-receiver,SMS,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+- conn is still open to wait for SMS ack dance</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- MS replies with CP-ACK for received SMS</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: SMS:0x04</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: + rx_from_ms: now used by 2 (sms,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: Dispatching 04.08 message: SMS SMS:0x04</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) receiving SMS message SMS:0x04</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) message MMSMS-DATA-IND (CP ACK) received in state WAIT_CP_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) received CP-ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) new CP state WAIT_CP_ACK -> MM_ESTABLISHED</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: - rx_from_ms: now used by 1 (sms)</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- MS also sends RP-ACK, MSC in turn sends CP-ACK for that</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: + rx_from_ms: now used by 2 (sms,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) receiving SMS message SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) message MMSMS-DATA-IND (CP DATA) received in state MM_ESTABLISHED</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) received CP-DATA</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) sending CP message (trans=0)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) GSM4.11 TX 09 04 </span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: RAN encode: DTAP on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+- DTAP --UTRAN-Iu--> MS: SMS:0x04: 0904</span><br><span style="color: hsl(120, 100%, 40%);">+- DTAP matches expected message</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) MNSMS-DATA/EST-IND</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) message MNSMS-DATA-IND received in state WAIT_FOR_RP_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) RX SMS RP-ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) new RP state WAIT_FOR_RP_ACK -> IDLE</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) RX SMS RP-ACK (MO)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - SMS-receiver: now used by 3 (attached,SMS,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) TX: MNSMS-REL-REQ</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) message MNSMS-REL-REQ received in state MM_ESTABLISHED</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) new CP state MM_ESTABLISHED -> IDLE</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) Got MMSMS_REL_REQ, destroying transaction.</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) Freeing transaction</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMR(0) clearing SMR instance</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS SMC(0) clearing instance</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - SMS: now used by 2 (attached,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: - sms: now used by 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: - rx_from_ms: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: state_chg to MSC_A_ST_RELEASING</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASING}: Releasing: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + msc_a_fsm_releasing_onenter: now used by 3 (attached,active-conn,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + vlr_subscr_cancel_attach_fsm: now used by 4 (attached,active-conn,msc_a_fsm_releasing_onenter,vlr_subscr_cancel_attach_fsm)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - vlr_subscr_cancel_attach_fsm: now used by 3 (attached,active-conn,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASING}: + wait-Clear-Complete: now used by 1 (wait-Clear-Complete)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASING}: RAN encode: CLEAR_COMMAND on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - msc_a_fsm_releasing_onenter: now used by 2 (attached,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+ dtap_tx_confirmed == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ iu_release_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASING}: RAN decode: CLEAR_COMPLETE</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASING}: - wait-Clear-Complete: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASING}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASING}: state_chg to MSC_A_ST_RELEASED</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASED}: Released: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP))</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_DONE}: Removing from parent msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR Process_Access_Request_VLR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){PR_ARQ_S_DONE}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASED}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASED}: max total use count was 3</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Received Event MSUB_EV_ROLE_TERMINATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) MSC-A terminated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) 1 MSC-I still active</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: state_chg to terminating</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_REGULAR, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){0}: Terminating in cascade, depth 3 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){0}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){0}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) Free</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - active-conn: now used by 1 (attached)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_RELEASED}: Deallocated, including all deferred deallocations</span><br><span style="color: hsl(120, 100%, 40%);">+- msub gone</span><br><span style="color: hsl(120, 100%, 40%);">+- SMS is done, conn is gone</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+- subscriber detaches</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_IMSI_DETACH_IND</span><br><span style="color: hsl(120, 100%, 40%);">+ new conn</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_VALIDATE_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: + rx_from_ms: now used by 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: RAN decode: COMPL_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: Dispatching 04.08 message: MM GSM48_MT_MM_IMSI_DETACH_IND</span><br><span style="color: hsl(120, 100%, 40%);">+DMM IMSI DETACH INDICATION: MI(IMSI)=901700000010650</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + gsm48_rx_mm_imsi_detach_ind: now used by 2 (attached,gsm48_rx_mm_imsi_detach_ind)</span><br><span style="color: hsl(120, 100%, 40%);">+DMM IMSI DETACH for IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + active-conn: now used by 3 (attached,gsm48_rx_mm_imsi_detach_ind,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + vlr_subscr_cancel_attach_fsm: now used by 4 (attached,gsm48_rx_mm_imsi_detach_ind,active-conn,vlr_subscr_cancel_attach_fsm)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - vlr_subscr_cancel_attach_fsm: now used by 3 (attached,gsm48_rx_mm_imsi_detach_ind,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - attached: now used by 2 (gsm48_rx_mm_imsi_detach_ind,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - gsm48_rx_mm_imsi_detach_ind: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_CN_CLOSE</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_RELEASING</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASING}: Releasing: msc_a use is 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + msc_a_fsm_releasing_onenter: now used by 2 (active-conn,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + vlr_subscr_cancel_attach_fsm: now used by 3 (active-conn,msc_a_fsm_releasing_onenter,vlr_subscr_cancel_attach_fsm)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - vlr_subscr_cancel_attach_fsm: now used by 2 (active-conn,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASING}: + wait-Clear-Complete: now used by 2 (rx_from_ms,wait-Clear-Complete)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASING}: RAN encode: CLEAR_COMMAND on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - msc_a_fsm_releasing_onenter: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASING}: - rx_from_ms: now used by 1 (wait-Clear-Complete)</span><br><span style="color: hsl(120, 100%, 40%);">+ iu_release_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASING}: RAN decode: CLEAR_COMPLETE</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASING}: - wait-Clear-Complete: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASING}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASING}: state_chg to MSC_A_ST_RELEASED</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASED}: Released: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASED}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASED}: max total use count was 2</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Received Event MSUB_EV_ROLE_TERMINATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) MSC-A terminated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) 1 MSC-I still active</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: state_chg to terminating</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_REGULAR, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){0}: Terminating in cascade, depth 3 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){0}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){0}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) Free</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - active-conn: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR freeing VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 (max total use count was 6)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASED}: Deallocated, including all deferred deallocations</span><br><span style="color: hsl(120, 100%, 40%);">+- msub gone</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+===== test_umts_authen_utran: SUCCESS</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+full talloc report on 'msgb' (total 0 bytes in 1 blocks)</span><br><span style="color: hsl(120, 100%, 40%);">+talloc_total_blocks(tall_bsc_ctx) == 19</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+===== test_umts_auth_ciph_utran</span><br><span style="color: hsl(120, 100%, 40%);">+- Location Update request causes a GSUP Send Auth Info request to HLR</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_LOC_UPD_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+ new conn</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_VALIDATE_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: + rx_from_ms: now used by 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: RAN decode: COMPL_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: Dispatching 04.08 message: MM GSM48_MT_MM_LOC_UPD_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: LOCATION UPDATING REQUEST: MI=IMSI-901700000010650 LU-type=NORMAL</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: USIM: old LAI: 1665-165-0</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: + mm_rx_loc_upd_req: now used by 2 (rx_from_ms,mm_rx_loc_upd_req)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: + lu: now used by 3 (rx_from_ms,mm_rx_loc_upd_req,lu)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: is child of msc_a(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span> DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: rev=R99 net=UTRAN Auth+Ciph</span><br><span> DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA</span><br><span> DREF VLR subscr unknown + _lu_fsm_associate_vsub: now used by 1 (_lu_fsm_associate_vsub)</span><br><span>@@ -655,7 +1260,7 @@</span><br><span> <-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span> auth_request_sent == 1</span><br><span> lu_result_sent == 0</span><br><span style="color: hsl(0, 100%, 40%);">-- MS sends Authen Response, VLR accepts and sends SecurityModeControl</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption enabled. MS sends Authen Response, VLR accepts and sends SecurityModeControl</span><br><span> MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span> DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span> DIUCS msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span>@@ -869,7 +1474,7 @@</span><br><span> DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span> DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: SMS:0x01</span><br><span> DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(0, 100%, 40%);">-- MS sends Authen Response, VLR accepts and sends SecurityModeControl</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption enabled. MS sends Authen Response, VLR accepts and sends SecurityModeControl</span><br><span> MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span> DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (cm_service_sms,rx_from_ms)</span><br><span> DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:CM_SERVICE_REQ){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span>@@ -955,16 +1560,16 @@</span><br><span> llist_count(&vsub->cs.requests) == 0</span><br><span> DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + SMS-receiver: now used by 3 (attached,_test_umts_authen,SMS-receiver)</span><br><span> DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + SMS: now used by 4 (attached,_test_umts_authen,SMS-receiver,SMS)</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000002 tid-0) New transaction</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000003 tid-0) New transaction</span><br><span> DLSMS SMC(0) instance created for network</span><br><span> DLSMS SMR(0) instance created for network.</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000002 tid-0) Going to send a MT SMS</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000003 tid-0) Going to send a MT SMS</span><br><span> DLSMS SMR(0) message SM-RL-DATA_REQ received in state IDLE</span><br><span> DLSMS SMR(0) TX SMS RP-DATA</span><br><span> DLSMS SMR(0) new RP state IDLE -> WAIT_FOR_RP_ACK</span><br><span> DLSMS SMC(0) message MNSMS-EST-REQ received in state IDLE</span><br><span> DLSMS SMC(0) new CP state IDLE -> MM_CONN_PENDING</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000002 tid-0) Initiating Paging due to MMSMS_EST_REQ</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000003 tid-0) Initiating Paging due to MMSMS_EST_REQ</span><br><span> DPAG Paging: IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 for MT-SMS: Starting paging</span><br><span> paging request (SIGNALLING_LOW_PRIO) to IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 on UTRAN-Iu</span><br><span> strcmp(paging_expecting_imsi, vsub->imsi) == 0</span><br><span>@@ -1031,7 +1636,7 @@</span><br><span> DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span> DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: SMS:0x01</span><br><span> DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(0, 100%, 40%);">-- MS sends Authen Response, VLR accepts and sends SecurityModeControl</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption enabled. MS sends Authen Response, VLR accepts and sends SecurityModeControl</span><br><span> MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span> DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (paging-response,rx_from_ms)</span><br><span> DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span>@@ -1071,13 +1676,13 @@</span><br><span> DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTH_CIPH}: state_chg to MSC_A_ST_AUTHENTICATED</span><br><span> DPAG Paging: IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 for MT-SMS: Paging Response action (success)</span><br><span> DPAG Paging: IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 for MT-SMS: Removing Paging Request</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000002 tid-0) mmsms_paging_cb(success)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 callref-0x40000003 tid-0) mmsms_paging_cb(success)</span><br><span> DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: + sms: now used by 2 (paging-response,sms)</span><br><span> DLSMS SMC(0) message MMSMS-EST-CNF received in state MM_CONN_PENDING</span><br><span> DLSMS SMC(0) send CP data</span><br><span> DLSMS SMC(0) new CP state MM_CONN_PENDING -> WAIT_CP_ACK</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) sending CP message (trans=0)</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) GSM4.11 TX 09 01 58 01 00 07 91 44 77 58 10 06 50 00 4c 00 05 80 24 43 f2 00 00 07 10 10 00 00 00 00 44 50 79 da 1e 1e e7 41 69 37 48 5e 9e a7 c9 65 37 3d 1d 66 83 c2 70 38 3b 3d 0e d3 d3 6f f7 1c 94 9e 83 c2 20 72 79 9e 96 87 c5 ec 32 a8 1d 96 af cb f4 b4 fb 0c 7a c3 e9 e9 b7 db 05 </span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) sending CP message (trans=0)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) GSM4.11 TX 09 01 58 01 00 07 91 44 77 58 10 06 50 00 4c 00 05 80 24 43 f2 00 00 07 10 10 00 00 00 00 44 50 79 da 1e 1e e7 41 69 37 48 5e 9e a7 c9 65 37 3d 1d 66 83 c2 70 38 3b 3d 0e d3 d3 6f f7 1c 94 9e 83 c2 20 72 79 9e 96 87 c5 ec 32 a8 1d 96 af cb f4 b4 fb 0c 7a c3 e9 e9 b7 db 05 </span><br><span> DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: RAN encode: DTAP on UTRAN-Iu</span><br><span> - DTAP --UTRAN-Iu--> MS: SMS:0x01: 09015801000791447758100650004c0005802443f2000007101000000000445079da1e1ee7416937485e9ea7c965373d1d6683c270383b3d0ed3d36ff71c949e83c22072799e9687c5ec32a81d96afcbf4b4fb0c7ac3e9e9b7db05</span><br><span> - DTAP matches expected message</span><br><span>@@ -1095,7 +1700,7 @@</span><br><span> DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: + rx_from_ms: now used by 2 (sms,rx_from_ms)</span><br><span> DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: RAN decode: DTAP</span><br><span> DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: Dispatching 04.08 message: SMS SMS:0x04</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) receiving SMS message SMS:0x04</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) receiving SMS message SMS:0x04</span><br><span> DLSMS SMC(0) message MMSMS-DATA-IND (CP ACK) received in state WAIT_CP_ACK</span><br><span> DLSMS SMC(0) received CP-ACK</span><br><span> DLSMS SMC(0) new CP state WAIT_CP_ACK -> MM_ESTABLISHED</span><br><span>@@ -1106,26 +1711,26 @@</span><br><span> DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: + rx_from_ms: now used by 2 (sms,rx_from_ms)</span><br><span> DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: RAN decode: DTAP</span><br><span> DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) receiving SMS message SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) receiving SMS message SMS:0x01</span><br><span> DLSMS SMC(0) message MMSMS-DATA-IND (CP DATA) received in state MM_ESTABLISHED</span><br><span> DLSMS SMC(0) received CP-DATA</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) sending CP message (trans=0)</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) GSM4.11 TX 09 04 </span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) sending CP message (trans=0)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) GSM4.11 TX 09 04 </span><br><span> DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){MSC_A_ST_AUTHENTICATED}: RAN encode: DTAP on UTRAN-Iu</span><br><span> - DTAP --UTRAN-Iu--> MS: SMS:0x04: 0904</span><br><span> - DTAP matches expected message</span><br><span> DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) MNSMS-DATA/EST-IND</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) MNSMS-DATA/EST-IND</span><br><span> DLSMS SMR(0) message MNSMS-DATA-IND received in state WAIT_FOR_RP_ACK</span><br><span> DLSMS SMR(0) RX SMS RP-ACK</span><br><span> DLSMS SMR(0) new RP state WAIT_FOR_RP_ACK -> IDLE</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) RX SMS RP-ACK (MO)</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) RX SMS RP-ACK (MO)</span><br><span> DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - SMS-receiver: now used by 3 (attached,SMS,active-conn)</span><br><span> DLSMS SMR(0) TX: MNSMS-REL-REQ</span><br><span> DLSMS SMC(0) message MNSMS-REL-REQ received in state MM_ESTABLISHED</span><br><span> DLSMS SMC(0) new CP state MM_ESTABLISHED -> IDLE</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) Got MMSMS_REL_REQ, destroying transaction.</span><br><span style="color: hsl(0, 100%, 40%);">-DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000002 tid-0) Freeing transaction</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) Got MMSMS_REL_REQ, destroying transaction.</span><br><span style="color: hsl(120, 100%, 40%);">+DLSMS trans(SMS IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:PAGING_RESP callref-0x40000003 tid-0) Freeing transaction</span><br><span> DLSMS SMR(0) clearing SMR instance</span><br><span> DLSMS SMC(0) clearing instance</span><br><span> DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - SMS: now used by 2 (attached,active-conn)</span><br><span>@@ -1226,7 +1831,7 @@</span><br><span> DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:NONE){MSC_A_ST_RELEASED}: Deallocated, including all deferred deallocations</span><br><span> - msub gone</span><br><span> llist_count(&msub_list) == 0</span><br><span style="color: hsl(0, 100%, 40%);">-===== test_umts_authen_utran: SUCCESS</span><br><span style="color: hsl(120, 100%, 40%);">+===== test_umts_auth_ciph_utran: SUCCESS</span><br><span> </span><br><span> full talloc report on 'msgb' (total 0 bytes in 1 blocks)</span><br><span> talloc_total_blocks(tall_bsc_ctx) == 19</span><br><span>@@ -1313,7 +1918,7 @@</span><br><span> <-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span> auth_request_sent == 1</span><br><span> lu_result_sent == 0</span><br><span style="color: hsl(0, 100%, 40%);">-- MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption disabled. MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR</span><br><span> MSC <--GERAN-A-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span> DREF msc_a(IMSI-901700000010650:GERAN-A:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span> DBSSAP msc_a(IMSI-901700000010650:GERAN-A:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span>@@ -1482,6 +2087,240 @@</span><br><span> DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: + lu: now used by 3 (rx_from_ms,mm_rx_loc_upd_req,lu)</span><br><span> DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: Allocated</span><br><span> DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: is child of msc_a(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: rev=R99 net=UTRAN Auth (no Ciph)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr unknown + _lu_fsm_associate_vsub: now used by 1 (_lu_fsm_associate_vsub)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR set IMSI on subscriber; IMSI=901700000010650 id=901700000010650</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR New subscr, IMSI: 901700000010650</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 + active-conn: now used by 2 (_lu_fsm_associate_vsub,active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: Received Event MSC_A_EV_COMPLETE_LAYER_3_OK</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_AUTH_CIPH</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 - _lu_fsm_associate_vsub: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1_pre()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000000156f00a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: - mm_rx_loc_upd_req: now used by 2 (rx_from_ms,lu)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: - rx_from_ms: now used by 1 (lu)</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- from HLR, rx _SEND_AUTH_INFO_RESULT; VLR sends Auth Req to MS</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000000156f00362201039fa2f4e3d523d8619a73b4f65c3e14d21049b36efdf2208059a4f668f6fbe39231027497388b6cb044648f396aa155b95ef2410f64735036e5871319c679f4742a75ea125108704f5ba55f30000d2ee44b22c8ea9192708e229c19e791f2e410a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 + vlr_gsup_rx: now used by 2 (active-conn,vlr_gsup_rx)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650) Received 1 auth tuples</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use UMTS AKA (is_r99=yes, at->vec.auth_types=0x3)</span><br><span style="color: hsl(120, 100%, 40%);">+- sending UMTS Auth Request for IMSI-901700000010650:UTRAN-Iu:LU: tuple use_count=1 key_seq=0 auth_types=0x3 and...</span><br><span style="color: hsl(120, 100%, 40%);">+- ...rand=39fa2f4e3d523d8619a73b4f65c3e14d</span><br><span style="color: hsl(120, 100%, 40%);">+- ...autn=8704f5ba55f30000d2ee44b22c8ea919</span><br><span style="color: hsl(120, 100%, 40%);">+- ...expecting res=e229c19e791f2e41</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 - vlr_gsup_rx: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span style="color: hsl(120, 100%, 40%);">+ auth_request_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- MS sends Authen Failure with Resync cause, VLR sends GSUP to HLR to resync</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_FAIL</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_AUTH_FAIL</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: MM R99 AUTHENTICATION SYNCH (AUTS = 979498b1f72d3e28c59fa2e72f9c)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_FAIL</span><br><span style="color: hsl(120, 100%, 40%);">+GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000000156f0260e979498b1f72d3e28c59fa2e72f9c201039fa2f4e3d523d8619a73b4f65c3e14d0a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_SAI_RESYNC</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: - rx_from_ms: now used by 1 (lu)</span><br><span style="color: hsl(120, 100%, 40%);">+ gsup_tx_confirmed == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ auth_request_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- HLR replies with new tuples</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000000156f0036220100f1feb1623e1bf626334e37ec448ac182104efde99da220814778c855c52373023108a90c769b7272f3bb7a1c1fbb1ea9349241043ffc1cf8c89a7fd6ab94bd8d6162cbf251002a83f62e9470000660d51afc75f169d27081df5f0b4f22b696e03622010ac21d34937b4e1142a2c757af294931921047818bfdc2208d175571f41f314a42310ff8edbceb6dd24799c77c3b9a6790c102410157c39022ca9d885a7f0766a7dfee44825108a43b91898e500002cf354c6f5d1f8c32708f748a7078f5018db0a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 + vlr_gsup_rx: now used by 2 (active-conn,vlr_gsup_rx)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_SAI_RESYNC}: Received Event VLR_AUTH_E_HLR_SAI_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650) Received 2 auth tuples</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_SAI_RESYNC}: state_chg to VLR_SUB_AS_WAIT_RESP_RESYNC</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP_RESYNC}: got auth tuple: use_count=1 key_seq=0 -- will use UMTS AKA (is_r99=yes, at->vec.auth_types=0x3)</span><br><span style="color: hsl(120, 100%, 40%);">+- sending UMTS Auth Request for IMSI-901700000010650:UTRAN-Iu:LU: tuple use_count=1 key_seq=0 auth_types=0x3 and...</span><br><span style="color: hsl(120, 100%, 40%);">+- ...rand=0f1feb1623e1bf626334e37ec448ac18</span><br><span style="color: hsl(120, 100%, 40%);">+- ...autn=02a83f62e9470000660d51afc75f169d</span><br><span style="color: hsl(120, 100%, 40%);">+- ...expecting res=1df5f0b4f22b696e</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 - vlr_gsup_rx: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span style="color: hsl(120, 100%, 40%);">+ auth_request_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption disabled. MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: MM UMTS AUTHENTICATION RESPONSE (res = 1df5f0b4f22b696e)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP_RESYNC}: Received Event VLR_AUTH_E_MS_AUTH_RESP</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650) AUTH on UTRAN received RES: 1df5f0b4f22b696e (8 bytes)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650) AUTH established UMTS security context</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP_RESYNC}: Authentication terminating with result PASSED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_WAIT_RESP_RESYNC}: state_chg to VLR_SUB_AS_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN encode: COMMON_ID on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:UTRAN-Iu:LU){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_WAIT_HLR_UPD</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_INIT}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000000156f02801020a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR VLR_Authenticate(IMSI-901700000010650:UTRAN-Iu:LU){VLR_SUB_AS_AUTHENTICATED}: Deallocated</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: - rx_from_ms: now used by 1 (lu)</span><br><span style="color: hsl(120, 100%, 40%);">+ gsup_tx_confirmed == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000000156f00804032443f20a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650 + vlr_gsup_rx: now used by 2 (active-conn,vlr_gsup_rx)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR IMSI:901700000010650 has MSISDN:42342</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342) VLR: update for IMSI=901700000010650 (MSISDN=42342)</span><br><span style="color: hsl(120, 100%, 40%);">+GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000000156f00a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342 - vlr_gsup_rx: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 0</span><br><span style="color: hsl(120, 100%, 40%);">+- HLR also sends GSUP _UPDATE_LOCATION_RESULT</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000000156f00a0101</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342 + vlr_gsup_rx: now used by 2 (active-conn,vlr_gsup_rx)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_INIT}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: lu_compl_vlr_new_tmsi()</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100) VLR: update for IMSI=901700000010650 (MSISDN=42342)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_WAIT_TMSI_CNF</span><br><span style="color: hsl(120, 100%, 40%);">+- sending LU Accept for IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU, with TMSI 0x03020100</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR upd_hlr_vlr_fsm(IMSI-901700000010650:UTRAN-Iu:LU){UPD_HLR_VLR_S_DONE}: Deallocated</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100 - vlr_gsup_rx: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span style="color: hsl(120, 100%, 40%);">+ lu_result_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+- a LU Accept with a new TMSI was sent, waiting for TMSI Realloc Compl</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 1</span><br><span style="color: hsl(120, 100%, 40%);">+msc_a_is_accepted() == false</span><br><span style="color: hsl(120, 100%, 40%);">+ requests shall be thwarted</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: CC GSM48_MT_CC_SETUP</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: GSM48_MT_CC_SETUP</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM unknown 0x33</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: unknown 0x33</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: RR GSM48_MT_RR_SYSINFO_1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: SMS SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Message not permitted for initial conn: SMS:0x01</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode error (rc=-13) for DTAP from MSC-I</span><br><span style="color: hsl(120, 100%, 40%);">+- even though the TMSI is not acked, we can already find the subscr with it</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100 + _test_umts_authen_resync: now used by 2 (active-conn,_test_umts_authen_resync)</span><br><span style="color: hsl(120, 100%, 40%);">+ vsub != NULL == 1</span><br><span style="color: hsl(120, 100%, 40%);">+ strcmp(vsub->imsi, imsi) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+ vsub->tmsi_new == 0x03020100</span><br><span style="color: hsl(120, 100%, 40%);">+ vsub->tmsi == 0xffffffff</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100 - _test_umts_authen_resync: now used by 1 (active-conn)</span><br><span style="color: hsl(120, 100%, 40%);">+- MS sends TMSI Realloc Complete</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_TMSI_REALL_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Dispatching 04.08 message: MM GSM48_MT_MM_TMSI_REALL_COMPL</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: TMSI Reallocation Completed</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSInew-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_NEW_TMSI_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_TMSI_CNF}: Received Event LU_COMPL_VLR_E_NEW_TMSI_ACK</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR SUBSCR(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) VLR: update for IMSI=901700000010650 (MSISDN=42342)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + attached: now used by 2 (active-conn,attached)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_WAIT_TMSI_CNF}: state_chg to LU_COMPL_VLR_S_DONE</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR lu_compl_vlr_fsm(IMSI-901700000010650:MSISDN-42342:UTRAN-Iu:LU){LU_COMPL_VLR_S_DONE}: Deallocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: Received Event MSC_A_EV_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: state_chg to MSC_A_ST_AUTHENTICATED</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTHENTICATED}: - lu: now used by 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTHENTICATED}: - rx_from_ms: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTHENTICATED}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_AUTHENTICATED}: state_chg to MSC_A_ST_RELEASING</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: Releasing: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + msc_a_fsm_releasing_onenter: now used by 3 (active-conn,attached,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 + vlr_subscr_cancel_attach_fsm: now used by 4 (active-conn,attached,msc_a_fsm_releasing_onenter,vlr_subscr_cancel_attach_fsm)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - vlr_subscr_cancel_attach_fsm: now used by 3 (active-conn,attached,msc_a_fsm_releasing_onenter)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: + wait-Clear-Complete: now used by 1 (wait-Clear-Complete)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: RAN encode: CLEAR_COMMAND on UTRAN-Iu</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){0}: Received Event MSC_I_EV_FROM_A_FORWARD_ACCESS_SIGNALLING_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - msc_a_fsm_releasing_onenter: now used by 2 (active-conn,attached)</span><br><span style="color: hsl(120, 100%, 40%);">+ iu_release_sent == 1</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: RAN decode: CLEAR_COMPLETE</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: - wait-Clear-Complete: now used by 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: Received Event MSC_A_EV_UNUSED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASING}: state_chg to MSC_A_ST_RELEASED</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: Released: msc_a use is 0 (-)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU))</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_DONE}: Removing from parent msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){VLR_ULA_S_DONE}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: max total use count was 3</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Received Event MSUB_EV_ROLE_TERMINATED</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) MSC-A terminated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) 1 MSC-I still active</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: state_chg to terminating</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_REGULAR, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){0}: Terminating in cascade, depth 3 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU))</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){0}: Removing from parent msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){0}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100) Free</span><br><span style="color: hsl(120, 100%, 40%);">+DREF VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 - active-conn: now used by 1 (attached)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{terminating}: Deferring: will deallocate with msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU)</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100:UTRAN-Iu:LU){MSC_A_ST_RELEASED}: Deallocated, including all deferred deallocations</span><br><span style="color: hsl(120, 100%, 40%);">+- msub gone</span><br><span style="color: hsl(120, 100%, 40%);">+- LU was successful, and the conn has already been closed</span><br><span style="color: hsl(120, 100%, 40%);">+ llist_count(&msub_list) == 0</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR freeing VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 (max total use count was 4)</span><br><span style="color: hsl(120, 100%, 40%);">+===== test_umts_authen_resync_utran: SUCCESS</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+full talloc report on 'msgb' (total 0 bytes in 1 blocks)</span><br><span style="color: hsl(120, 100%, 40%);">+talloc_total_blocks(tall_bsc_ctx) == 19</span><br><span style="color: hsl(120, 100%, 40%);">+</span><br><span style="color: hsl(120, 100%, 40%);">+===== test_umts_auth_ciph_resync_utran</span><br><span style="color: hsl(120, 100%, 40%);">+- Location Update request causes a GSUP Send Auth Info request to HLR</span><br><span style="color: hsl(120, 100%, 40%);">+ MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_LOC_UPD_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+ new conn</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msub_fsm{active}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a{MSC_A_ST_VALIDATE_L3}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: state_chg to MSC_A_ST_VALIDATE_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DMSC dummy_msc_i{0}: is child of msub_fsm</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: + rx_from_ms: now used by 1 (rx_from_ms)</span><br><span style="color: hsl(120, 100%, 40%);">+DIUCS msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: RAN decode: COMPL_L3</span><br><span style="color: hsl(120, 100%, 40%);">+DRLL msc_a(unknown:UTRAN-Iu:NONE){MSC_A_ST_VALIDATE_L3}: Dispatching 04.08 message: MM GSM48_MT_MM_LOC_UPD_REQUEST</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: LOCATION UPDATING REQUEST: MI=IMSI-901700000010650 LU-type=NORMAL</span><br><span style="color: hsl(120, 100%, 40%);">+DMM msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: USIM: old LAI: 1665-165-0</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: + mm_rx_loc_upd_req: now used by 2 (rx_from_ms,mm_rx_loc_upd_req)</span><br><span style="color: hsl(120, 100%, 40%);">+DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_VALIDATE_L3}: + lu: now used by 3 (rx_from_ms,mm_rx_loc_upd_req,lu)</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: Allocated</span><br><span style="color: hsl(120, 100%, 40%);">+DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: is child of msc_a(IMSI-901700000010650:UTRAN-Iu:LU)</span><br><span> DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: rev=R99 net=UTRAN Auth+Ciph</span><br><span> DVLR vlr_lu_fsm(IMSI-901700000010650:UTRAN-Iu:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA</span><br><span> DREF VLR subscr unknown + _lu_fsm_associate_vsub: now used by 1 (_lu_fsm_associate_vsub)</span><br><span>@@ -1545,7 +2384,7 @@</span><br><span> <-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0</span><br><span> auth_request_sent == 1</span><br><span> lu_result_sent == 0</span><br><span style="color: hsl(0, 100%, 40%);">-- MS sends Authen Response, VLR accepts and sends SecurityModeControl</span><br><span style="color: hsl(120, 100%, 40%);">+- Encryption enabled. MS sends Authen Response, VLR accepts and sends SecurityModeControl</span><br><span> MSC <--UTRAN-Iu-- MS: GSM48_MT_MM_AUTH_RESP</span><br><span> DREF msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: + rx_from_ms: now used by 2 (lu,rx_from_ms)</span><br><span> DIUCS msc_a(IMSI-901700000010650:UTRAN-Iu:LU){MSC_A_ST_AUTH_CIPH}: RAN decode: DTAP</span><br><span>@@ -1702,7 +2541,7 @@</span><br><span> - LU was successful, and the conn has already been closed</span><br><span> llist_count(&msub_list) == 0</span><br><span> DVLR freeing VLR subscr IMSI-901700000010650:MSISDN-42342:TMSI-0x03020100 (max total use count was 4)</span><br><span style="color: hsl(0, 100%, 40%);">-===== test_umts_authen_resync_utran: SUCCESS</span><br><span style="color: hsl(120, 100%, 40%);">+===== test_umts_auth_ciph_resync_utran: SUCCESS</span><br><span> </span><br><span> full talloc report on 'msgb' (total 0 bytes in 1 blocks)</span><br><span> talloc_total_blocks(tall_bsc_ctx) == 19</span><br><span></span><br></pre><p>To view, visit <a href="https://gerrit.osmocom.org/c/osmo-msc/+/15023">change 15023</a>. To unsubscribe, or for help writing mail filters, visit <a href="https://gerrit.osmocom.org/settings">settings</a>.</p><div itemscope itemtype="http://schema.org/EmailMessage"><div itemscope itemprop="action" itemtype="http://schema.org/ViewAction"><link itemprop="url" href="https://gerrit.osmocom.org/c/osmo-msc/+/15023"/><meta itemprop="name" content="View Change"/></div></div>
<div style="display:none"> Gerrit-Project: osmo-msc </div>
<div style="display:none"> Gerrit-Branch: master </div>
<div style="display:none"> Gerrit-Change-Id: I54227f1f08c38c0bf69b9c48924669c4829b04b9 </div>
<div style="display:none"> Gerrit-Change-Number: 15023 </div>
<div style="display:none"> Gerrit-PatchSet: 2 </div>
<div style="display:none"> Gerrit-Owner: neels <nhofmeyr@sysmocom.de> </div>
<div style="display:none"> Gerrit-Reviewer: Jenkins Builder </div>
<div style="display:none"> Gerrit-Reviewer: laforge <laforge@gnumonks.org> </div>
<div style="display:none"> Gerrit-MessageType: merged </div>