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