Gitiles
Code Review Sign In
gerrit.osmocom.org / osmo-msc / 7c5346cd7005ad469702a2f74572b79de738fbbb / . / tests / msc_vlr / msc_vlr_test_hlr_timeout.err
blob: 900488a931470c218ec1d8e36b399fb6334d2150 [file] [log] [blame]
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]1full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]2talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]3
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]4===== test_hlr_timeout_lu_auth_info
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]5- Total time passed: 0.000000 s
6- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]7 MSC <--GERAN-A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]8 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]9DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]10DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]11DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]12DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
13DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LOCATION UPDATING REQUEST: MI=IMSI-901700000004620 LU-type=IMSI-ATTACH
14DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LU/new-LAC: 1/23
15DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Allocated
16DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
17DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
18DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]19DREF VLR subscr unknown + _lu_fsm_associate_vsub: now used by 1 (_lu_fsm_associate_vsub)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]20DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
21DVLR New subscr, IMSI: 901700000004620
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]22DREF VLR subscr IMSI-901700000004620 + conn: now used by 2 (_lu_fsm_associate_vsub,conn)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]23DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]24DREF VLR subscr IMSI-901700000004620 - _lu_fsm_associate_vsub: now used by 1 (conn)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]25DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
26DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
27DVLR VLR_Authenticate(IMSI-901700000004620:GERAN-A-0:LU){VLR_SUB_AS_NEEDS_AUTH}: Allocated
28DVLR VLR_Authenticate(IMSI-901700000004620:GERAN-A-0:LU){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU)
29DVLR VLR_Authenticate(IMSI-901700000004620:GERAN-A-0:LU){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]30DVLR GSUP tx: 08010809710000004026f0
31GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]32DVLR VLR_Authenticate(IMSI-901700000004620:GERAN-A-0:LU){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
33DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
34DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
35DREF IMSI-901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
36DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
37DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]38 lu_result_sent == 0
39---
40- HLR never replies
41- At first, we're still waiting
42- Total time passed: 0.000423 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]43 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]44- Total time passed: 1.000658 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]45 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]46- Total time passed: 2.000893 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]47 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]48- Total time passed: 3.001128 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]49 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]50- Total time passed: 4.001363 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]51 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]52- Total time passed: 5.001598 s
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]53DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Timeout of T0
54DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
55DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Close event, cause: CONGESTION
56DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
57DREF IMSI-901700000004620: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]58DREF VLR subscr IMSI-901700000004620 + vlr_subscr_cancel_attach_fsm: now used by 2 (conn,vlr_subscr_cancel_attach_fsm)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]59- sending LU Reject for IMSI-901700000004620, cause 22
60DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_DONE
61DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_CN_CLOSE
62DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Event RAN_CONN_E_CN_CLOSE not permitted
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]63DREF VLR subscr IMSI-901700000004620 - vlr_subscr_cancel_attach_fsm: now used by 1 (conn)
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]64- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]65- RAN_CONN_TIMEOUT has passed, conn is gone.
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]66 bssap_clear_sent == 1
67- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]68DREF IMSI-901700000004620: MSC conn use - release == 0 (0x0: )
69DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
70DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
71DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame]72DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: RAN_conn(IMSI-901700000004620:GERAN-A-0:LU))
73DVLR VLR_Authenticate(IMSI-901700000004620:GERAN-A-0:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Terminating in cascade, depth 3 (cause = OSMO_FSM_TERM_PARENT, caused by: RAN_conn(IMSI-901700000004620:GERAN-A-0:LU))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]74DVLR VLR_Authenticate(IMSI-901700000004620:GERAN-A-0:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame]75DVLR VLR_Authenticate(IMSI-901700000004620:GERAN-A-0:LU){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Deferring: will deallocate with RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]76DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_DONE}: Removing from parent RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
77DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame]78DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_DONE}: Deferring: will deallocate with RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]79DRLL IMSI-901700000004620: Freeing RAN connection
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]80DREF VLR subscr IMSI-901700000004620 - conn: now used by 0 (-)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]81DREF freeing VLR subscr IMSI-901700000004620
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame]82DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Deallocated, including all deferred deallocations
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]83 llist_count(&net->ran_conns) == 0
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]84 lu_result_sent == 2
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]85===== test_hlr_timeout_lu_auth_info: SUCCESS
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]86
87full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]88talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]89
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]90===== test_hlr_timeout_lu_upd_loc_result
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]91- Total time passed: 0.000000 s
92- Location Update request causes a GSUP LU request to HLR
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]93 MSC <--GERAN-A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]94 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]95DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]96DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]97DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]98DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
99DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LOCATION UPDATING REQUEST: MI=IMSI-901700000004620 LU-type=IMSI-ATTACH
100DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LU/new-LAC: 1/23
101DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Allocated
102DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
103DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
104DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]105DREF VLR subscr unknown + _lu_fsm_associate_vsub: now used by 1 (_lu_fsm_associate_vsub)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]106DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
107DVLR New subscr, IMSI: 901700000004620
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]108DREF VLR subscr IMSI-901700000004620 + conn: now used by 2 (_lu_fsm_associate_vsub,conn)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]109DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]110DREF VLR subscr IMSI-901700000004620 - _lu_fsm_associate_vsub: now used by 1 (conn)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]111DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
112DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()
113DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()
114DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()
115DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
116DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Allocated
117DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU)
118DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyrd0756b12018-09-28 02:41:39 +0200[diff] [blame]119DVLR GSUP tx: 04010809710000004026f0280102
120GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]121DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
122DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
123DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
124DREF IMSI-901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
125DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
126DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]127 lu_result_sent == 0
128- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
129<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
130DVLR GSUP rx 17: 10010809710000004026f00804036470f1
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]131DREF VLR subscr IMSI-901700000004620 + vlr_gsupc_read_cb: now used by 2 (conn,vlr_gsupc_read_cb)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]132DVLR IMSI:901700000004620 has MSISDN:46071
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]133DVLR SUBSCR(IMSI-901700000004620:MSISDN-46071) VLR: update for IMSI=901700000004620 (MSISDN=46071)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]134DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Updated ID
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]135DVLR GSUP tx: 12010809710000004026f0
136GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]137DREF VLR subscr IMSI-901700000004620:MSISDN-46071 - vlr_gsupc_read_cb: now used by 1 (conn)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]138<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
139 lu_result_sent == 0
140---
141- HLR never sends GSUP _UPDATE_LOCATION_RESULT
142- At first, we're still waiting
143- Total time passed: 0.000423 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]144 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]145 lu_result_sent == 0
146- Total time passed: 1.000658 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]147 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]148 lu_result_sent == 0
149- Total time passed: 2.000893 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]150 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]151 lu_result_sent == 0
152- Total time passed: 3.001128 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]153 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]154 lu_result_sent == 0
155- Total time passed: 4.001363 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]156 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]157 lu_result_sent == 0
158- Total time passed: 5.001598 s
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]159DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Timeout of T0
160DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
161DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Close event, cause: CONGESTION
162DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
163DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]164DREF VLR subscr IMSI-901700000004620:MSISDN-46071 + vlr_subscr_cancel_attach_fsm: now used by 2 (conn,vlr_subscr_cancel_attach_fsm)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]165- sending LU Reject for IMSI-901700000004620:MSISDN-46071, cause 22
166DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_DONE
167DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_CN_CLOSE
168DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Event RAN_CONN_E_CN_CLOSE not permitted
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]169DREF VLR subscr IMSI-901700000004620:MSISDN-46071 - vlr_subscr_cancel_attach_fsm: now used by 1 (conn)
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]170- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]171- RAN_CONN_TIMEOUT has passed, conn is gone.
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]172 bssap_clear_sent == 1
173- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]174DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - release == 0 (0x0: )
175DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
176DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
177DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame]178DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU))
179DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Terminating in cascade, depth 3 (cause = OSMO_FSM_TERM_PARENT, caused by: RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU))
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]180DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame]181DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Deferring: will deallocate with RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]182DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Removing from parent RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
183DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame]184DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Deferring: will deallocate with RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]185DRLL IMSI-901700000004620:MSISDN-46071: Freeing RAN connection
Neels Hofmeyr7c5346c2019-02-19 02:36:35 +0100[diff] [blame^]186DREF VLR subscr IMSI-901700000004620:MSISDN-46071 - conn: now used by 0 (-)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame]187DREF freeing VLR subscr IMSI-901700000004620:MSISDN-46071
Neels Hofmeyre4f7e712019-03-24 21:07:33 +0100[diff] [blame]188DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Deallocated, including all deferred deallocations
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]189 llist_count(&net->ran_conns) == 0
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]190 lu_result_sent == 2
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]191===== test_hlr_timeout_lu_upd_loc_result: SUCCESS
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]192
193full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]194talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]195
196full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]197talloc_total_blocks(tall_bsc_ctx) == 13
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]198