Gitiles
Code Review Sign In
gerrit.osmocom.org / osmo-msc / c036b79918dbeb2baefff177be51dfdd9dac1588 / . / tests / msc_vlr / msc_vlr_test_ms_timeout.err
blob: bf3a0ed7c66f7784fc84f0c3ec723dcc0a70ef6d [file] [log] [blame]
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]1full talloc report on 'msgb' (total 0 bytes in 1 blocks)
2talloc_total_blocks(tall_bsc_ctx) == 12
3
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]4===== test_ms_timeout_lu_auth_resp
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 Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]7 MSC <--RAN_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 Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]12DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]13DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr8a656eb2017-11-22 02:55:52 +0100[diff] [blame]14DMM LU/new-LAC: 1/23
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]15DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]16DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]17DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
18DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]19DREF VLR subscr unknown usage increases to: 1
20DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
21DVLR New subscr, IMSI: 901700000004620
22DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr54a706c2017-07-18 15:39:27 +0200[diff] [blame]23DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]24DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
25DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
26DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
27DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
28DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]29DVLR GSUP tx: 08010809710000004026f0
30GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]31DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]32DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
33DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]34DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]35DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
36DMM RAN_conn(LU:901700000004620){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]37 lu_result_sent == 0
38- from HLR, rx _SEND_AUTH_INFO_RESULT; VLR sends Auth Req to MS
39<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
40DVLR GSUP rx 47: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
41DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]42DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]43DVLR SUBSCR(IMSI:901700000004620) Received 1 auth tuples
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]44DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP
45DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x1)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]46- sending GSM Auth Request for IMSI:901700000004620: tuple use_count=1 key_seq=0 auth_types=0x1 and...
47- ...rand=585df1ae287f6e273dce07090d61320b
48- ...expecting sres=2d8b2c3e
49DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
50<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0
51 auth_request_sent == 1
52 lu_result_sent == 0
53---
54- MS fails to send an Authentication Response
55- At first, we're still waiting
56- Total time passed: 0.000423 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]57 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]58 lu_result_sent == 0
59- Total time passed: 1.000658 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]60 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]61 lu_result_sent == 0
62- Total time passed: 2.000893 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]63 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]64 lu_result_sent == 0
65- Total time passed: 3.001128 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]66 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]67 lu_result_sent == 0
68- Total time passed: 4.001363 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]69 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]70 lu_result_sent == 0
71- Total time passed: 5.001598 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]72DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Timeout of T0
73DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
74DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Close event, cause: CONGESTION
75DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]76DREF IMSI:901700000004620: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr3bae8362017-11-18 23:26:24 +0100[diff] [blame]77DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]78- sending LU Reject for IMSI:901700000004620, cause 22
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]79DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]80DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_CN_CLOSE
81DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Event RAN_CONN_E_CN_CLOSE not permitted
Neels Hofmeyre3d3dc62018-03-31 00:02:14 +0200[diff] [blame]82DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
83- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]84- RAN_CONN_TIMEOUT has passed, conn is gone.
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]85 bssap_clear_sent == 1
86- BSS sends BSSMAP Clear Complete
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]87DREF IMSI:901700000004620: MSC conn use - release == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]88DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
89DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
90DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]91DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
92DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Terminating (cause = OSMO_FSM_TERM_PARENT)
93DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Removing from parent vlr_lu_fsm(LU:901700000004620)
94DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Freeing instance
95DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]96DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]97DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
98DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
99DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]100DRLL IMSI:901700000004620: Freeing RAN connection
Neels Hofmeyr3bae8362017-11-18 23:26:24 +0100[diff] [blame]101DREF VLR subscr IMSI:901700000004620 usage decreases to: 0
102DREF freeing VLR subscr IMSI:901700000004620
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]103DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
104DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
105 llist_count(&net->ran_conns) == 0
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]106 lu_result_sent == 2
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]107===== test_ms_timeout_lu_auth_resp: SUCCESS
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]108
109full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]110talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]111
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]112===== test_ms_timeout_cm_auth_resp
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]113- Total time passed: 0.000000 s
114- Location Update request causes a GSUP Send Auth Info request to HLR
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]115 MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]116 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]117DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]118DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]119DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]120DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]121DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
Neels Hofmeyr8a656eb2017-11-22 02:55:52 +0100[diff] [blame]122DMM LU/new-LAC: 1/23
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]123DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]124DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]125DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
126DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]127DREF VLR subscr unknown usage increases to: 1
128DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
129DVLR New subscr, IMSI: 901700000004620
130DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyr54a706c2017-07-18 15:39:27 +0200[diff] [blame]131DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]132DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
133DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
134DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
135DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
136DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]137DVLR GSUP tx: 08010809710000004026f0
138GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]139DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]140DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
141DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]142DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]143DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
144DMM RAN_conn(LU:901700000004620){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]145 lu_result_sent == 0
146- from HLR, rx _SEND_AUTH_INFO_RESULT; VLR sends Auth Req to MS
147<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a8000322201012aca96fb4ffdea5c985cbafa9b6e18b210420bde240220807fa7502e07e1c0003222010e7c03ba7cf0e2fde82b2dc4d63077d422104a29514ae2208e2b234f80788640003222010fa8f20b781b5881329d4fea26b1a3c5121045afc8d7222082392f14f709ae000032220100fd4cc8dbe8715d1f439e304edfd68dc2104bc8d1c5b2208da7cdd6bfe2d7000
148DVLR GSUP rx 191: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a8000322201012aca96fb4ffdea5c985cbafa9b6e18b210420bde240220807fa7502e07e1c0003222010e7c03ba7cf0e2fde82b2dc4d63077d422104a29514ae2208e2b234f80788640003222010fa8f20b781b5881329d4fea26b1a3c5121045afc8d7222082392f14f709ae000032220100fd4cc8dbe8715d1f439e304edfd68dc2104bc8d1c5b2208da7cdd6bfe2d7000
149DREF VLR subscr IMSI:901700000004620 usage increases to: 2
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]150DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]151DVLR SUBSCR(IMSI:901700000004620) Received 5 auth tuples
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]152DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP
153DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x1)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]154- sending GSM Auth Request for IMSI:901700000004620: tuple use_count=1 key_seq=0 auth_types=0x1 and...
155- ...rand=585df1ae287f6e273dce07090d61320b
156- ...expecting sres=2d8b2c3e
157DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
158<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0
159 auth_request_sent == 1
160 lu_result_sent == 0
161- MS sends Authen Response, VLR accepts and sends GSUP LU Req to HLR
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]162 MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_AUTH_RESP
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]163DREF IMSI:901700000004620: MSC conn use + dtap == 1 (0x2: dtap)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]164DRLL Dispatching 04.08 message GSM48_MT_MM_AUTH_RESP (0x5:0x14)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]165DMM IMSI:901700000004620: MM GSM AUTHENTICATION RESPONSE (sres = 2d8b2c3e)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]166DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
Neels Hofmeyra9099bc2018-03-10 04:22:50 +0100[diff] [blame]167DVLR SUBSCR(IMSI:901700000004620) AUTH on GERAN received SRES/RES: 2d8b2c3e (4 bytes)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]168DVLR SUBSCR(IMSI:901700000004620) AUTH established GSM security context
Neels Hofmeyr15809592018-04-06 02:57:51 +0200[diff] [blame]169DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]170DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED
171DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
172DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
173DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Freeing instance
174DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Deallocated
175DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
176DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()
177DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()
178DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()
179DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
180DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Allocated
181DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
182DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyrd0756b12018-09-28 02:41:39 +0200[diff] [blame]183DVLR GSUP tx: 04010809710000004026f0280102
184GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]185DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]186DREF IMSI:901700000004620: MSC conn use - dtap == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]187DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
188DMM RAN_conn(LU:901700000004620){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]189 lu_result_sent == 0
190- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
191<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
192DVLR GSUP rx 17: 10010809710000004026f00804036470f1
193DREF VLR subscr IMSI:901700000004620 usage increases to: 2
194DVLR IMSI:901700000004620 has MSISDN:46071
Maxa263bb22017-12-27 13:23:44 +0100[diff] [blame]195DVLR SUBSCR(MSISDN:46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]196DVLR GSUP tx: 12010809710000004026f0
197GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
198DREF VLR subscr MSISDN:46071 usage decreases to: 1
199<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
200 lu_result_sent == 0
201- HLR also sends GSUP _UPDATE_LOCATION_RESULT
202<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
203DVLR GSUP rx 11: 06010809710000004026f0
204DREF VLR subscr MSISDN:46071 usage increases to: 2
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]205DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
206DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
207DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
208DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
209DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
210DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Freeing instance
211DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Deallocated
212DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
213DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
214DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Allocated
215DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
216DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
217DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
218DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Allocated
219DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: is child of lu_compl_vlr_fsm(LU:901700000004620)
220DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Received Event SUB_PRES_VLR_E_START
221DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: state_chg to SUB_PRES_VLR_S_DONE
222DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
223DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Removing from parent lu_compl_vlr_fsm(LU:901700000004620)
224DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Freeing instance
225DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Deallocated
226DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]227- sending LU Accept for MSISDN:46071
228DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]229DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
230DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
231DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
232DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
233DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Freeing instance
234DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Deallocated
235DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]236DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
237DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
238DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
239DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]240DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyre3d3dc62018-03-31 00:02:14 +0200[diff] [blame]241DREF VLR subscr MSISDN:46071 usage increases to: 4
242DREF VLR subscr MSISDN:46071 usage decreases to: 3
243- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]244DREF VLR subscr MSISDN:46071 usage decreases to: 2
245<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
246 bssap_clear_sent == 1
247- LU was successful, and the conn has already been closed
248 lu_result_sent == 1
249- BSS sends BSSMAP Clear Complete
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]250DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]251DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
252DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
253DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]254DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]255DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]256DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
257DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
258DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]259DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]260DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]261DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
262DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
263 llist_count(&net->ran_conns) == 0
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]264---
265- after a while, a new conn sends a CM Service Request. VLR responds with Auth Req, 2nd auth vector
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]266 MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_CM_SERV_REQ
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]267 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]268DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]269DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr78ada642017-03-10 02:15:20 +0100[diff] [blame]270DRLL Dispatching 04.08 message GSM48_MT_MM_CM_SERV_REQ (0x5:0x24)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]271DMM <- CM SERVICE REQUEST serv_type=0x08 MI(IMSI)=901700000004620
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]272DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]273DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_INIT}: Allocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]274DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_INIT}: is child of RAN_conn(CM_SERVICE_REQ:901700000004620)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]275DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_INIT}: rev=GSM net=GERAN Auth (no Ciph)
276DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]277DREF VLR subscr MSISDN:46071 usage increases to: 2
278DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]279DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()
280DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_WAIT_AUTH
281DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
282DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620)
283DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
284DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_WAIT_RESP
285DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=1 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x1)
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]286- sending GSM Auth Request for MSISDN:46071: tuple use_count=1 key_seq=1 auth_types=0x1 and...
287- ...rand=12aca96fb4ffdea5c985cbafa9b6e18b
288- ...expecting sres=20bde240
289DREF VLR subscr MSISDN:46071 usage decreases to: 2
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]290DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
291DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]292DREF MSISDN:46071: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]293DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
294DMM RAN_conn(CM_SERVICE_REQ:901700000004620){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]295 cm_service_result_sent == 0
296 auth_request_sent == 1
297---
298- MS fails to send an Authentication Response
299- At first, we're still waiting
300- Total time passed: 0.000423 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]301 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]302 cm_service_result_sent == 0
303- Total time passed: 1.000658 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]304 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]305 cm_service_result_sent == 0
306- Total time passed: 2.000893 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]307 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]308 cm_service_result_sent == 0
309- Total time passed: 3.001128 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]310 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]311 cm_service_result_sent == 0
312- Total time passed: 4.001363 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]313 llist_count(&net->ran_conns) == 1
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]314 cm_service_result_sent == 0
315- Total time passed: 5.001598 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]316DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_AUTH_CIPH}: Timeout of T0
317DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_CN_CLOSE
318DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_AUTH_CIPH}: Close event, cause: CONGESTION
319DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]320DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyr3bae8362017-11-18 23:26:24 +0100[diff] [blame]321DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyr15809592018-04-06 02:57:51 +0200[diff] [blame]322DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_WAIT_AUTH}: Cancel: OSMO_FSM_TERM_ERROR
323DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_WAIT_AUTH}: proc_arq_fsm_done(CONGESTION)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]324DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_WAIT_AUTH}: state_chg to PR_ARQ_S_DONE
Neels Hofmeyr15809592018-04-06 02:57:51 +0200[diff] [blame]325DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_DONE}: Process Access Request result: CONGESTION
326- sending CM Service Reject for MSISDN:46071, cause: CONGESTION
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]327DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_CN_CLOSE
328DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_RELEASING}: Event RAN_CONN_E_CN_CLOSE not permitted
Neels Hofmeyre3d3dc62018-03-31 00:02:14 +0200[diff] [blame]329DREF VLR subscr MSISDN:46071 usage decreases to: 2
330- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]331- RAN_CONN_TIMEOUT has passed, conn is gone.
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]332 bssap_clear_sent == 1
333- BSS sends BSSMAP Clear Complete
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]334DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]335DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
336DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
337DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]338DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
339DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_WAIT_RESP}: Terminating (cause = OSMO_FSM_TERM_PARENT)
340DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_WAIT_RESP}: Removing from parent Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620)
341DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_WAIT_RESP}: Freeing instance
342DVLR VLR_Authenticate(CM_SERVICE_REQ:901700000004620){VLR_SUB_AS_WAIT_RESP}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]343DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_DONE}: Removing from parent RAN_conn(CM_SERVICE_REQ:901700000004620)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]344DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_DONE}: Freeing instance
345DVLR Process_Access_Request_VLR(CM_SERVICE_REQ:901700000004620){PR_ARQ_S_DONE}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]346DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyre3d3dc62018-03-31 00:02:14 +0200[diff] [blame]347DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]348DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
349DMM RAN_conn(CM_SERVICE_REQ:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
350 llist_count(&net->ran_conns) == 0
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]351 cm_service_result_sent == 2
352DREF freeing VLR subscr MSISDN:46071
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]353===== test_ms_timeout_cm_auth_resp: SUCCESS
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]354
355full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]356talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]357
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]358===== test_ms_timeout_paging
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]359- Total time passed: 0.000000 s
360- Location Update request causes a GSUP LU request to HLR
361 MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
362 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]363DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]364DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]365DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]366DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]367DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
368DMM LU/new-LAC: 1/23
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]369DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]370DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]371DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
372DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]373DREF VLR subscr unknown usage increases to: 1
374DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
375DVLR New subscr, IMSI: 901700000004620
376DREF VLR subscr IMSI:901700000004620 usage increases to: 2
377DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]378DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
379DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()
380DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()
381DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()
382DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
383DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Allocated
384DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
385DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
Neels Hofmeyrd0756b12018-09-28 02:41:39 +0200[diff] [blame]386DVLR GSUP tx: 04010809710000004026f0280102
387GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]388DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_INIT}: state_chg to UPD_HLR_VLR_S_WAIT_FOR_DATA
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]389DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
390DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]391DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]392DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
393DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]394 lu_result_sent == 0
395- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
396<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
397DVLR GSUP rx 17: 10010809710000004026f00804036470f1
398DREF VLR subscr IMSI:901700000004620 usage increases to: 2
399DVLR IMSI:901700000004620 has MSISDN:46071
Maxa263bb22017-12-27 13:23:44 +0100[diff] [blame]400DVLR SUBSCR(MSISDN:46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]401DVLR GSUP tx: 12010809710000004026f0
402GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
403DREF VLR subscr MSISDN:46071 usage decreases to: 1
404<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
405 lu_result_sent == 0
406- HLR also sends GSUP _UPDATE_LOCATION_RESULT
407<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
408DVLR GSUP rx 11: 06010809710000004026f0
409DREF VLR subscr MSISDN:46071 usage increases to: 2
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]410DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
411DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
412DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
413DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
414DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
415DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Freeing instance
416DVLR upd_hlr_vlr_fsm(LU:901700000004620){UPD_HLR_VLR_S_DONE}: Deallocated
417DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
418DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
419DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Allocated
420DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(LU:901700000004620)
421DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
422DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
423DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Allocated
424DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: is child of lu_compl_vlr_fsm(LU:901700000004620)
425DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: Received Event SUB_PRES_VLR_E_START
426DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_INIT}: state_chg to SUB_PRES_VLR_S_DONE
427DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
428DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Removing from parent lu_compl_vlr_fsm(LU:901700000004620)
429DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Freeing instance
430DVLR sub_pres_vlr_fsm(LU:901700000004620){SUB_PRES_VLR_S_DONE}: Deallocated
431DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]432- sending LU Accept for MSISDN:46071
433DREF VLR subscr MSISDN:46071 usage increases to: 3
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]434DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
435DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
436DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
437DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(LU:901700000004620)
438DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Freeing instance
439DVLR lu_compl_vlr_fsm(LU:901700000004620){LU_COMPL_VLR_S_DONE}: Deallocated
440DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]441DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
442DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
443DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
444DMM RAN_conn(LU:901700000004620){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]445DREF MSISDN:46071: MSC conn use + release == 1 (0x100: release)
Neels Hofmeyre3d3dc62018-03-31 00:02:14 +0200[diff] [blame]446DREF VLR subscr MSISDN:46071 usage increases to: 4
447DREF VLR subscr MSISDN:46071 usage decreases to: 3
448- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]449DREF VLR subscr MSISDN:46071 usage decreases to: 2
450<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
451- LU was successful, and the conn has already been closed
452 lu_result_sent == 1
453 bssap_clear_sent == 1
454- BSS sends BSSMAP Clear Complete
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]455DREF MSISDN:46071: MSC conn use - release == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]456DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
457DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
458DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]459DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]460DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyrfe4ba7c2018-04-02 23:17:50 +0200[diff] [blame]461DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
462DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
463DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]464DRLL MSISDN:46071: Freeing RAN connection
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]465DREF VLR subscr MSISDN:46071 usage decreases to: 1
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]466DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
467DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
468 llist_count(&net->ran_conns) == 0
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]469---
470- an SMS is sent, MS is paged
471DREF VLR subscr MSISDN:46071 usage increases to: 2
472 llist_count(&vsub->cs.requests) == 0
473DREF VLR subscr MSISDN:46071 usage increases to: 3
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]474DCC (ti 00 sub MSISDN:46071 callref 40000001) New transaction
475DREF VLR subscr MSISDN:46071 usage increases to: 4
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]476DMM Subscriber MSISDN:46071 not paged yet, start paging.
477 RAN_GERAN_A sends out paging request to IMSI 901700000004620, TMSI 0xffffffff, LAC 23
478 strcmp(paging_expecting_imsi, imsi) == 0
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]479DREF VLR subscr MSISDN:46071 usage increases to: 5
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]480 llist_count(&vsub->cs.requests) == 1
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]481DREF VLR subscr MSISDN:46071 usage decreases to: 4
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]482 paging_sent == 1
483 paging_stopped == 0
484- time passes and no paging result is received
485- Total time passed: 9.000000 s
486- the paging timeout has not yet expired
487 paging_stopped == 0
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]488DREF VLR subscr MSISDN:46071 usage increases to: 5
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]489 vsub->cs.is_paging == 1
490- another request is added to the list but does not cause another paging
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]491DREF VLR subscr MSISDN:46071 usage increases to: 6
492DCC (ti 01 sub MSISDN:46071 callref 40000002) New transaction
493DREF VLR subscr MSISDN:46071 usage increases to: 7
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]494DMM Subscriber MSISDN:46071 already paged.
495 llist_count(&vsub->cs.requests) == 2
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]496DREF VLR subscr MSISDN:46071 usage decreases to: 6
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]497 paging_sent == 0
498- the paging timeout expires, the paging as well as the requests are canceled
499- Total time passed: 11.000000 s
500DPAG Paging failure for MSISDN:46071 (event=1)
501DPAG Calling paging cbfn.
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]502DREF VLR subscr MSISDN:46071 usage decreases to: 5
503DREF VLR subscr MSISDN:46071 usage decreases to: 4
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]504DPAG Calling paging cbfn.
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]505DREF VLR subscr MSISDN:46071 usage decreases to: 3
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]506DREF VLR subscr MSISDN:46071 usage decreases to: 2
507DREF VLR subscr MSISDN:46071 usage decreases to: 1
508 paging_stopped == 1
509DREF VLR subscr MSISDN:46071 usage increases to: 2
510 vsub->cs.is_paging == 0
511 llist_count(&vsub->cs.requests) == 0
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]512---
Neels Hofmeyrfacd57a2017-12-15 03:48:48 +0100[diff] [blame]513- Now that the timeout has expired, another Paging is sent on request
514DREF VLR subscr MSISDN:46071 usage increases to: 3
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]515DCC (ti 00 sub MSISDN:46071 callref 40000003) New transaction
516DREF VLR subscr MSISDN:46071 usage increases to: 4
Neels Hofmeyrfacd57a2017-12-15 03:48:48 +0100[diff] [blame]517DMM Subscriber MSISDN:46071 not paged yet, start paging.
518 RAN_GERAN_A sends out paging request to IMSI 901700000004620, TMSI 0xffffffff, LAC 23
519 strcmp(paging_expecting_imsi, imsi) == 0
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]520DREF VLR subscr MSISDN:46071 usage increases to: 5
Neels Hofmeyrfacd57a2017-12-15 03:48:48 +0100[diff] [blame]521 llist_count(&vsub->cs.requests) == 1
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]522DREF VLR subscr MSISDN:46071 usage decreases to: 4
Neels Hofmeyrfacd57a2017-12-15 03:48:48 +0100[diff] [blame]523 paging_sent == 1
524 paging_stopped == 0
525---
526- subscriber detaches, pagings are canceled
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]527 MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_IMSI_DETACH_IND
528 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]529DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]530DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]531DRLL Dispatching 04.08 message GSM48_MT_MM_IMSI_DETACH_IND (0x5:0x1)
532DMM IMSI DETACH INDICATION: MI(IMSI)=901700000004620
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]533DREF VLR subscr MSISDN:46071 usage increases to: 5
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]534DMM IMSI DETACH for MSISDN:46071
Neels Hofmeyrfacd57a2017-12-15 03:48:48 +0100[diff] [blame]535DPAG Paging failure for MSISDN:46071 (event=1)
536DPAG Calling paging cbfn.
Vadim Yanitskiy24e025e2018-11-22 15:42:39 +0700[diff] [blame]537DREF VLR subscr MSISDN:46071 usage decreases to: 4
Neels Hofmeyrfacd57a2017-12-15 03:48:48 +0100[diff] [blame]538DREF VLR subscr MSISDN:46071 usage decreases to: 3
539DREF VLR subscr MSISDN:46071 usage decreases to: 2
Neels Hofmeyr15809592018-04-06 02:57:51 +0200[diff] [blame]540DREF VLR subscr MSISDN:46071 usage increases to: 3
541DREF VLR subscr MSISDN:46071 usage decreases to: 2
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]542DREF VLR subscr MSISDN:46071 usage decreases to: 1
543DREF VLR subscr MSISDN:46071 usage decreases to: 0
544DREF freeing VLR subscr MSISDN:46071
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]545DMM RAN_conn{RAN_CONN_S_NEW}: Received Event RAN_CONN_E_CN_CLOSE
546DMM RAN_conn{RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]547DREF unknown: MSC conn use + release == 2 (0x101: compl_l3,release)
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]548- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]549DREF unknown: MSC conn use - compl_l3 == 1 (0x100: release)
Neels Hofmeyr4068ab22018-04-01 20:55:54 +0200[diff] [blame]550 bssap_clear_sent == 1
551 paging_stopped == 1
552- BSS sends BSSMAP Clear Complete
Neels Hofmeyr99a8d232018-04-09 20:44:56 +0200[diff] [blame]553DREF unknown: MSC conn use - release == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]554DMM RAN_conn{RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
555DMM RAN_conn{RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
556DMM RAN_conn{RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
557DRLL Freeing RAN connection with NULL subscriber
558DMM RAN_conn{RAN_CONN_S_RELEASED}: Freeing instance
559DMM RAN_conn{RAN_CONN_S_RELEASED}: Deallocated
560 llist_count(&net->ran_conns) == 0
Neels Hofmeyrdfdc61d2018-03-02 00:40:58 +0100[diff] [blame]561===== test_ms_timeout_paging: SUCCESS
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]562
563full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]564talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr2ff5bcd2017-12-15 03:02:27 +0100[diff] [blame]565
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]566===== test_classmark_update_timeout
567- Total time passed: 0.000000 s
568- Location Update request causes a GSUP Send Auth Info request to HLR
569 MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
570 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]571DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]572DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
573DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]574DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Updated ID
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]575DMM LOCATION UPDATING REQUEST: MI(IMSI)=901700000004620 type=IMSI ATTACH
576DMM LU/new-LAC: 1/23
577DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Allocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]578DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: is child of RAN_conn(LU:901700000004620)
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]579DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth+Ciph
580DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
581DREF VLR subscr unknown usage increases to: 1
582DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
583DVLR New subscr, IMSI: 901700000004620
584DREF VLR subscr IMSI:901700000004620 usage increases to: 2
585DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
586DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
587DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_AUTH
588DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Allocated
589DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(LU:901700000004620)
590DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
591DVLR GSUP tx: 08010809710000004026f0
592GSUP --> HLR: OSMO_GSUP_MSGT_SEND_AUTH_INFO_REQUEST: 08010809710000004026f0
593DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH}: state_chg to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]594DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
595DMM RAN_conn(LU:901700000004620){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]596DREF IMSI:901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]597DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
598DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]599 lu_result_sent == 0
600- from HLR, rx _SEND_AUTH_INFO_RESULT; VLR sends Auth Req to MS
601<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
602DVLR GSUP rx 47: 0a010809710000004026f003222010585df1ae287f6e273dce07090d61320b21042d8b2c3e220861855fb81fc2a800
603DREF VLR subscr IMSI:901700000004620 usage increases to: 2
604DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
605DVLR SUBSCR(IMSI:901700000004620) Received 1 auth tuples
606DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: state_chg to VLR_SUB_AS_WAIT_RESP
607DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x1)
608- sending GSM Auth Request for IMSI:901700000004620: tuple use_count=1 key_seq=0 auth_types=0x1 and...
609- ...rand=585df1ae287f6e273dce07090d61320b
610- ...expecting sres=2d8b2c3e
611DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
612<-- GSUP rx OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT: vlr_gsupc_read_cb() returns 0
613 lu_result_sent == 0
614 auth_request_sent == 1
615---
616- MS sends Authen Response, VLR accepts and wants to send Ciphering Mode Command to MS -- but needs Classmark 2 to determine whether A5/3 is supported
617 MSC <--RAN_GERAN_A-- MS: GSM48_MT_MM_AUTH_RESP
618DREF IMSI:901700000004620: MSC conn use + dtap == 1 (0x2: dtap)
619DRLL Dispatching 04.08 message GSM48_MT_MM_AUTH_RESP (0x5:0x14)
620DMM IMSI:901700000004620: MM GSM AUTHENTICATION RESPONSE (sres = 2d8b2c3e)
621DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
622DVLR SUBSCR(IMSI:901700000004620) AUTH on GERAN received SRES/RES: 2d8b2c3e (4 bytes)
623DVLR SUBSCR(IMSI:901700000004620) AUTH established GSM security context
624DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
625DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_WAIT_RESP}: state_chg to VLR_SUB_AS_AUTHENTICATED
626DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
627DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(LU:901700000004620)
628DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Freeing instance
629DVLR VLR_Authenticate(LU:901700000004620){VLR_SUB_AS_AUTHENTICATED}: Deallocated
630DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
631DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()
632DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: Set Ciphering Mode
633DMM IMSI:901700000004620: to determine whether A5/3 is supported, first ask for a Classmark Update to obtain Classmark 2
634 BSC <--BSSAP-BSS-MANAGEMENT-- MSC: CLASSMARK REQ [L3]> 00 01 58
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]635DMM RAN_conn(LU:901700000004620){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_WAIT_CLASSMARK_UPDATE
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]636DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_AUTH}: state_chg to VLR_ULA_S_WAIT_CIPH
637DREF IMSI:901700000004620: MSC conn use - dtap == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]638DMM RAN_conn(LU:901700000004620){RAN_CONN_S_WAIT_CLASSMARK_UPDATE}: Received Event RAN_CONN_E_UNUSED
639DMM RAN_conn(LU:901700000004620){RAN_CONN_S_WAIT_CLASSMARK_UPDATE}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]640 lu_result_sent == 0
641---
642- But the BSSMAP Classmark Update never arrives
643- At first, we're still waiting
644- Total time passed: 0.000423 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]645 llist_count(&net->ran_conns) == 1
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]646 lu_result_sent == 0
647- Total time passed: 1.000658 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]648 llist_count(&net->ran_conns) == 1
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]649 lu_result_sent == 0
650- Total time passed: 2.000893 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]651 llist_count(&net->ran_conns) == 1
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]652 lu_result_sent == 0
653- Total time passed: 3.001128 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]654 llist_count(&net->ran_conns) == 1
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]655 lu_result_sent == 0
656- Total time passed: 4.001363 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]657 llist_count(&net->ran_conns) == 1
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]658 lu_result_sent == 0
659- Total time passed: 5.001598 s
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]660DMM RAN_conn(LU:901700000004620){RAN_CONN_S_WAIT_CLASSMARK_UPDATE}: Timeout of T0
661DMM RAN_conn(LU:901700000004620){RAN_CONN_S_WAIT_CLASSMARK_UPDATE}: Received Event RAN_CONN_E_CN_CLOSE
662DMM RAN_conn(LU:901700000004620){RAN_CONN_S_WAIT_CLASSMARK_UPDATE}: Close event, cause: CONGESTION
663DMM RAN_conn(LU:901700000004620){RAN_CONN_S_WAIT_CLASSMARK_UPDATE}: state_chg to RAN_CONN_S_RELEASING
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]664DREF IMSI:901700000004620: MSC conn use + release == 1 (0x100: release)
665DREF VLR subscr IMSI:901700000004620 usage increases to: 2
666- sending LU Reject for IMSI:901700000004620, cause 22
667DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_WAIT_CIPH}: state_chg to VLR_ULA_S_DONE
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]668DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_CN_CLOSE
669DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Event RAN_CONN_E_CN_CLOSE not permitted
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]670DREF VLR subscr IMSI:901700000004620 usage decreases to: 1
671- BSSAP Clear --RAN_GERAN_A--> MS
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]672- RAN_CONN_TIMEOUT has passed, conn is gone.
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]673 bssap_clear_sent == 1
674- BSS sends BSSMAP Clear Complete
675DREF IMSI:901700000004620: MSC conn use - release == 0 (0x0: )
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]676DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
677DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
678DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]679DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]680DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Removing from parent RAN_conn(LU:901700000004620)
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]681DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
682DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Freeing instance
683DVLR vlr_lu_fsm(LU:901700000004620){VLR_ULA_S_DONE}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]684DRLL IMSI:901700000004620: Freeing RAN connection
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]685DREF VLR subscr IMSI:901700000004620 usage decreases to: 0
686DREF freeing VLR subscr IMSI:901700000004620
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame^]687DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Freeing instance
688DMM RAN_conn(LU:901700000004620){RAN_CONN_S_RELEASED}: Deallocated
689 llist_count(&net->ran_conns) == 0
Neels Hofmeyr3117b702018-09-13 03:23:07 +0200[diff] [blame]690 lu_result_sent == 2
691===== test_classmark_update_timeout: SUCCESS
692
693full talloc report on 'msgb' (total 0 bytes in 1 blocks)
694talloc_total_blocks(tall_bsc_ctx) == 12
695
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]696full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Neels Hofmeyr08b38282018-03-30 23:04:04 +0200[diff] [blame]697talloc_total_blocks(tall_bsc_ctx) == 12
Neels Hofmeyr6a29d322017-01-25 15:04:16 +0100[diff] [blame]698