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