Blame - tests/msc_vlr/msc_vlr_test_hlr_reject.err - osmo-msc

blob: ba4db3e68378da2ab63d1ed4d0c285ac2e91b54a [file] [log] [blame]

Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	1	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
				2	talloc_total_blocks(tall_bsc_ctx) == 12
				3
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	4	===== test_hlr_rej_auth_info_unknown_imsi
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	5	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	6	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	7	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	8	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	9	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	10	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	11	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	12	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	13	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	14	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	15	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	16	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				17	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	18	DREF VLR subscr unknown usage increases to: 1
				19	DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
				20	DVLR New subscr, IMSI: 901700000004620
				21	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	22	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	23	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				24	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				25	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				26	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				27	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	28	DVLR GSUP tx: 08010809710000004026f0
				29	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	30	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	31	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				32	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	33	DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	34	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				35	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	36	lu_result_sent == 0
				37	- HLR sends _SEND_AUTH_INFO_ERROR = unknown IMSI
				38	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: 09010809710000004026f0020102
				39	DVLR GSUP rx 14: 09010809710000004026f0020102
				40	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	41	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_NACK
				42	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: GSUP: rx Auth Info Error cause: 2: IMSI unknown in HLR
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	43	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Authentication terminating with result IMSI unknown in HLR
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	44	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_AUTH_FAILED
				45	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				46	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				47	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Freeing instance
				48	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Deallocated
				49	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	50	- sending LU Reject for IMSI:901700000004620, cause 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	51	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	52	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
				53	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	54	DREF IMSI:901700000004620: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	55	DREF VLR subscr IMSI:901700000004620 usage increases to: 3
				56	DREF VLR subscr IMSI:901700000004620 usage decreases to: 2
				57	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	58	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
				59	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: vlr_gsupc_read_cb() returns 0
				60	auth_request_sent == 0
				61	lu_result_sent == 2
				62	bssap_clear_sent == 1
				63	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	64	DREF IMSI:901700000004620: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	65	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				66	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				67	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	68	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	69	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	70	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				71	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				72	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	73	DRLL IMSI:901700000004620: Freeing RAN connection
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	74	DREF VLR subscr IMSI:901700000004620 usage decreases to: 0
				75	DREF freeing VLR subscr IMSI:901700000004620
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	76	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				77	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				78	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	79	===== test_hlr_rej_auth_info_unknown_imsi: SUCCESS
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	80
				81	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	82	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	83
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	84	===== test_hlr_rej_auth_info_net_fail
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	85	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	86	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	87	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	88	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	89	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	90	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	91	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	92	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	93	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	94	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	95	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	96	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				97	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	98	DREF VLR subscr unknown usage increases to: 1
				99	DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
				100	DVLR New subscr, IMSI: 901700000004620
				101	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	102	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	103	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				104	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				105	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				106	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				107	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	108	DVLR GSUP tx: 08010809710000004026f0
				109	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	110	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	111	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				112	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	113	DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	114	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				115	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	116	lu_result_sent == 0
				117	- HLR sends _SEND_AUTH_INFO_ERROR = net fail
				118	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: 09010809710000004026f0020111
				119	DVLR GSUP rx 14: 09010809710000004026f0020111
				120	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	121	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_NACK
				122	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: GSUP: rx Auth Info Error cause: 17: Network failure
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	123	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Authentication terminating with result Network failure
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	124	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_AUTH_FAILED
				125	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				126	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				127	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Freeing instance
				128	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Deallocated
				129	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	130	- sending LU Reject for IMSI:901700000004620, cause 17
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	131	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	132	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
				133	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	134	DREF IMSI:901700000004620: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	135	DREF VLR subscr IMSI:901700000004620 usage increases to: 3
				136	DREF VLR subscr IMSI:901700000004620 usage decreases to: 2
				137	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	138	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
				139	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: vlr_gsupc_read_cb() returns 0
				140	auth_request_sent == 0
				141	lu_result_sent == 2
				142	bssap_clear_sent == 1
				143	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	144	DREF IMSI:901700000004620: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	145	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				146	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				147	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	148	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	149	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	150	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				151	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				152	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	153	DRLL IMSI:901700000004620: Freeing RAN connection
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	154	DREF VLR subscr IMSI:901700000004620 usage decreases to: 0
				155	DREF freeing VLR subscr IMSI:901700000004620
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	156	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				157	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				158	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	159	===== test_hlr_rej_auth_info_net_fail: SUCCESS
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	160
				161	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	162	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	163
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	164	===== test_hlr_rej_auth_info_net_fail_reuse_tuples
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	165	---
				166	- Submit a used auth tuple in the VLR
				167	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	168	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	169	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	170	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	171	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	172	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	173	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	174	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	175	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	176	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	177	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	178	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				179	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	180	DREF VLR subscr unknown usage increases to: 1
				181	DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
				182	DVLR New subscr, IMSI: 901700000004620
				183	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	184	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	185	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				186	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				187	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				188	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				189	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	190	DVLR GSUP tx: 08010809710000004026f0
				191	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	192	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	193	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				194	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	195	DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	196	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				197	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	198	lu_result_sent == 0
				199	- from HLR, rx _SEND_AUTH_INFO_RESULT; VLR sends Auth Req to MS
				200	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
				201	DVLR GSUP rx 47: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
				202	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	203	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	204	DVLR SUBSCR(IMSI:901700000004620) Received 1 auth tuples
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	205	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP
				206	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x1)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	207	- sending GSM Auth Request for IMSI:901700000004620: tuple use_count=1 key_seq=0 auth_types=0x1 and...
				208	- ...rand=585df1ae287f6e273dce07090d61320b
				209	- ...expecting sres=2d8b2c3e
				210	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
				211	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0
				212	auth_request_sent == 1
				213	lu_result_sent == 0
				214	- MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	215	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_AUTH_RESP
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	216	DREF IMSI:901700000004620: MSC conn use + dtap == 1 (0x2: dtap)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	217	DRLL Dispatching 04.08 message GSM48_MT_MM_AUTH_RESP (0x5:0x14)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	218	DMM IMSI:901700000004620: MM GSM AUTHENTICATION RESPONSE (sres = 2d8b2c3e)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	219	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
Neels Hofmeyr	a9099bc	2018-03-10 04:22:50 +0100	[diff] [blame]	220	DVLR SUBSCR(IMSI:901700000004620) AUTH on GERAN received SRES/RES: 2d8b2c3e (4 bytes)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	221	DVLR SUBSCR(IMSI:901700000004620) AUTH established GSM security context
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	222	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	223	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED
				224	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				225	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				226	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Freeing instance
				227	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Deallocated
				228	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
				229	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()
				230	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()
				231	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()
				232	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
				233	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Allocated
				234	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				235	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyr	d0756b1	2018-09-28 02:41:39 +0200	[diff] [blame]	236	DVLR GSUP tx: 04010809710000004026f0280102
				237	GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	238	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	239	DREF IMSI:901700000004620: MSC conn use - dtap == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	240	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				241	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	242	lu_result_sent == 0
				243	- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
				244	<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
				245	DVLR GSUP rx 17: 10010809710000004026f00804036470f1
				246	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
				247	DVLR IMSI:901700000004620 has MSISDN:46071
