Gitiles
Code Review Sign In
gerrit.osmocom.org / osmo-msc / 361e5718150064452c56d7fa15a3dbef3a29415e / . / tests / msc_vlr / msc_vlr_test_ss.err
blob: a801f9e0a04fb7f9556699948fd274403080cabd [file] [log] [blame]
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[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
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]3
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]4===== test_ss_ussd_mo_geran
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]5- Location Update request causes a GSUP LU request to HLR
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]6 MSC <--GERAN-A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]7 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]8DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]9DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
10DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]11DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
12DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LOCATION UPDATING REQUEST: MI=IMSI-901700000004620 LU-type=IMSI-ATTACH
13DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LU/new-LAC: 1/23
14DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Allocated
15DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
16DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
17DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]18DREF VLR subscr unknown usage increases to: 1
19DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
20DVLR New subscr, IMSI: 901700000004620
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]21DREF VLR subscr IMSI-901700000004620 usage increases to: 2
22DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
23DREF VLR subscr IMSI-901700000004620 usage decreases to: 1
24DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
25DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()
26DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()
27DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()
28DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
29DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Allocated
30DVLR 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)
31DVLR 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]32DVLR GSUP tx: 04010809710000004026f0280102
33GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]34DVLR 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
35DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
36DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
37DREF IMSI-901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
38DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
39DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]40 lu_result_sent == 0
41- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
42<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
43DVLR GSUP rx 17: 10010809710000004026f00804036470f1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]44DREF VLR subscr IMSI-901700000004620 usage increases to: 2
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]45DVLR IMSI:901700000004620 has MSISDN:46071
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]46DVLR SUBSCR(IMSI-901700000004620:MSISDN-46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
47DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Updated ID
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]48DVLR GSUP tx: 12010809710000004026f0
49GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]50DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]51<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
52 lu_result_sent == 0
53- HLR also sends GSUP _UPDATE_LOCATION_RESULT
54<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
55DVLR GSUP rx 11: 06010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]56DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 2
57DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
58DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
59DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
60DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
61DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
62DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Freeing instance
63DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Deallocated
64DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
65DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
66DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Allocated
67DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
68DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
69DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
70DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
71- sending LU Accept for IMSI-901700000004620:MSISDN-46071
72DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
73DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
74DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
75DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
76DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
77DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Freeing instance
78DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Deallocated
79DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
80DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
81DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
82DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
83DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
84DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + release == 1 (0x100: release)
85DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
86DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]87- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]88DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]89<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
90- LU was successful, and the conn has already been closed
91 lu_result_sent == 1
92 bssap_clear_sent == 1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]93DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]94 vsub != NULL == 1
95 strcmp(vsub->imsi, IMSI) == 0
Max33b6d0c2018-12-17 17:15:13 +0100[diff] [blame]96 LAC == 23
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]97DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]98- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]99DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - release == 0 (0x0: )
100DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
101DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
102DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
103DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
104DVLR 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)
105DVLR 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
106DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Freeing instance
107DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Deallocated
108DRLL IMSI-901700000004620:MSISDN-46071: Freeing RAN connection
109DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
110DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Freeing instance
111DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]112 llist_count(&net->ran_conns) == 0
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]113---
114- after a while, a new conn sends a CM Service Request
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]115 MSC <--GERAN-A-- MS: GSM48_MT_MM_CM_SERV_REQ
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]116 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]117DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]118DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
119DRLL Dispatching 04.08 message GSM48_MT_MM_CM_SERV_REQ (0x5:0x24)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]120DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: Updated ID
121DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: Rx CM SERVICE REQUEST cm_service_type=0x08
122DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: Allocated
123DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ)
124DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: rev=GSM net=GERAN (no Auth)
125DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
126DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 2
127DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
128DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: Updated ID
129DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()
130DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: _proc_arq_vlr_node2()
131DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_ciph()
132DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_vlr()
133DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: _proc_arq_vlr_post_pres()
134DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: _proc_arq_vlr_post_trace()
135DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: _proc_arq_vlr_post_imei()
136DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: proc_arq_fsm_done(PASSED)
137DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_DONE
138DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Process Access Request result: PASSED
139- sending CM Service Accept for IMSI-901700000004620:MSISDN-46071
140DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_ACCEPTED
141DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + cm_service == 2 (0x9: compl_l3,cm_service)
142DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_ACCEPTED
143DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_ACCEPTED}: ran_conn_fsm_has_active_transactions: still awaiting first request after a CM Service Request
144DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
145DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
146DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - compl_l3 == 1 (0x8: cm_service)
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]147 cm_service_result_sent == 1
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]148ran_conn_is_accepted() == true
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]149 MSC <--GERAN-A-- MS: GSM0480_MTYPE_REGISTER
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]150DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + dtap == 2 (0xa: dtap,cm_service)
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]151DRLL Dispatching 04.08 message GSM0480_MTYPE_REGISTER (0xb:0x3b)
152DMM Received SS/USSD data (trans_id=8, msg_type=GSM0480_MTYPE_REGISTER)
153DMM -> (new transaction)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]154DCC (ti 08 sub IMSI-901700000004620:MSISDN-46071 callref 20000001) New transaction
155DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
156DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + trans_nc_ss == 3 (0x4a: dtap,cm_service,trans_nc_ss)
157DMM IMSI-901700000004620:MSISDN-46071: rx msg GSM0480_MTYPE_REGISTER: received_cm_service_request changes to false
158DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - cm_service == 2 (0x42: dtap,trans_nc_ss)
Vadim Yanitskiy8a6ef552018-06-12 08:21:20 +0700[diff] [blame]159GSUP --> HLR: OSMO_GSUP_MSGT_PROC_SS_REQUEST: 20010809710000004026f03004200000013101013515a11302010102013b300b04010f0406aa510c061b01
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]160DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_COMMUNICATING
161DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_COMMUNICATING
162DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - dtap == 1 (0x40: trans_nc_ss)
Vadim Yanitskiy8a6ef552018-06-12 08:21:20 +0700[diff] [blame]163<-- GSUP rx OSMO_GSUP_MSGT_PROC_SS_REQUEST: 20010809710000004026f03004200000013101033527a225020101302002013b301b04010f0416d9775d0e2ae3e965f73cfd7683d27310cd06bbc51a0d
164DVLR GSUP rx 61: 20010809710000004026f03004200000013101033527a225020101302002013b301b04010f0416d9775d0e2ae3e965f73cfd7683d27310cd06bbc51a0d
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]165DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
Vadim Yanitskiy8a6ef552018-06-12 08:21:20 +0700[diff] [blame]166DMSC Routed to GSM 09.11 SS/USSD handler
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]167DMSC msc_tx 43 bytes to IMSI-901700000004620:MSISDN-46071 via GERAN-A
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]168- DTAP --GERAN-A--> MS: GSM0480_MTYPE_RELEASE_COMPLETE: 8b2a1c27a225020101302002013b301b04010f0416d9775d0e2ae3e965f73cfd7683d27310cd06bbc51a0d
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]169- DTAP matches expected message
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]170DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
171DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - trans_nc_ss == 0 (0x0: )
172DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_COMMUNICATING}: Received Event RAN_CONN_E_UNUSED
173DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_COMMUNICATING}: state_chg to RAN_CONN_S_RELEASING
174DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + release == 1 (0x100: release)
175DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
176DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]177- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]178DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Vadim Yanitskiy8a6ef552018-06-12 08:21:20 +0700[diff] [blame]179<-- GSUP rx OSMO_GSUP_MSGT_PROC_SS_REQUEST: vlr_gsupc_read_cb() returns 0
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]180 dtap_tx_confirmed == 1
181 bssap_clear_sent == 1
182- all requests serviced, conn has been released
183- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]184DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - release == 0 (0x0: )
185DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
186DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
187DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
188DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
189DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Removing from parent RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ)
190DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Freeing instance
191DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){PR_ARQ_S_DONE}: Deallocated
192DRLL IMSI-901700000004620:MSISDN-46071: Freeing RAN connection
193DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
194DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASED}: Freeing instance
195DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:CM_SERVICE_REQ){RAN_CONN_S_RELEASED}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]196 llist_count(&net->ran_conns) == 0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]197DREF freeing VLR subscr IMSI-901700000004620:MSISDN-46071
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]198===== test_ss_ussd_mo_geran: SUCCESS
199
200full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]201talloc_total_blocks(tall_bsc_ctx) == 13
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]202
203===== test_ss_ussd_no_geran
204- Location Update request causes a GSUP LU request to HLR
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]205 MSC <--GERAN-A-- MS: GSM48_MT_MM_LOC_UPD_REQUEST
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]206 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]207DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]208DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
209DRLL Dispatching 04.08 message GSM48_MT_MM_LOC_UPD_REQUEST (0x5:0x8)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]210DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
211DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LOCATION UPDATING REQUEST: MI=IMSI-901700000004620 LU-type=IMSI-ATTACH
212DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: LU/new-LAC: 1/23
213DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Allocated
214DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:LU)
215DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: rev=GSM net=GERAN (no Auth)
216DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]217DREF VLR subscr unknown usage increases to: 1
218DVLR set IMSI on subscriber; IMSI=901700000004620 id=901700000004620
219DVLR New subscr, IMSI: 901700000004620
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]220DREF VLR subscr IMSI-901700000004620 usage increases to: 2
221DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Updated ID
222DREF VLR subscr IMSI-901700000004620 usage decreases to: 1
223DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
224DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_auth()
225DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_post_ciph()
226DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: vlr_loc_upd_node_4()
227DVLR vlr_lu_fsm(IMSI-901700000004620:GERAN-A-0:LU){VLR_ULA_S_IDLE}: state_chg to VLR_ULA_S_WAIT_HLR_UPD
228DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_INIT}: Allocated
229DVLR 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)
230DVLR 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]231DVLR GSUP tx: 04010809710000004026f0280102
232GSUP --> HLR: OSMO_GSUP_MSGT_UPDATE_LOCATION_REQUEST: 04010809710000004026f0280102
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]233DVLR 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
234DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
235DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_AUTH_CIPH
236DREF IMSI-901700000004620: MSC conn use - compl_l3 == 0 (0x0: )
237DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_UNUSED
238DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Awaiting results for Auth+Ciph, overruling event RAN_CONN_E_UNUSED
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]239 lu_result_sent == 0
240- HLR sends _INSERT_DATA_REQUEST, VLR responds with _INSERT_DATA_RESULT
241<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: 10010809710000004026f00804036470f1
242DVLR GSUP rx 17: 10010809710000004026f00804036470f1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]243DREF VLR subscr IMSI-901700000004620 usage increases to: 2
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]244DVLR IMSI:901700000004620 has MSISDN:46071
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]245DVLR SUBSCR(IMSI-901700000004620:MSISDN-46071) VLR: update for IMSI=901700000004620 (MSISDN=46071, used=2)
246DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Updated ID
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]247DVLR GSUP tx: 12010809710000004026f0
248GSUP --> HLR: OSMO_GSUP_MSGT_INSERT_DATA_RESULT: 12010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]249DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]250<-- GSUP rx OSMO_GSUP_MSGT_INSERT_DATA_REQUEST: vlr_gsupc_read_cb() returns 0
251 lu_result_sent == 0
252- HLR also sends GSUP _UPDATE_LOCATION_RESULT
253<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: 06010809710000004026f0
254DVLR GSUP rx 11: 06010809710000004026f0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]255DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 2
256DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
257DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
258DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_WAIT_FOR_DATA}: state_chg to UPD_HLR_VLR_S_DONE
259DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
260DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
261DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Freeing instance
262DVLR upd_hlr_vlr_fsm(IMSI-901700000004620:GERAN-A-0:LU){UPD_HLR_VLR_S_DONE}: Deallocated
263DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
264DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_HLR_UPD}: state_chg to VLR_ULA_S_WAIT_LU_COMPL
265DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Allocated
266DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
267DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
268DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_INIT}: state_chg to LU_COMPL_VLR_S_WAIT_SUB_PRES
269DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
270- sending LU Accept for IMSI-901700000004620:MSISDN-46071
271DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
272DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_WAIT_SUB_PRES}: state_chg to LU_COMPL_VLR_S_DONE
273DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
274DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
275DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU)
276DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Freeing instance
277DVLR lu_compl_vlr_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){LU_COMPL_VLR_S_DONE}: Deallocated
278DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_WAIT_LU_COMPL}: state_chg to VLR_ULA_S_DONE
279DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: Received Event RAN_CONN_E_ACCEPTED
280DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_AUTH_CIPH}: state_chg to RAN_CONN_S_ACCEPTED
281DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_UNUSED
282DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_RELEASING
283DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + release == 1 (0x100: release)
284DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
285DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]286- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]287DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]288<-- GSUP rx OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT: vlr_gsupc_read_cb() returns 0
289- LU was successful, and the conn has already been closed
290 lu_result_sent == 1
291 bssap_clear_sent == 1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]292DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]293 vsub != NULL == 1
294 strcmp(vsub->imsi, IMSI) == 0
Max33b6d0c2018-12-17 17:15:13 +0100[diff] [blame]295 LAC == 23
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]296DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]297- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]298DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - release == 0 (0x0: )
299DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
300DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
301DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
302DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
303DVLR 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)
304DVLR 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
305DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Freeing instance
306DVLR vlr_lu_fsm(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){VLR_ULA_S_DONE}: Deallocated
307DRLL IMSI-901700000004620:MSISDN-46071: Freeing RAN connection
308DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
309DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Freeing instance
310DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:LU){RAN_CONN_S_RELEASED}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]311 llist_count(&net->ran_conns) == 0
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]312---
313- after a while, HLR initiates SS/USSD session
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]314DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 2
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]315 llist_count(&vsub->cs.requests) == 0
316<-- GSUP rx OSMO_GSUP_MSGT_PROC_SS_REQUEST: 20010809710000004026f03004200001013101013515a11302010102013b300b04010f0406aa510c061b01
317DVLR GSUP rx 43: 20010809710000004026f03004200001013101013515a11302010102013b300b04010f0406aa510c061b01
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]318DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 3
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]319DMSC Routed to GSM 09.11 SS/USSD handler
320DMM Establishing network-originated session
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]321DCC (ti ff sub IMSI-901700000004620:MSISDN-46071 callref 20000101) New transaction
322DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]323DMM Triggering Paging Request
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]324DMM Subscriber IMSI-901700000004620:MSISDN-46071 not paged yet, start paging.
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]325 GERAN-A sends out paging request to IMSI 901700000004620, TMSI 0xffffffff, LAC 23
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]326 strcmp(paging_expecting_imsi, imsi) == 0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]327DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 5
328DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 4
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]329<-- GSUP rx OSMO_GSUP_MSGT_PROC_SS_REQUEST: vlr_gsupc_read_cb() returns -22
330 llist_count(&vsub->cs.requests) == 1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]331DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]332 paging_sent == 1
333 paging_stopped == 0
334- the subscriber and its pending request should remain
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]335DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]336 llist_count(&vsub->cs.requests) == 1
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]337DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]338- MS replies with Paging Response, we deliver the NC/USSD
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]339 MSC <--GERAN-A-- MS: GSM48_MT_RR_PAG_RESP
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]340 new conn
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]341DMM RAN_conn{RAN_CONN_S_NEW}: Allocated
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]342DREF unknown: MSC conn use + compl_l3 == 1 (0x1: compl_l3)
343DRLL Dispatching 04.08 message GSM48_MT_RR_PAG_RESP (0x6:0x27)
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]344DMM RAN_conn(IMSI-901700000004620:GERAN-A-0:PAGING_RESP){RAN_CONN_S_NEW}: Updated ID
345DRR RAN_conn(IMSI-901700000004620:GERAN-A-0:PAGING_RESP){RAN_CONN_S_NEW}: PAGING RESPONSE
346DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: Allocated
347DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: is child of RAN_conn(IMSI-901700000004620:GERAN-A-0:PAGING_RESP)
348DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: rev=GSM net=GERAN (no Auth)
349DVLR Process_Access_Request_VLR(IMSI-901700000004620:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
350DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
351DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 5
352DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_NEW}: Updated ID
353DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()
354DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: _proc_arq_vlr_node2()
355DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_ciph()
356DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: _proc_arq_vlr_node2_post_vlr()
357DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: _proc_arq_vlr_post_pres()
358DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: _proc_arq_vlr_post_trace()
359DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: _proc_arq_vlr_post_imei()
360DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: proc_arq_fsm_done(PASSED)
361DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_INIT}: state_chg to PR_ARQ_S_DONE
362DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_DONE}: Process Access Request result: PASSED
363DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_NEW}: Received Event RAN_CONN_E_ACCEPTED
364DPAG Paging success for IMSI-901700000004620:MSISDN-46071 (event=0)
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]365DPAG Calling paging cbfn.
366DMM Paging subscr 46071 succeeded!
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]367DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + trans_nc_ss == 2 (0x41: compl_l3,trans_nc_ss)
368DMSC msc_tx 25 bytes to IMSI-901700000004620:MSISDN-46071 via GERAN-A
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]369- DTAP --GERAN-A--> MS: GSM0480_MTYPE_REGISTER: 0b3b1c15a11302010102013b300b04010f0406aa510c061b01
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]370- DTAP matches expected message
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]371DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 4
372DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_NEW}: state_chg to RAN_CONN_S_ACCEPTED
373DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_ACCEPTED}: ran_conn_fsm_has_active_transactions: connection still has active transaction: NCSS
374DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
375DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_COMPLETE_LAYER_3
376DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - compl_l3 == 1 (0x40: trans_nc_ss)
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]377 dtap_tx_confirmed == 1
378 paging_stopped == 1
379- MS responds to SS/USSD request
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]380 MSC <--GERAN-A-- MS: GSM0480_MTYPE_FACILITY
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]381DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + dtap == 2 (0x42: dtap,trans_nc_ss)
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]382DRLL Dispatching 04.08 message GSM0480_MTYPE_FACILITY (0xb:0x3a)
383DMM Received SS/USSD data (trans_id=0, msg_type=GSM0480_MTYPE_FACILITY)
384GSUP --> HLR: OSMO_GSUP_MSGT_PROC_SS_REQUEST: 20010809710000004026f03004200001013101023527a225020101302002013b301b04010f0416d9775d0e2ae3e965f73cfd7683d27310cd06bbc51a0d
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]385DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_ACCEPTED}: Received Event RAN_CONN_E_COMMUNICATING
386DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_ACCEPTED}: state_chg to RAN_CONN_S_COMMUNICATING
387DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - dtap == 1 (0x40: trans_nc_ss)
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]388 dtap_tx_confirmed == 1
389 paging_stopped == 1
390- HLR terminates the session
391<-- GSUP rx OSMO_GSUP_MSGT_PROC_SS_REQUEST: 20010809710000004026f0300420000101310103
392DVLR GSUP rx 20: 20010809710000004026f0300420000101310103
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]393DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]394DMSC Routed to GSM 09.11 SS/USSD handler
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]395DMSC msc_tx 2 bytes to IMSI-901700000004620:MSISDN-46071 via GERAN-A
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]396- DTAP --GERAN-A--> MS: GSM0480_MTYPE_RELEASE_COMPLETE: 0b2a
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]397- DTAP matches expected message
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]398DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
399DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - trans_nc_ss == 0 (0x0: )
400DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_COMMUNICATING}: Received Event RAN_CONN_E_UNUSED
401DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_COMMUNICATING}: state_chg to RAN_CONN_S_RELEASING
402DREF IMSI-901700000004620:MSISDN-46071: MSC conn use + release == 1 (0x100: release)
403DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage increases to: 4
404DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 3
Neels Hofmeyr7814a832018-12-26 00:40:18 +0100[diff] [blame]405- BSSAP Clear --GERAN-A--> MS
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]406DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 2
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]407<-- GSUP rx OSMO_GSUP_MSGT_PROC_SS_REQUEST: vlr_gsupc_read_cb() returns 0
408 dtap_tx_confirmed == 1
409 bssap_clear_sent == 1
410- all requests serviced, conn has been released
411- BSS sends BSSMAP Clear Complete
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]412DREF IMSI-901700000004620:MSISDN-46071: MSC conn use - release == 0 (0x0: )
413DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_RELEASING}: Received Event RAN_CONN_E_UNUSED
414DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_RELEASING}: state_chg to RAN_CONN_S_RELEASED
415DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_RELEASED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
416DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
417DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_DONE}: Removing from parent RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP)
418DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_DONE}: Freeing instance
419DVLR Process_Access_Request_VLR(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){PR_ARQ_S_DONE}: Deallocated
420DRLL IMSI-901700000004620:MSISDN-46071: Freeing RAN connection
421DREF VLR subscr IMSI-901700000004620:MSISDN-46071 usage decreases to: 1
422DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_RELEASED}: Freeing instance
423DMM RAN_conn(IMSI-901700000004620:MSISDN-46071:GERAN-A-0:PAGING_RESP){RAN_CONN_S_RELEASED}: Deallocated
Neels Hofmeyrc036b792018-11-29 22:37:51 +0100[diff] [blame]424 llist_count(&net->ran_conns) == 0
Neels Hofmeyr361e5712019-01-03 02:32:14 +0100[diff] [blame^]425DREF freeing VLR subscr IMSI-901700000004620:MSISDN-46071
Vadim Yanitskiyf2f83b02018-06-17 21:09:28 +0700[diff] [blame]426===== test_ss_ussd_no_geran: SUCCESS
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]427
428full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]429talloc_total_blocks(tall_bsc_ctx) == 13
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]430
431full talloc report on 'msgb' (total 0 bytes in 1 blocks)
Stefan Sperlingafa030d2018-12-06 12:06:59 +0100[diff] [blame]432talloc_total_blocks(tall_bsc_ctx) == 13
Vadim Yanitskiy27605852018-06-15 23:57:30 +0700[diff] [blame]433