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

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