Max	a263bb2	2017-12-27 13:23:44 +0100	[diff] [blame]	248	DVLR SUBSCR(MSISDN:46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	249	DVLR GSUP tx: 12010809710000004026f0
				250	GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
				251	DREF VLR subscr MSISDN:46071 usage decreases to: 1
				252	<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
				253	lu_result_sent == 0
				254	- HLR also sends GSUP _UPDATE_LOCATION_RESULT
				255	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
				256	DVLR GSUP rx 11: 06010809710000004026f0
				257	DREF VLR subscr MSISDN:46071 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	258	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
				259	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
				260	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
				261	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				262	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				263	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Freeing instance
				264	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Deallocated
				265	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
				266	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
				267	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Allocated
				268	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				269	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
				270	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
				271	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Allocated
				272	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: is child of lu_compl_vlr_fsm(LU:901700000004620)
				273	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Received Event SUB_PRES_VLR_E_START
				274	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: state_chg to SUB_PRES_VLR_S_DONE
				275	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				276	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Removing from parent lu_compl_vlr_fsm(LU:901700000004620)
				277	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Freeing instance
				278	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Deallocated
				279	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	280	- sending LU Accept for MSISDN:46071
				281	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	282	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
				283	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
				284	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
				285	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				286	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Freeing instance
				287	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Deallocated
				288	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	289	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
				290	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
				291	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
				292	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	293	DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	294	DREF VLR subscr MSISDN:46071 usage increases to: 4
				295	DREF VLR subscr MSISDN:46071 usage decreases to: 3
				296	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	297	DREF VLR subscr MSISDN:46071 usage decreases to: 2
				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 Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	303	DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	304	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				305	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				306	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	307	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	308	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	309	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				310	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				311	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	312	DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	313	DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	314	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				315	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				316	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	317	---
				318	- Now one auth tuple is available, but used.
				319	DREF VLR subscr MSISDN:46071 usage increases to: 2
				320	vsub->last_tuple->use_count == 1
				321	DREF VLR subscr MSISDN:46071 usage decreases to: 1
				322	---
				323	- Another LU wants to get new tuples; even though HLR sends Network Failure, we are reusing the old tuples.
				324	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	325	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	326	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	327	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	328	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	329	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	330	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	331	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	332	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	333	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	334	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	335	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				336	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	337	DREF VLR subscr MSISDN:46071 usage increases to: 2
				338	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	339	DREF VLR subscr MSISDN:46071 usage decreases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	340	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				341	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				342	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				343	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				344	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	345	DVLR GSUP tx: 08010809710000004026f0
				346	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	347	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	348	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				349	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	350	DREF MSISDN:46071: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	351	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				352	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	353	lu_result_sent == 0
				354	- HLR sends _SEND_AUTH_INFO_ERROR = net fail
				355	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: 09010809710000004026f0020111
				356	DVLR GSUP rx 14: 09010809710000004026f0020111
				357	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	358	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_NACK
				359	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: GSUP: rx Auth Info Error cause: 17: Network failure
				360	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP
				361	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=2 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x1)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	362	- sending GSM Auth Request for MSISDN:46071: tuple use_count=2 key_seq=0 auth_types=0x1 and...
				363	- ...rand=585df1ae287f6e273dce07090d61320b
				364	- ...expecting sres=2d8b2c3e
				365	DREF VLR subscr MSISDN:46071 usage decreases to: 2
				366	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: vlr_gsupc_read_cb() returns 0
				367	auth_request_sent == 1
				368	lu_result_sent == 0
				369	- MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	370	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_AUTH_RESP
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	371	DREF MSISDN:46071: MSC conn use + dtap == 1 (0x2: dtap)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	372	DRLL Dispatching 04.08 message GSM48_MT_MM_AUTH_RESP (0x5:0x14)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	373	DMM MSISDN:46071: MM GSM AUTHENTICATION RESPONSE (sres = 2d8b2c3e)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	374	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
Neels Hofmeyr	a9099bc	2018-03-10 04:22:50 +0100	[diff] [blame]	375	DVLR SUBSCR(MSISDN:46071) AUTH on GERAN received SRES/RES: 2d8b2c3e (4 bytes)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	376	DVLR SUBSCR(MSISDN:46071) AUTH established GSM security context
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	377	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	378	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED
				379	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				380	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				381	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Freeing instance
				382	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Deallocated
				383	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
				384	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()
				385	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()
				386	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()
				387	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
				388	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Allocated
				389	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				390	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyr	d0756b1	2018-09-28 02:41:39 +0200	[diff] [blame]	391	DVLR GSUP tx: 04010809710000004026f0280102
				392	GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	393	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	394	DREF MSISDN:46071: MSC conn use - dtap == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	395	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				396	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	397	lu_result_sent == 0
				398	- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
				399	<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
				400	DVLR GSUP rx 17: 10010809710000004026f00804036470f1
				401	DREF VLR subscr MSISDN:46071 usage increases to: 3
				402	DVLR IMSI:901700000004620 has MSISDN:46071
