Gitiles
Code Review Sign In
gerrit.osmocom.org / osmo-msc / e4f7e71204d81c8c48e1a404fdf1c3520e4b8a72 / . / tests / msc_vlr / msc_vlr_test_rest.err
blob: be962378c6f59897c969654c74a4ef937e18539e [file] [log] [blame]
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]1full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]2talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]3
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]4===== test_early_stage
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]5- NULL conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]6ran_conn_is_accepted() == false
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]7- freshly allocated conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]8DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
9ran_conn_is_accepted() == false
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]10- conn_fsm present, in state NEW
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]11ran_conn_is_accepted() == false
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]12 requests shall be thwarted
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]13DRLL Dispatching 04.08 message GSM48_MT_CC_SETUP (0x3:0x5)
14DRLL subscr unknown: Message not permitted for initial conn: GSM48_MT_CC_SETUP
15DRLL Dispatching 04.08 message unknown 0x33 (0x5:0x33)
16DRLL subscr unknown: Message not permitted for initial conn: unknown 0x33
17DRLL Dispatching 04.08 message GSM48_MT_RR_SYSINFO_1 (0x6:0x19)
18DRLL subscr unknown: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1
Harald Welte80315ef2018-01-24 22:53:00 +0100[diff] [blame]19DRLL Dispatching 04.08 message SMS:0x01 (0x9:0x1)
20DRLL subscr unknown: Message not permitted for initial conn: SMS:0x01
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]21- fake: acceptance
22DREF VLR subscr unknown usage increases to: 1
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]23DMM RAN_conn{RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_ACCEPTED
24DMM RAN_conn{RAN_CONN_S_ACCEPTED}: ran_conn_fsm_has_active_transactions: silent call still active
25 llist_count(&net->ran_conns) == 1
26ran_conn_is_accepted() == true
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]27- CLOSE event marks conn_fsm as released and frees the conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]28DMM RAN_conn{RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_CN_CLOSE
29DMM RAN_conn{RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]30DREF unknown: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyre3d3dc62018-03-31 00:02:14 +0200[diff] [blame]31DREF VLR subscr unknown usage increases to: 2
32DREF VLR subscr unknown usage decreases to: 1
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]33- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]34 bssap_clear_sent == 1
35- BSS sends BSSMAP Clear Complete
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]36DREF unknown: MSC conn use - release == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]37DMM RAN_conn{RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
38DMM RAN_conn{RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
39DMM RAN_conn{RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
40DMM RAN_conn{RAN_CONN_S_RELEASED}: ran_conn_fsm_has_active_transactions: silent call still active
41DMM RAN_conn{RAN_CONN_S_RELEASED}: Deallocating despite active transactions
42DRLL unknown: Freeing RAN connection
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]43DREF VLR subscr unknown usage decreases to: 0
44DREF freeing VLR subscr unknown
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]45DMM RAN_conn{RAN_CONN_S_RELEASED}: Deallocated, including all deferred deallocations
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]46 llist_count(&net->ran_conns) == 0
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]47===== test_early_stage: SUCCESS
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]48
49full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]50talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]51
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]52===== test_cm_service_without_lu
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]53- CM Service Request without a prior Location Updating
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]54 MSC <--GERAN-A-- MS: GSM48_MT_MM_CM_SERV_REQ
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]55 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]56DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]57DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]58DRLL Dispatching 04.08 message GSM48_MT_MM_CM_SERV_REQ (0x5:0x24)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]59DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: Updated ID
60DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: Rx CM SERVICE REQUEST cm_service_type=0x08
61DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: Allocated
62DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ)
63DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: rev=GSM net=GERAN (no Auth)
64DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
65DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: proc_arq_fsm_done(IMSI_UNKNOWN_IN_VLR)
66DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_DONE
67DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Process Access Request result: IMSI_UNKNOWN_IN_VLR
Philipp Maier6f4752e2018-05-15 15:23:59 +0200[diff] [blame]68- sending CM Service Reject for unknown, cause: IMSI_UNKNOWN_IN_VLR
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]69DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_CN_CLOSE
70DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]71DREF unknown: MSC conn use + release == 2 (0x101: compl_l3,release)
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]72- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]73DBSSAP RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASING}: subscriber not allowed to do a CM Service Request
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]74DREF unknown: MSC conn use - compl_l3 == 1 (0x100: release)
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]75 bssap_clear_sent == 1
76- conn was released
77- BSS sends BSSMAP Clear Complete
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]78DREF unknown: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]79DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
80DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
81DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]82DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]83DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Removing from parent RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]84DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Deferring: will deallocate with RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ)
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]85DRLL Freeing RAN connection with NULL subscriber
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]86DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASED}: Deallocated, including all deferred deallocations
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]87 llist_count(&net->ran_conns) == 0
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]88===== test_cm_service_without_lu: SUCCESS
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]89
90full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]91talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]92
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]93===== test_two_lu
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]94- Location Update request causes a GSUP LU request to HLR
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]95 MSC <--GERAN-A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]96 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]97DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]98DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]99DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]100DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
101DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LOCATION UPDATING REQUEST: MI=IMSI-901700000004620 LU-type=IMSI-ATTACH
102DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LU/new-LAC: 1/23
103DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Allocated
104DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
105DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
106DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]107DREF VLR subscr unknown usage increases to: 1
108DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
109DVLR New subscr, IMSI: 901700000004620
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]110DREF VLR subscr IMSI-901700000004620 usage increases to: 2
111DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
112DREF VLR subscr IMSI-901700000004620 usage decreases to: 1
113DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
114DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()
115DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()
116DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()
117DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
118DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Allocated
119DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU)
120DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyrd0756b12018-09-28 02:41:39 +0200[diff] [blame]121DVLR GSUP tx: 04010809710000004026f0280102
122GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]123DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
124DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
125DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
126DREF IMSI-901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
127DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
128DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]129 lu_result_sent == 0
130- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
131<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
132DVLR GSUP rx 17: 10010809710000004026f00804036470f1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]133DREF VLR subscr IMSI-901700000004620 usage increases to: 2
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]134DVLR IMSI:901700000004620 has MSISDN:46071
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]135DVLR SUBSCR(IMSI-901700000004620:MSISDN-46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
136DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Updated ID
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]137DVLR GSUP tx: 12010809710000004026f0
138GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]139DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]140<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
141 lu_result_sent == 0
142- having received subscriber data does not mean acceptance
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]143ran_conn_is_accepted() == false
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]144 requests shall be thwarted
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]145DRLL Dispatching 04.08 message GSM48_MT_CC_SETUP (0x3:0x5)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]146DRLL subscr IMSI-901700000004620:MSISDN-46071: Message not permitted for initial conn: GSM48_MT_CC_SETUP
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]147DRLL Dispatching 04.08 message unknown 0x33 (0x5:0x33)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]148DRLL subscr IMSI-901700000004620:MSISDN-46071: Message not permitted for initial conn: unknown 0x33
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]149DRLL Dispatching 04.08 message GSM48_MT_RR_SYSINFO_1 (0x6:0x19)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]150DRLL subscr IMSI-901700000004620:MSISDN-46071: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1
Harald Welte80315ef2018-01-24 22:53:00 +0100[diff] [blame]151DRLL Dispatching 04.08 message SMS:0x01 (0x9:0x1)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]152DRLL subscr IMSI-901700000004620:MSISDN-46071: Message not permitted for initial conn: SMS:0x01
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]153 lu_result_sent == 0
154- HLR also sends GSUP _UPDATE_LOCATION_RESULT
155<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
156DVLR GSUP rx 11: 06010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]157DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 2
158DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
159DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
160DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
161DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
162DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]163DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
164DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
165DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Allocated
166DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
167DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
168DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
169DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
170- sending LU Accept for IMSI-901700000004620:MSISDN-46071
171DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
172DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
173DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]174DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]175DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]176DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Deferring: will deallocate with upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]177DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
178DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
179DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
180DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
181DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
182DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + release == 1 (0x100: release)
183DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
184DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]185- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]186DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Deallocated, including all deferred deallocations
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]187DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]188<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
189 bssap_clear_sent == 1
190- LU was successful, and the conn has already been closed
191 lu_result_sent == 1
192- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]193DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - release == 0 (0x0: )
194DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
195DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
196DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]197DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]198DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Removing from parent RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
199DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]200DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Deferring: will deallocate with RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]201DRLL IMSI-901700000004620:MSISDN-46071: Freeing RAN connection
202DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]203DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Deallocated, including all deferred deallocations
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]204 llist_count(&net->ran_conns) == 0
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]205---
206- verify that the MS can send another LU request
207- Location Update request causes a GSUP LU request to HLR
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]208 MSC <--GERAN-A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]209 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]210DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]211DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]212DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]213DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
214DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LOCATION UPDATING REQUEST: MI=IMSI-901700000004620 LU-type=IMSI-ATTACH
215DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LU/new-LAC: 1/23
216DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Allocated
217DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
218DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
219DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
220DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 2
221DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
222DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
223DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
224DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
225DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()
226DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()
227DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()
228DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
229DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Allocated
230DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
231DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyrd0756b12018-09-28 02:41:39 +0200[diff] [blame]232DVLR GSUP tx: 04010809710000004026f0280102
233GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]234DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
235DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
236DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
237DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - compl_l3 == 0 (0x0: )
238DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
239DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]240 lu_result_sent == 0
241- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
242<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
243DVLR GSUP rx 17: 10010809710000004026f00804036470f1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]244DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]245DVLR IMSI:901700000004620 has MSISDN:46071
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]246DVLR SUBSCR(IMSI-901700000004620:MSISDN-46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=3)
247DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Updated ID
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]248DVLR GSUP tx: 12010809710000004026f0
249GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]250DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]251<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
252 lu_result_sent == 0
253- having received subscriber data does not mean acceptance
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]254ran_conn_is_accepted() == false
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]255 requests shall be thwarted
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]256DRLL Dispatching 04.08 message GSM48_MT_CC_SETUP (0x3:0x5)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]257DRLL subscr IMSI-901700000004620:MSISDN-46071: Message not permitted for initial conn: GSM48_MT_CC_SETUP
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]258DRLL Dispatching 04.08 message unknown 0x33 (0x5:0x33)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]259DRLL subscr IMSI-901700000004620:MSISDN-46071: Message not permitted for initial conn: unknown 0x33
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]260DRLL Dispatching 04.08 message GSM48_MT_RR_SYSINFO_1 (0x6:0x19)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]261DRLL subscr IMSI-901700000004620:MSISDN-46071: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1
Harald Welte80315ef2018-01-24 22:53:00 +0100[diff] [blame]262DRLL Dispatching 04.08 message SMS:0x01 (0x9:0x1)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]263DRLL subscr IMSI-901700000004620:MSISDN-46071: Message not permitted for initial conn: SMS:0x01
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]264 lu_result_sent == 0
265- HLR also sends GSUP _UPDATE_LOCATION_RESULT
266<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
267DVLR GSUP rx 11: 06010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]268DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
269DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
270DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
271DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
272DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
273DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]274DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
275DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
276DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Allocated
277DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
278DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
279DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
280DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
281- sending LU Accept for IMSI-901700000004620:MSISDN-46071
282DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
283DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]284DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]285DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]286DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Deferring: will deallocate with upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]287DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
288DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
289DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
290DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
291DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
292DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + release == 1 (0x100: release)
293DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
294DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]295- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]296DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Deallocated, including all deferred deallocations
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]297DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]298<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
299 bssap_clear_sent == 1
300- LU was successful, and the conn has already been closed
301 lu_result_sent == 1
302- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]303DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - release == 0 (0x0: )
304DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
305DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
306DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]307DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]308DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Removing from parent RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
309DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]310DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Deferring: will deallocate with RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]311DRLL IMSI-901700000004620:MSISDN-46071: Freeing RAN connection
312DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]313DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Deallocated, including all deferred deallocations
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]314 llist_count(&net->ran_conns) == 0
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]315---
316- subscriber detaches
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]317 MSC <--GERAN-A-- MS: GSM48_MT_MM_IMSI_DETACH_IND
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]318 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]319DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]320DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]321DRLL Dispatching 04.08 message GSM48_MT_MM_IMSI_DETACH_IND (0x5:0x1)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]322DMM IMSI DETACH INDICATION: MI(IMSI)=901700000004620
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]323DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 2
324DMM IMSI DETACH for IMSI-901700000004620:MSISDN-46071
325DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
326DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
327DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
328DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 0
329DREF freeing VLR subscr IMSI-901700000004620:MSISDN-46071
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]330DMM RAN_conn{RAN_CONN_S_NEW}: Received Event RAN_CONN_E_CN_CLOSE
331DMM RAN_conn{RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]332DREF unknown: MSC conn use + release == 2 (0x101: compl_l3,release)
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]333- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]334DREF unknown: MSC conn use - compl_l3 == 1 (0x100: release)
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]335 bssap_clear_sent == 1
336- BSS sends BSSMAP Clear Complete
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]337DREF unknown: MSC conn use - release == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]338DMM RAN_conn{RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
339DMM RAN_conn{RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
340DMM RAN_conn{RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
341DRLL Freeing RAN connection with NULL subscriber
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]342DMM RAN_conn{RAN_CONN_S_RELEASED}: Deallocated
343 llist_count(&net->ran_conns) == 0
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]344===== test_two_lu: SUCCESS
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]345
346full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]347talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]348
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]349===== test_lu_unknown_tmsi
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]350- Location Update request with unknown TMSI sends ID Request for IMSI
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]351 MSC <--GERAN-A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]352 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]353DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]354DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]355DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]356DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
357DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_NEW}: LOCATION UPDATING REQUEST: MI=TMSI-0x23422342 LU-type=IMSI-ATTACH
358DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_NEW}: LU/new-LAC: 1/23
359DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Allocated
360DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_IDLE}: is child of RAN_conn(TMSI-0x23422342:GERAN-A-0:LU)
361DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
362DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]363DREF VLR subscr unknown usage increases to: 1
364DVLR New subscr, TMSI: 0x23422342
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]365DREF VLR subscr TMSI-0x23422342 usage increases to: 2
366DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
367DREF VLR subscr TMSI-0x23422342 usage decreases to: 1
368DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_want_imsi()
369DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_IMSI
370DMSC msc_tx 3 bytes to TMSI-0x23422342 via GERAN-A
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]371- DTAP --GERAN-A--> MS: GSM48_MT_MM_ID_REQ: 051801
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]372- DTAP matches expected message
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]373DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
374DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
375DREF TMSI-0x23422342: MSC conn use - compl_l3 == 0 (0x0: )
376DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
377DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]378 lu_result_sent == 0
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]379ran_conn_is_accepted() == false
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]380 requests shall be thwarted
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]381DRLL Dispatching 04.08 message GSM48_MT_CC_SETUP (0x3:0x5)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]382DRLL subscr TMSI-0x23422342: Message not permitted for initial conn: GSM48_MT_CC_SETUP
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]383DRLL Dispatching 04.08 message unknown 0x33 (0x5:0x33)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]384DRLL subscr TMSI-0x23422342: Message not permitted for initial conn: unknown 0x33
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]385DRLL Dispatching 04.08 message GSM48_MT_RR_SYSINFO_1 (0x6:0x19)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]386DRLL subscr TMSI-0x23422342: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1
Harald Welte80315ef2018-01-24 22:53:00 +0100[diff] [blame]387DRLL Dispatching 04.08 message SMS:0x01 (0x9:0x1)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]388DRLL subscr TMSI-0x23422342: Message not permitted for initial conn: SMS:0x01
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]389- MS tells us the IMSI, causes a GSUP LU request to HLR
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]390 MSC <--GERAN-A-- MS: GSM48_MT_MM_ID_RESP
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]391DREF TMSI-0x23422342: MSC conn use + dtap == 1 (0x2: dtap)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]392DRLL Dispatching 04.08 message GSM48_MT_MM_ID_RESP (0x5:0x19)
Neels Hofmeyrb62289a2019-01-11 00:04:32 +0100[diff] [blame]393DMM IDENTITY RESPONSE: MI=IMSI-901700000004620
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]394DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]395DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_IMSI}: Received Event VLR_ULA_E_ID_IMSI
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]396DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]397DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_IMSI}: vlr_loc_upd_node1()
398DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_IMSI}: vlr_loc_upd_post_auth()
399DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_IMSI}: vlr_loc_upd_post_ciph()
400DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_IMSI}: vlr_loc_upd_node_4()
401DVLR vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_IMSI}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
402DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Allocated
403DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(TMSI-0x23422342:GERAN-A-0:LU)
404DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyrd0756b12018-09-28 02:41:39 +0200[diff] [blame]405DVLR GSUP tx: 04010809710000004026f0280102
406GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]407DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
408DREF IMSI-901700000004620:TMSI-0x23422342: MSC conn use - dtap == 0 (0x0: )
409DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
410DMM RAN_conn(TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]411 lu_result_sent == 0
412- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
413<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
414DVLR GSUP rx 17: 10010809710000004026f00804036470f1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]415DREF VLR subscr IMSI-901700000004620:TMSI-0x23422342 usage increases to: 2
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]416DVLR IMSI:901700000004620 has MSISDN:46071
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]417DVLR SUBSCR(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
418DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Updated ID
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]419DVLR GSUP tx: 12010809710000004026f0
420GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]421DREF VLR subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342 usage decreases to: 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]422<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
423 lu_result_sent == 0
424- having received subscriber data does not mean acceptance
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]425ran_conn_is_accepted() == false
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]426 requests shall be thwarted
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]427DRLL Dispatching 04.08 message GSM48_MT_CC_SETUP (0x3:0x5)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]428DRLL subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342: Message not permitted for initial conn: GSM48_MT_CC_SETUP
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]429DRLL Dispatching 04.08 message unknown 0x33 (0x5:0x33)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]430DRLL subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342: Message not permitted for initial conn: unknown 0x33
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]431DRLL Dispatching 04.08 message GSM48_MT_RR_SYSINFO_1 (0x6:0x19)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]432DRLL subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342: Message not permitted for initial conn: GSM48_MT_RR_SYSINFO_1
Harald Welte80315ef2018-01-24 22:53:00 +0100[diff] [blame]433DRLL Dispatching 04.08 message SMS:0x01 (0x9:0x1)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]434DRLL subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342: Message not permitted for initial conn: SMS:0x01
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]435 lu_result_sent == 0
436- HLR also sends GSUP _UPDATE_LOCATION_RESULT
437<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
438DVLR GSUP rx 11: 06010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]439DREF VLR subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342 usage increases to: 2
440DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
441DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
442DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
443DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
444DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]445DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
446DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
447DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Allocated
448DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU)
449DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
450DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
451DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
452- sending LU Accept for IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342
453DREF VLR subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342 usage increases to: 3
454DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
455DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]456DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]457DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]458DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Deferring: will deallocate with upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]459DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
460DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
461DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
462DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
463DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
464DREF IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342: MSC conn use + release == 1 (0x100: release)
465DREF VLR subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342 usage increases to: 4
466DREF VLR subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342 usage decreases to: 3
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]467- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]468DVLR upd_hlr_vlr_fsm(TMSI-0x23422342:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Deallocated, including all deferred deallocations
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]469DREF VLR subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342 usage decreases to: 2
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]470<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
471 bssap_clear_sent == 1
472- LU was successful, and the conn has already been closed
473 lu_result_sent == 1
474- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]475DREF IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342: MSC conn use - release == 0 (0x0: )
476DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
477DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
478DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]479DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]480DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_DONE}: Removing from parent RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU)
481DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]482DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){VLR_ULA_S_DONE}: Deferring: will deallocate with RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]483DRLL IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342: Freeing RAN connection
484DREF VLR subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342 usage decreases to: 1
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame^]485DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Deallocated, including all deferred deallocations
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]486 llist_count(&net->ran_conns) == 0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]487DREF freeing VLR subscr IMSI-901700000004620:MSISDN-46071:TMSI-0x23422342
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]488===== test_lu_unknown_tmsi: SUCCESS
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]489
490full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]491talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]492
493full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]494talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]495