Max	a263bb2	2017-12-27 13:23:44 +0100	[diff] [blame]	403	DVLR SUBSCR(MSISDN:46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=3)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	404	DVLR GSUP tx: 12010809710000004026f0
				405	GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
				406	DREF VLR subscr MSISDN:46071 usage decreases to: 2
				407	<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
				408	lu_result_sent == 0
				409	- HLR also sends GSUP _UPDATE_LOCATION_RESULT
				410	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
				411	DVLR GSUP rx 11: 06010809710000004026f0
				412	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	413	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
				414	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
				415	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
				416	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				417	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				418	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Freeing instance
				419	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Deallocated
				420	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
				421	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
				422	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Allocated
				423	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				424	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
				425	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
				426	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Allocated
				427	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: is child of lu_compl_vlr_fsm(LU:901700000004620)
				428	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Received Event SUB_PRES_VLR_E_START
				429	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: state_chg to SUB_PRES_VLR_S_DONE
				430	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				431	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Removing from parent lu_compl_vlr_fsm(LU:901700000004620)
				432	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Freeing instance
				433	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Deallocated
				434	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	435	- sending LU Accept for MSISDN:46071
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	436	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
				437	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
				438	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
				439	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				440	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Freeing instance
				441	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Deallocated
				442	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	443	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
				444	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
				445	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
				446	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	447	DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	448	DREF VLR subscr MSISDN:46071 usage increases to: 4
				449	DREF VLR subscr MSISDN:46071 usage decreases to: 3
				450	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	451	DREF VLR subscr MSISDN:46071 usage decreases to: 2
				452	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
				453	bssap_clear_sent == 1
				454	- LU was successful, and the conn has already been closed
				455	lu_result_sent == 1
				456	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	457	DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	458	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				459	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				460	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	461	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	462	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	463	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				464	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				465	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	466	DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	467	DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	468	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				469	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				470	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	471	DREF freeing VLR subscr MSISDN:46071
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	472	===== test_hlr_rej_auth_info_net_fail_reuse_tuples: SUCCESS
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	473
				474	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	475	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	476
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	477	===== test_hlr_rej_auth_info_net_fail_no_reuse_tuples
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	478	---
				479	- Submit a used auth tuple in the VLR
				480	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	481	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	482	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	483	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	484	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	485	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	486	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	487	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	488	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	489	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	490	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	491	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				492	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	493	DREF VLR subscr unknown usage increases to: 1
				494	DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
				495	DVLR New subscr, IMSI: 901700000004620
				496	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	497	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	498	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				499	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				500	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				501	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				502	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	503	DVLR GSUP tx: 08010809710000004026f0
				504	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	505	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	506	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				507	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	508	DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	509	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				510	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	511	lu_result_sent == 0
				512	- from HLR, rx _SEND_AUTH_INFO_RESULT; VLR sends Auth Req to MS
				513	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
				514	DVLR GSUP rx 47: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
				515	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	516	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	517	DVLR SUBSCR(IMSI:901700000004620) Received 1 auth tuples
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	518	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP
				519	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x1)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	520	- sending GSM Auth Request for IMSI:901700000004620: tuple use_count=1 key_seq=0 auth_types=0x1 and...
				521	- ...rand=585df1ae287f6e273dce07090d61320b
				522	- ...expecting sres=2d8b2c3e
				523	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
				524	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0
				525	auth_request_sent == 1
				526	lu_result_sent == 0
				527	- MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	528	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_AUTH_RESP
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	529	DREF IMSI:901700000004620: MSC conn use + dtap == 1 (0x2: dtap)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	530	DRLL Dispatching 04.08 message GSM48_MT_MM_AUTH_RESP (0x5:0x14)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	531	DMM IMSI:901700000004620: MM GSM AUTHENTICATION RESPONSE (sres = 2d8b2c3e)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	532	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
Neels Hofmeyr	a9099bc	2018-03-10 04:22:50 +0100	[diff] [blame]	533	DVLR SUBSCR(IMSI:901700000004620) AUTH on GERAN received SRES/RES: 2d8b2c3e (4 bytes)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	534	DVLR SUBSCR(IMSI:901700000004620) AUTH established GSM security context
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	535	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	536	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED
				537	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				538	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				539	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Freeing instance
				540	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Deallocated
				541	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
				542	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()
				543	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()
				544	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()
				545	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
				546	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Allocated
				547	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				548	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyr	d0756b1	2018-09-28 02:41:39 +0200	[diff] [blame]	549	DVLR GSUP tx: 04010809710000004026f0280102
				550	GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	551	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	552	DREF IMSI:901700000004620: MSC conn use - dtap == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	553	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				554	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	555	lu_result_sent == 0
				556	- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
				557	<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
				558	DVLR GSUP rx 17: 10010809710000004026f00804036470f1
				559	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
				560	DVLR IMSI:901700000004620 has MSISDN:46071
Max	a263bb2	2017-12-27 13:23:44 +0100	[diff] [blame]	561	DVLR SUBSCR(MSISDN:46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	562	DVLR GSUP tx: 12010809710000004026f0
				563	GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
				564	DREF VLR subscr MSISDN:46071 usage decreases to: 1
				565	<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
				566	lu_result_sent == 0
				567	- HLR also sends GSUP _UPDATE_LOCATION_RESULT
				568	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
				569	DVLR GSUP rx 11: 06010809710000004026f0
				570	DREF VLR subscr MSISDN:46071 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	571	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
				572	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
				573	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
				574	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				575	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				576	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Freeing instance
				577	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Deallocated
				578	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
				579	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
				580	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Allocated
				581	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				582	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
				583	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
				584	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Allocated
				585	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: is child of lu_compl_vlr_fsm(LU:901700000004620)
				586	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Received Event SUB_PRES_VLR_E_START
				587	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: state_chg to SUB_PRES_VLR_S_DONE
				588	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				589	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Removing from parent lu_compl_vlr_fsm(LU:901700000004620)
				590	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Freeing instance
				591	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Deallocated
				592	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	593	- sending LU Accept for MSISDN:46071
				594	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	595	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
				596	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
				597	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
				598	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				599	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Freeing instance
				600	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Deallocated
				601	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	602	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
				603	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
				604	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
				605	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	606	DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	607	DREF VLR subscr MSISDN:46071 usage increases to: 4
				608	DREF VLR subscr MSISDN:46071 usage decreases to: 3
				609	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	610	DREF VLR subscr MSISDN:46071 usage decreases to: 2
				611	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
				612	bssap_clear_sent == 1
				613	- LU was successful, and the conn has already been closed
				614	lu_result_sent == 1
				615	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	616	DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	617	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				618	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				619	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	620	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	621	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	622	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				623	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				624	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	625	DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	626	DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	627	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				628	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				629	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	630	---
				631	- Now one auth tuple is available, but used.
				632	DREF VLR subscr MSISDN:46071 usage increases to: 2
				633	vsub->last_tuple->use_count == 1
				634	DREF VLR subscr MSISDN:46071 usage decreases to: 1
				635	---
				636	- Another LU wants to get new tuples; HLR sends Network Failure, we reject.
				637	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	638	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	639	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	640	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	641	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	642	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	643	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	644	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	645	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	646	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	647	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	648	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				649	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	650	DREF VLR subscr MSISDN:46071 usage increases to: 2
				651	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	652	DREF VLR subscr MSISDN:46071 usage decreases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	653	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				654	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				655	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				656	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				657	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	658	DVLR GSUP tx: 08010809710000004026f0
				659	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	660	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	661	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				662	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	663	DREF MSISDN:46071: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	664	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				665	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	666	lu_result_sent == 0
				667	- HLR sends _SEND_AUTH_INFO_ERROR = net fail
				668	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: 09010809710000004026f0020111
				669	DVLR GSUP rx 14: 09010809710000004026f0020111
				670	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	671	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_NACK
				672	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: GSUP: rx Auth Info Error cause: 17: Network failure
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	673	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Authentication terminating with result Network failure
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	674	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_AUTH_FAILED
				675	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				676	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				677	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Freeing instance
				678	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Deallocated
				679	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	680	- sending LU Reject for MSISDN:46071, cause 17
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	681	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	682	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
				683	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	684	DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	685	DREF VLR subscr MSISDN:46071 usage increases to: 4
				686	DREF VLR subscr MSISDN:46071 usage decreases to: 3
				687	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	688	DREF VLR subscr MSISDN:46071 usage decreases to: 2
				689	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: vlr_gsupc_read_cb() returns 0
				690	auth_request_sent == 0
				691	lu_result_sent == 2
				692	bssap_clear_sent == 1
				693	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	694	DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	695	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				696	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				697	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	698	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	699	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	700	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				701	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				702	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	703	DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	704	DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	705	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				706	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				707	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	708	DREF freeing VLR subscr MSISDN:46071
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	709	===== test_hlr_rej_auth_info_net_fail_no_reuse_tuples: SUCCESS
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	710
				711	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	712	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	713
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	714	===== test_hlr_rej_auth_info_unkown_imsi_no_reuse_tuples
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	715	---
				716	- Submit a used auth tuple in the VLR
				717	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	718	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	719	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	720	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	721	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	722	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	723	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	724	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	725	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	726	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	727	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	728	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				729	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	730	DREF VLR subscr unknown usage increases to: 1
				731	DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
				732	DVLR New subscr, IMSI: 901700000004620
				733	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	734	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	735	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				736	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				737	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				738	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				739	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	740	DVLR GSUP tx: 08010809710000004026f0
				741	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	742	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	743	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				744	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	745	DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	746	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				747	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	748	lu_result_sent == 0
				749	- from HLR, rx _SEND_AUTH_INFO_RESULT; VLR sends Auth Req to MS
				750	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
				751	DVLR GSUP rx 47: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
				752	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	753	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	754	DVLR SUBSCR(IMSI:901700000004620) Received 1 auth tuples
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	755	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP
				756	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x1)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	757	- sending GSM Auth Request for IMSI:901700000004620: tuple use_count=1 key_seq=0 auth_types=0x1 and...
				758	- ...rand=585df1ae287f6e273dce07090d61320b
				759	- ...expecting sres=2d8b2c3e
				760	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
				761	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0
				762	auth_request_sent == 1
				763	lu_result_sent == 0
				764	- MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	765	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_AUTH_RESP
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	766	DREF IMSI:901700000004620: MSC conn use + dtap == 1 (0x2: dtap)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	767	DRLL Dispatching 04.08 message GSM48_MT_MM_AUTH_RESP (0x5:0x14)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	768	DMM IMSI:901700000004620: MM GSM AUTHENTICATION RESPONSE (sres = 2d8b2c3e)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	769	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
Neels Hofmeyr	a9099bc	2018-03-10 04:22:50 +0100	[diff] [blame]	770	DVLR SUBSCR(IMSI:901700000004620) AUTH on GERAN received SRES/RES: 2d8b2c3e (4 bytes)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	771	DVLR SUBSCR(IMSI:901700000004620) AUTH established GSM security context
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	772	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	773	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED
				774	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				775	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				776	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Freeing instance
				777	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Deallocated
				778	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
				779	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()
				780	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()
				781	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()
				782	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
				783	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Allocated
				784	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				785	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyr	d0756b1	2018-09-28 02:41:39 +0200	[diff] [blame]	786	DVLR GSUP tx: 04010809710000004026f0280102
				787	GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	788	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	789	DREF IMSI:901700000004620: MSC conn use - dtap == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	790	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				791	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	792	lu_result_sent == 0
				793	- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
				794	<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
				795	DVLR GSUP rx 17: 10010809710000004026f00804036470f1
				796	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
				797	DVLR IMSI:901700000004620 has MSISDN:46071
Max	a263bb2	2017-12-27 13:23:44 +0100	[diff] [blame]	798	DVLR SUBSCR(MSISDN:46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	799	DVLR GSUP tx: 12010809710000004026f0
				800	GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
				801	DREF VLR subscr MSISDN:46071 usage decreases to: 1
				802	<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
				803	lu_result_sent == 0
				804	- HLR also sends GSUP _UPDATE_LOCATION_RESULT
				805	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
				806	DVLR GSUP rx 11: 06010809710000004026f0
				807	DREF VLR subscr MSISDN:46071 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	808	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
				809	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
				810	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
				811	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				812	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				813	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Freeing instance
				814	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Deallocated
				815	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
				816	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
				817	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Allocated
				818	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				819	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
				820	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
				821	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Allocated
				822	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: is child of lu_compl_vlr_fsm(LU:901700000004620)
				823	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Received Event SUB_PRES_VLR_E_START
				824	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: state_chg to SUB_PRES_VLR_S_DONE
				825	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				826	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Removing from parent lu_compl_vlr_fsm(LU:901700000004620)
				827	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Freeing instance
				828	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Deallocated
				829	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	830	- sending LU Accept for MSISDN:46071
				831	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	832	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
				833	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
				834	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
				835	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				836	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Freeing instance
				837	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Deallocated
				838	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	839	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
				840	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
				841	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
				842	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	843	DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	844	DREF VLR subscr MSISDN:46071 usage increases to: 4
				845	DREF VLR subscr MSISDN:46071 usage decreases to: 3
				846	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	847	DREF VLR subscr MSISDN:46071 usage decreases to: 2
				848	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
				849	bssap_clear_sent == 1
				850	- LU was successful, and the conn has already been closed
				851	lu_result_sent == 1
				852	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	853	DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	854	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				855	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				856	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	857	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	858	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	859	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				860	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				861	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	862	DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	863	DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	864	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				865	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				866	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	867	---
				868	- Now one auth tuple is available, but used.
				869	DREF VLR subscr MSISDN:46071 usage increases to: 2
				870	vsub->last_tuple->use_count == 1
				871	DREF VLR subscr MSISDN:46071 usage decreases to: 1
				872	---
				873	- Another LU wants to get new tuples; HLR sends IMSI Unknown. Even though we would re-use an old tuple, reject the unknown IMSI.
				874	net->vlr->cfg.auth_reuse_old_sets_on_error == 1
				875	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	876	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	877	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	878	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	879	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	880	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	881	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	882	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	883	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	884	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	885	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	886	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				887	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	888	DREF VLR subscr MSISDN:46071 usage increases to: 2
				889	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	890	DREF VLR subscr MSISDN:46071 usage decreases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	891	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				892	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				893	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				894	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				895	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	896	DVLR GSUP tx: 08010809710000004026f0
				897	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	898	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	899	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				900	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	901	DREF MSISDN:46071: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	902	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				903	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	904	lu_result_sent == 0
				905	- HLR sends _SEND_AUTH_INFO_ERROR = unknown IMSI
				906	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: 09010809710000004026f0020102
				907	DVLR GSUP rx 14: 09010809710000004026f0020102
				908	DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	909	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_NACK
				910	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: GSUP: rx Auth Info Error cause: 2: IMSI unknown in HLR
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	911	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Authentication terminating with result IMSI unknown in HLR
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	912	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_AUTH_FAILED
				913	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				914	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				915	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Freeing instance
				916	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Deallocated
				917	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	918	- sending LU Reject for MSISDN:46071, cause 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	919	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	920	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
				921	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	922	DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	923	DREF VLR subscr MSISDN:46071 usage increases to: 4
				924	DREF VLR subscr MSISDN:46071 usage decreases to: 3
				925	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	926	DREF VLR subscr MSISDN:46071 usage decreases to: 2
				927	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_ERROR: vlr_gsupc_read_cb() returns 0
				928	auth_request_sent == 0
				929	lu_result_sent == 2
				930	bssap_clear_sent == 1
				931	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	932	DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	933	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				934	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				935	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	936	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	937	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	938	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				939	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				940	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	941	DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	942	DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	943	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				944	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				945	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	946	DREF freeing VLR subscr MSISDN:46071
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	947	===== test_hlr_rej_auth_info_unkown_imsi_no_reuse_tuples: SUCCESS
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	948
				949	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	950	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	951
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	952	===== test_hlr_acc_but_no_auth_tuples
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	953	- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	954	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	955	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	956	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	957	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	958	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	959	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	960	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	961	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	962	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	963	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	964	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
				965	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	966	DREF VLR subscr unknown usage increases to: 1
				967	DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
				968	DVLR New subscr, IMSI: 901700000004620
				969	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	970	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	971	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				972	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
				973	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
				974	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
				975	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	976	DVLR GSUP tx: 08010809710000004026f0
				977	GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	978	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	979	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				980	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	981	DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	982	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				983	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	984	lu_result_sent == 0
				985	- from HLR, rx _SEND_AUTH_INFO_RESULT but it lacks auth tuples
				986	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000004026f0
				987	DVLR GSUP rx 11: 0a010809710000004026f0
				988	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	989	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
Neels Hofmeyr	1580959	2018-04-06 02:57:51 +0200	[diff] [blame]	990	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Authentication terminating with result Network failure
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	991	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_AUTH_FAILED
				992	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				993	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				994	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Freeing instance
				995	DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTH_FAILED}: Deallocated
				996	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	997	- sending LU Reject for IMSI:901700000004620, cause 17
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	998	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	999	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
				1000	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1001	DREF IMSI:901700000004620: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	1002	DREF VLR subscr IMSI:901700000004620 usage increases to: 3
				1003	DREF VLR subscr IMSI:901700000004620 usage decreases to: 2
				1004	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	1005	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
				1006	<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0
				1007	auth_request_sent == 0
				1008	lu_result_sent == 2
				1009	bssap_clear_sent == 1
				1010	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1011	DREF IMSI:901700000004620: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1012	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				1013	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				1014	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1015	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1016	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1017	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				1018	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				1019	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1020	DRLL IMSI:901700000004620: Freeing RAN connection
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1021	DREF VLR subscr IMSI:901700000004620 usage decreases to: 0
				1022	DREF freeing VLR subscr IMSI:901700000004620
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1023	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				1024	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				1025	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	1026	===== test_hlr_acc_but_no_auth_tuples: SUCCESS
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1027
				1028	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	1029	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1030
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	1031	===== test_hlr_rej_lu
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1032	- Location Update request causes a GSUP LU request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	1033	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1034	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1035	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1036	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	1037	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1038	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1039	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	1040	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1041	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1042	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1043	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
				1044	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1045	DREF VLR subscr unknown usage increases to: 1
				1046	DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
				1047	DVLR New subscr, IMSI: 901700000004620
				1048	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	1049	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1050	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				1051	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()
				1052	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()
				1053	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()
				1054	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
				1055	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Allocated
				1056	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				1057	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyr	d0756b1	2018-09-28 02:41:39 +0200	[diff] [blame]	1058	DVLR GSUP tx: 04010809710000004026f0280102
				1059	GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1060	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1061	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				1062	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1063	DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1064	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				1065	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1066	lu_result_sent == 0
				1067	- HLR sends UPDATE_LOCATION_ERROR
				1068	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_ERROR: 05010809710000004026f0020102
				1069	DVLR GSUP rx 14: 05010809710000004026f0020102
				1070	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
				1071	DVLR SUBSCR(IMSI:901700000004620) UpdateLocation failed; gmm_cause: IMSI unknown in HLR
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1072	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
				1073	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_NACK
				1074	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
				1075	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				1076	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				1077	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Freeing instance
				1078	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Deallocated
				1079	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1080	- sending LU Reject for IMSI:901700000004620, cause 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1081	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1082	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
				1083	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1084	DREF IMSI:901700000004620: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	1085	DREF VLR subscr IMSI:901700000004620 usage increases to: 3
				1086	DREF VLR subscr IMSI:901700000004620 usage decreases to: 2
				1087	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	1088	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
				1089	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_ERROR: vlr_gsupc_read_cb() returns 0
				1090	lu_result_sent == 2
				1091	bssap_clear_sent == 1
				1092	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1093	DREF IMSI:901700000004620: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1094	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				1095	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				1096	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1097	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1098	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1099	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				1100	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				1101	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1102	DRLL IMSI:901700000004620: Freeing RAN connection
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1103	DREF VLR subscr IMSI:901700000004620 usage decreases to: 0
				1104	DREF freeing VLR subscr IMSI:901700000004620
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1105	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				1106	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				1107	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	1108	===== test_hlr_rej_lu: SUCCESS
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1109
				1110	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	1111	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1112
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	1113	===== test_hlr_no_insert_data
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1114	- Location Update request causes a GSUP LU request to HLR
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	1115	MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1116	new conn
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1117	DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1118	DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr	78ada64	2017-03-10 02:15:20 +0100	[diff] [blame]	1119	DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1120	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1121	DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr	8a656eb	2017-11-22 02:55:52 +0100	[diff] [blame]	1122	DMM LU/new-LAC: 1/23
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1123	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1124	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1125	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
				1126	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1127	DREF VLR subscr unknown usage increases to: 1
				1128	DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
				1129	DVLR New subscr, IMSI: 901700000004620
				1130	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	54a706c	2017-07-18 15:39:27 +0200	[diff] [blame]	1131	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1132	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
				1133	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()
				1134	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()
				1135	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()
				1136	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
				1137	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Allocated
				1138	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				1139	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyr	d0756b1	2018-09-28 02:41:39 +0200	[diff] [blame]	1140	DVLR GSUP tx: 04010809710000004026f0280102
				1141	GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1142	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1143	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
				1144	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1145	DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1146	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
				1147	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1148	lu_result_sent == 0
				1149	- HLR sends only _UPDATE_LOCATION_RESULT, no INSERT DATA
				1150	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
				1151	DVLR GSUP rx 11: 06010809710000004026f0
				1152	DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1153	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
				1154	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
				1155	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
				1156	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				1157	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				1158	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Freeing instance
				1159	DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Deallocated
				1160	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
				1161	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
				1162	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Allocated
				1163	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
				1164	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
				1165	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
				1166	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Allocated
				1167	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: is child of lu_compl_vlr_fsm(LU:901700000004620)
				1168	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Received Event SUB_PRES_VLR_E_START
				1169	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: state_chg to SUB_PRES_VLR_S_DONE
				1170	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
				1171	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Removing from parent lu_compl_vlr_fsm(LU:901700000004620)
				1172	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Freeing instance
				1173	DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Deallocated
				1174	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1175	- sending LU Accept for IMSI:901700000004620
				1176	DREF VLR subscr IMSI:901700000004620 usage increases to: 3
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1177	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
				1178	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
				1179	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
				1180	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
				1181	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Freeing instance
				1182	DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Deallocated
				1183	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1184	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
				1185	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
				1186	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
				1187	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1188	DREF IMSI:901700000004620: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr	e3d3dc6	2018-03-31 00:02:14 +0200	[diff] [blame]	1189	DREF VLR subscr IMSI:901700000004620 usage increases to: 4
				1190	DREF VLR subscr IMSI:901700000004620 usage decreases to: 3
				1191	- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	1192	DREF VLR subscr IMSI:901700000004620 usage decreases to: 2
				1193	<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
				1194	bssap_clear_sent == 1
				1195	- LU was successful, and the conn has already been closed
				1196	lu_result_sent == 1
				1197	- BSS sends BSSMAP Clear Complete
Neels Hofmeyr	99a8d23	2018-04-09 20:44:56 +0200	[diff] [blame]	1198	DREF IMSI:901700000004620: MSC conn use - release == 0 (0x0: )
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1199	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
				1200	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
				1201	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1202	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1203	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr	fe4ba7c	2018-04-02 23:17:50 +0200	[diff] [blame]	1204	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
				1205	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
				1206	DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1207	DRLL IMSI:901700000004620: Freeing RAN connection
Neels Hofmeyr	4068ab2	2018-04-01 20:55:54 +0200	[diff] [blame]	1208	DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyr	c036b79	2018-11-29 22:37:51 +0100	[diff] [blame^]	1209	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
				1210	DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
				1211	llist_count(&net->ran_conns) == 0
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1212	DREF freeing VLR subscr IMSI:901700000004620
Neels Hofmeyr	dfdc61d	2018-03-02 00:40:58 +0100	[diff] [blame]	1213	===== test_hlr_no_insert_data: SUCCESS
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1214
				1215	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	1216	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1217
				1218	full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr	08b3828	2018-03-30 23:04:04 +0200	[diff] [blame]	1219	talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr	6a29d32	2017-01-25 15:04:16 +0100	[diff] [blame]	1220