Actions
Bug #4003
closedmsc crashes with sigsegv on "sh subscriber msisdn xxxxx" via vty
Start date:
05/15/2019
Due date:
% Done:
100%
Resolution:
Spec Reference:
Description
version 1.3.1.108.d24c from -nightlsy on debian
steps to reproduce: run a network with sysmobts and one c118 subscriber attached and do "sh subscriber extension 23841" on msc repeatedly
ussd *#100# fails after restarting msc. retry multiple times (usually ~2 fail, third goes through.)
while doing ussd, connect to msc:
telnet localhost 4254
en
sh subscriber extension 23841
% No subscriber found for extension 23841
OsmoMSC# sh subscriber extension 23841
% No subscriber found for extension 23841
OsmoMSC# sh subscriber extension 23841
<segfault>
first sh sub. on msc fails with "% No subscriber found for msisdn 23841"
*#100# fails with 'operation fail' on mobile and gives this output on msc console:
<0010> ran_peer.c:243 ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55555595ab60]{WAIT_RX_RESET}: Receiving CO message on RAN peer that has not done a proper RESET yet. Disconnecting on incoming message, sending RESET to RAN peer.
<0020> sccp_scoc.c:1677 SCCP-SCOC(0)[0x5555559593c0]{DISCONN_PEND}: Event RCOC-DT1.ind not permitted
second *#100# on mobile - same error on mobile
<000e> fsm.c:423 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_INIT}: Allocated
<000e> fsm.c:453 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_INIT}: is child of msc_a(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f00]
<000e> vlr_access_req_fsm.c:675 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_INIT}: rev=GSM net=GERAN Auth (no Ciph)
<000e> vlr_access_req_fsm.c:699 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
<000e> vlr_access_req_fsm.c:402 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_INIT}: proc_arq_fsm_done(IMSI_UNKNOWN_IN_VLR)
<000e> vlr_access_req_fsm.c:103 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_INIT}: State change to PR_ARQ_S_DONE (no timeout)
<000e> vlr_access_req_fsm.c:112 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_DONE}: Process Access Request result: IMSI_UNKNOWN_IN_VLR
<0010> gsm_04_08.c:805 msc_a(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f00]{MSC_A_ST_RELEASING}: subscriber not allowed to do a CM Service Request
<0010> msc_a.c:1525 msc_a(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f00]{MSC_A_ST_RELEASING}: RAN decode error (rc=-5) for COMPL_L3 from MSC-I
<000e> msc_a.c:806 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f00])
<000e> msc_a.c:806 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_DONE}: Removing from parent msc_a(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f00]
<000e> fsm.c:514 Process_Access_Request_VLR(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4b0]{PR_ARQ_S_DONE}: Deferring: will deallocate with msc_a(TMSI-0x5247688F:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f00]
some delay
<000e> fsm.c:423 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_IDLE}: Allocated
<000e> fsm.c:453 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_IDLE}: is child of msc_a(IMSI-901700000023841:GERAN-A-2:LU)[0x555555958f00]
<000e> vlr_lu_fsm.c:1476 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
<000e> vlr_lu_fsm.c:1482 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
<000e> vlr.c:435 set IMSI on subscriber; IMSI=901700000023841 id=901700000023841
<000e> vlr.c:386 New subscr, IMSI: 901700000023841
<000e> vlr_lu_fsm.c:909 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
<000e> vlr_lu_fsm.c:916 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_IDLE}: State change to VLR_ULA_S_WAIT_AUTH (T0, 30s)
<000e> fsm.c:423 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_NEEDS_AUTH}: Allocated
<000e> fsm.c:453 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]
<000e> vlr_auth_fsm.c:628 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
<000e> vlr_auth_fsm.c:318 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_NEEDS_AUTH}: State change to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI (T0, 30s)
<0005> ran_peer.c:105 ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55555595ab60]{READY}: Added seen cell to this RAN peer: CGI:901-70-1-0
<000e> vlr.c:752 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
<000e> vlr.c:732 SUBSCR(IMSI-901700000023841) Received 5 auth tuples
<000e> vlr_auth_fsm.c:374 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: State change to VLR_SUB_AS_WAIT_RESP (no timeout)
<000e> vlr_auth_fsm.c:286 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x3)
<000e> vlr.c:1323 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
<000e> vlr_auth_fsm.c:136 SUBSCR(IMSI-901700000023841) AUTH on GERAN received SRES/RES: 09aaf6d3 (4 bytes)
<000e> vlr_auth_fsm.c:208 SUBSCR(IMSI-901700000023841) AUTH established GSM security context
<000e> vlr_auth_fsm.c:244 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
<000e> vlr_auth_fsm.c:248 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_WAIT_RESP}: State change to VLR_SUB_AS_AUTHENTICATED (no timeout)
<000e> vlr_auth_fsm.c:253 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
<000e> vlr_auth_fsm.c:253 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]
<000e> vlr_auth_fsm.c:253 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
<000e> vlr_lu_fsm.c:863 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()
<000e> vlr_lu_fsm.c:830 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()
<000e> vlr_lu_fsm.c:791 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()
<000e> vlr_lu_fsm.c:800 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_AUTH}: State change to VLR_ULA_S_WAIT_HLR_UPD (T0, 30s)
<000e> fsm.c:423 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_INIT}: Allocated
<000e> fsm.c:453 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4b0]
<000e> vlr_lu_fsm.c:176 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
<000e> vlr_lu_fsm.c:87 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_INIT}: State change to UPD_HLR_VLR_S_WAIT_FOR_DATA (T0, 30s)
<000e> fsm.c:535 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959940]{VLR_SUB_AS_AUTHENTICATED}: Deallocated
<000e> vlr.c:775 IMSI:901700000023841 has MSISDN:23841
<000e> gsm_04_08.c:1364 SUBSCR(IMSI-901700000023841:MSISDN-23841) VLR: update for IMSI=901700000023841 (MSISDN=23841)
<000e> vlr.c:876 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
<000e> vlr_lu_fsm.c:1186 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
<000e> vlr_lu_fsm.c:109 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_WAIT_FOR_DATA}: State change to UPD_HLR_VLR_S_DONE (no timeout)
<000e> vlr_lu_fsm.c:110 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
<000e> vlr_lu_fsm.c:110 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4b0]
<000e> vlr_lu_fsm.c:110 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
<000e> vlr_lu_fsm.c:1194 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_HLR_UPD}: State change to VLR_ULA_S_WAIT_LU_COMPL (T0, 30s)
<000e> fsm.c:423 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_INIT}: Allocated
<000e> fsm.c:453 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4b0]
<000e> vlr_lu_fsm.c:773 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
<000e> vlr_lu_fsm.c:418 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_INIT}: State change to LU_COMPL_VLR_S_WAIT_SUB_PRES (T0, 30s)
<000e> vlr_lu_fsm.c:425 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
<000e> vlr_lu_fsm.c:434 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: lu_compl_vlr_new_tmsi()
<000e> gsm_04_08.c:1364 SUBSCR(IMSI-901700000023841:MSISDN-23841:TMSInew-0x54096394) VLR: update for IMSI=901700000023841 (MSISDN=23841)
<000e> vlr_lu_fsm.c:443 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: State change to LU_COMPL_VLR_S_WAIT_TMSI_CNF (no timeout)
<000e> fsm.c:535 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a7e0]{UPD_HLR_VLR_S_DONE}: Deallocated
<000e> vlr.c:1185 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSInew-0x54096394:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_NEW_TMSI_ACK
<000e> vlr_lu_fsm.c:1228 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_WAIT_TMSI_CNF}: Received Event LU_COMPL_VLR_E_NEW_TMSI_ACK
<000e> gsm_04_08.c:1364 SUBSCR(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394) VLR: update for IMSI=901700000023841 (MSISDN=23841)
<000e> vlr_lu_fsm.c:353 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_WAIT_TMSI_CNF}: State change to LU_COMPL_VLR_S_DONE (no timeout)
<000e> vlr_lu_fsm.c:388 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
<000e> vlr_lu_fsm.c:781 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
<000e> vlr_lu_fsm.c:781 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x55555595c4b0]
<000e> fsm.c:535 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959940]{LU_COMPL_VLR_S_DONE}: Deallocated
<000e> vlr_lu_fsm.c:749 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_WAIT_LU_COMPL}: State change to VLR_ULA_S_DONE (no timeout)
<000e> msc_a.c:806 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x555555958f00])
<000e> msc_a.c:806 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_DONE}: Removing from parent msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x555555958f00]
<000e> vlr_lu_fsm.c:1401 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
<000e> fsm.c:514 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x55555595c4b0]{VLR_ULA_S_DONE}: Deferring: will deallocate with msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x54096394:GERAN-A-2:LU)[0x555555958f00]
sh subscriber msisdn 23841
<segfault>
Program received signal SIGSEGV, Segmentation fault. 0x00007ffff7536ee5 in osmo_use_count_total (uc=uc@entry=0x670) at use_count.c:76 76 use_count.c: No such file or directory. (gdb) (gdb) (gdb) (gdb) (gdb) bt #0 0x00007ffff7536ee5 in osmo_use_count_total (uc=uc@entry=0x670) at use_count.c:76 #1 0x00007ffff7536f94 in osmo_use_count_name_buf (buf=buf@entry=0x7fffffffdc00 "\020\334\377\377\377\177", buf_len=buf_len@entry=128, uc=uc@entry=0x670) at use_count.c:110 #2 0x0000555555567ae2 in vty_dump_one_conn (vty=vty@entry=0x5555559513b0, msub=0x55555595bbc0, idx=idx@entry=0x7fffffffdcdc) at msc_vty.c:648 #3 0x00005555555682df in subscr_dump_full_vty (vty=vty@entry=0x5555559513b0, vsub=vsub@entry=0x555555959030) at msc_vty.c:813 #4 0x000055555556a362 in show_subscr (self=<optimized out>, vty=0x5555559513b0, argc=<optimized out>, argv=<optimized out>) at msc_vty.c:971 #5 0x00007ffff7bbf7c1 in cmd_execute_command_real (vline=vline@entry=0x555555951940, vty=vty@entry=0x5555559513b0, cmd=0x0) at command.c:2320 #6 0x00007ffff7bc2231 in cmd_execute_command (vline=vline@entry=0x555555951940, vty=vty@entry=0x5555559513b0, cmd=cmd@entry=0x0, vtysh=vtysh@entry=0) at command.c:2353 #7 0x00007ffff7bc4740 in vty_command (buf=<optimized out>, vty=0x5555559513b0) at vty.c:431 #8 vty_execute (vty=0x5555559513b0) at vty.c:695 #9 vty_read (vty=<optimized out>) at vty.c:1419 #10 0x00007ffff7bc6831 in client_data (fd=0x5555559522c8, what=1) at telnet_interface.c:147 #11 0x00007ffff7525667 in osmo_fd_disp_fds (_eset=0x7fffffffea40, _wset=0x7fffffffe9c0, _rset=0x7fffffffe940) at select.c:223 #12 osmo_select_main (polling=0) at select.c:263 #13 0x0000555555564c7b in main (argc=3, argv=<optimized out>) at msc_main.c:747 (gdb)
full trace:
root@test123:~# gdb --args /usr/bin/osmo-msc -c /etc/osmocom/osmo-msc.cfg
GNU gdb (Debian 7.12-6) 7.12.0.20161007-git
Copyright (C) 2016 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law. Type "show copying"
and "show warranty" for details.
This GDB was configured as "x86_64-linux-gnu".
Type "show configuration" for configuration details.
For bug reporting instructions, please see:
<http://www.gnu.org/software/gdb/bugs/>.
Find the GDB manual and other documentation resources online at:
<http://www.gnu.org/software/gdb/documentation/>.
For help, type "help".
Type "apropos word" to search for commands related to "word"...
Reading symbols from /usr/bin/osmo-msc...Reading symbols from /usr/lib/debug/.build-id/3e/d2e8d03b9e2e3e1b51b41c6de46b194bdd70f0.debug...done.
done.
(gdb) r
Starting program: /usr/bin/osmo-msc -c /etc/osmocom/osmo-msc.cfg
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
<001f> osmo_ss7.c:1326 0: ASP Restart for server not implemented yet!
<0013> telnet_interface.c:104 Available via telnet 127.0.0.1 4254
<000c> smpp_smsc.c:1017 SMPP at 0.0.0.0 2775
<001a> control_if.c:911 CTRL at 127.0.0.1 4255
<0009> db.c:619 Init database connection to 'sms.db' using libdbi v0.9.0
<001d> gsup_client.c:73 GSUP connecting to 127.0.0.1:4222
<0023> mgcp_client.c:716 MGCP client: using endpoint domain '@mgw'
<0013> socket.c:228 unable to bind socket: 0.0.0.0:2727: Address already in use
<0013> socket.c:237 no suitable local addr found for: 0.0.0.0:2727
<0023> mgcp_client.c:749 MGCPGW failed to bind to 0.0.0.0:2727, retrying with port 2728
<0020> sccp_user.c:515 OsmoMSC-A-Iu: Using SS7 instance 0, pc:0.23.1
<0020> sccp_user.c:533 OsmoMSC-A-Iu: Using AS instance as-clnt-OsmoMSC-A-Iu
<0020> sccp_user.c:538 OsmoMSC-A-Iu: Creating default route
<0020> sccp_user.c:594 OsmoMSC-A-Iu: Using ASP instance asp-clnt-OsmoMSC-A-Iu
<001f> osmo_ss7.c:471 0: Creating SCCP instance
<0011> sgs_server.c:186 SGs socket bound to r=NULL<->l=0.0.0.0:29118
<0006> msc_main.c:712 A-interface: SCCP user OsmoMSC-A:RI=SSN_PC,PC=(no PC),SSN=BSSAP, cs7-instance 0 ((null))
<0006> msc_main.c:731 Iu-interface: SCCP user OsmoMSC-IuCS:RI=SSN_PC,PC=(no PC),SSN=RANAP, cs7-instance 0 ((null))
<0015> input/ipa.c:128 127.0.0.1:4222 connection done
<0015> input/ipaccess.c:705 received ID get from 0/0/0
<0022> m3ua.c:634 asp-asp-clnt-OsmoMSC-A-Iu: Received NOTIFY Type State Change:AS Inactive ()
<001f> xua_default_lm_fsm.c:353 xua_default_lm(asp-clnt-OsmoMSC-A-Iu)[0x555555953850]{ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm
<0022> m3ua.c:634 asp-asp-clnt-OsmoMSC-A-Iu: Received NOTIFY Type State Change:AS Active ()
<0010> ran_peer.c:243 ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55555595ab80]{WAIT_RX_RESET}: Receiving CO message on RAN peer that has not done a proper RESET yet. Disconnecting on incoming message, sending RESET to RAN peer.
<0020> sccp_scoc.c:1677 SCCP-SCOC(0)[0x5555559593e0]{DISCONN_PEND}: Event RCOC-DT1.ind not permitted
<000e> fsm.c:423 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_INIT}: Allocated
<000e> fsm.c:453 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_INIT}: is child of msc_a(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f20]
<000e> vlr_access_req_fsm.c:675 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_INIT}: rev=GSM net=GERAN Auth (no Ciph)
<000e> vlr_access_req_fsm.c:699 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
<000e> vlr_access_req_fsm.c:402 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_INIT}: proc_arq_fsm_done(IMSI_UNKNOWN_IN_VLR)
<000e> vlr_access_req_fsm.c:103 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_INIT}: State change to PR_ARQ_S_DONE (no timeout)
<000e> vlr_access_req_fsm.c:112 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_DONE}: Process Access Request result: IMSI_UNKNOWN_IN_VLR
<0010> gsm_04_08.c:805 msc_a(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f20]{MSC_A_ST_RELEASING}: subscriber not allowed to do a CM Service Request
<0010> msc_a.c:1525 msc_a(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f20]{MSC_A_ST_RELEASING}: RAN decode error (rc=-5) for COMPL_L3 from MSC-I
<000e> msc_a.c:806 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f20])
<000e> msc_a.c:806 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_DONE}: Removing from parent msc_a(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f20]
<000e> fsm.c:514 Process_Access_Request_VLR(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x55555595c4d0]{PR_ARQ_S_DONE}: Deferring: will deallocate with msc_a(TMSI-0x2EE3A704:GERAN-A-1:CM_SERVICE_REQ)[0x555555958f20]
<000e> fsm.c:423 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_IDLE}: Allocated
<000e> fsm.c:453 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_IDLE}: is child of msc_a(IMSI-901700000023841:GERAN-A-2:LU)[0x555555958f20]
<000e> vlr_lu_fsm.c:1476 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_IDLE}: rev=GSM net=GERAN Auth (no Ciph)
<000e> vlr_lu_fsm.c:1482 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_IDLE}: Received Event VLR_ULA_E_UPDATE_LA
<000e> vlr.c:435 set IMSI on subscriber; IMSI=901700000023841 id=901700000023841
<000e> vlr.c:386 New subscr, IMSI: 901700000023841
<000e> vlr_lu_fsm.c:909 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_IDLE}: vlr_loc_upd_node1()
<000e> vlr_lu_fsm.c:916 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_IDLE}: State change to VLR_ULA_S_WAIT_AUTH (T0, 30s)
<000e> fsm.c:423 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_NEEDS_AUTH}: Allocated
<000e> fsm.c:453 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_NEEDS_AUTH}: is child of vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]
<000e> vlr_auth_fsm.c:628 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
<000e> vlr_auth_fsm.c:318 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_NEEDS_AUTH}: State change to VLR_SUB_AS_NEEDS_AUTH_WAIT_AI (T0, 30s)
<0005> ran_peer.c:105 ran_peer(GERAN-A:RI-SSN_PC:PC-0-23-3:SSN-BSSAP)[0x55555595ab80]{READY}: Added seen cell to this RAN peer: CGI:901-70-1-0
<000e> vlr.c:752 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: Received Event VLR_AUTH_E_HLR_SAI_ACK
<000e> vlr.c:732 SUBSCR(IMSI-901700000023841) Received 5 auth tuples
<000e> vlr_auth_fsm.c:374 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_NEEDS_AUTH_WAIT_AI}: State change to VLR_SUB_AS_WAIT_RESP (no timeout)
<000e> vlr_auth_fsm.c:286 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=0 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x3)
<000e> vlr.c:1323 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
<000e> vlr_auth_fsm.c:136 SUBSCR(IMSI-901700000023841) AUTH on GERAN received SRES/RES: 9a8c5d25 (4 bytes)
<000e> vlr_auth_fsm.c:208 SUBSCR(IMSI-901700000023841) AUTH established GSM security context
<000e> vlr_auth_fsm.c:244 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
<000e> vlr_auth_fsm.c:248 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_WAIT_RESP}: State change to VLR_SUB_AS_AUTHENTICATED (no timeout)
<000e> vlr_auth_fsm.c:253 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
<000e> vlr_auth_fsm.c:253 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_AUTHENTICATED}: Removing from parent vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]
<000e> vlr_auth_fsm.c:253 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_AUTH}: Received Event VLR_ULA_E_AUTH_RES
<000e> vlr_lu_fsm.c:863 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_auth()
<000e> vlr_lu_fsm.c:830 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_post_ciph()
<000e> vlr_lu_fsm.c:791 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_AUTH}: vlr_loc_upd_node_4()
<000e> vlr_lu_fsm.c:800 vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_AUTH}: State change to VLR_ULA_S_WAIT_HLR_UPD (T0, 30s)
<000e> fsm.c:423 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_INIT}: Allocated
<000e> fsm.c:453 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595c4d0]
<000e> vlr_lu_fsm.c:176 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_INIT}: Received Event UPD_HLR_VLR_E_START
<000e> vlr_lu_fsm.c:87 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_INIT}: State change to UPD_HLR_VLR_S_WAIT_FOR_DATA (T0, 30s)
<000e> fsm.c:535 VLR_Authenticate(IMSI-901700000023841:GERAN-A-2:LU)[0x555555959960]{VLR_SUB_AS_AUTHENTICATED}: Deallocated
<000e> vlr.c:775 IMSI:901700000023841 has MSISDN:23841
<000e> gsm_04_08.c:1364 SUBSCR(IMSI-901700000023841:MSISDN-23841) VLR: update for IMSI=901700000023841 (MSISDN=23841)
<000e> vlr.c:876 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_HLR_LU_RES
<000e> vlr_lu_fsm.c:1186 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_WAIT_FOR_DATA}: Received Event UPD_HLR_VLR_E_UPD_LOC_ACK
<000e> vlr_lu_fsm.c:109 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_WAIT_FOR_DATA}: State change to UPD_HLR_VLR_S_DONE (no timeout)
<000e> vlr_lu_fsm.c:110 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
<000e> vlr_lu_fsm.c:110 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4d0]
<000e> vlr_lu_fsm.c:110 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_HLR_UPD}: Received Event VLR_ULA_E_UPD_HLR_COMPL
<000e> vlr_lu_fsm.c:1194 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_HLR_UPD}: State change to VLR_ULA_S_WAIT_LU_COMPL (T0, 30s)
<000e> fsm.c:423 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_INIT}: Allocated
<000e> fsm.c:453 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_INIT}: is child of vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x55555595c4d0]
<000e> vlr_lu_fsm.c:773 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_INIT}: Received Event LU_COMPL_VLR_E_START
<000e> vlr_lu_fsm.c:418 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_INIT}: State change to LU_COMPL_VLR_S_WAIT_SUB_PRES (T0, 30s)
<000e> vlr_lu_fsm.c:425 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: Received Event LU_COMPL_VLR_E_SUB_PRES_COMPL
<000e> vlr_lu_fsm.c:434 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: lu_compl_vlr_new_tmsi()
<000e> gsm_04_08.c:1364 SUBSCR(IMSI-901700000023841:MSISDN-23841:TMSInew-0x31E5A925) VLR: update for IMSI=901700000023841 (MSISDN=23841)
<000e> vlr_lu_fsm.c:443 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_WAIT_SUB_PRES}: State change to LU_COMPL_VLR_S_WAIT_TMSI_CNF (no timeout)
<000e> fsm.c:535 upd_hlr_vlr_fsm(IMSI-901700000023841:GERAN-A-2:LU)[0x55555595a800]{UPD_HLR_VLR_S_DONE}: Deallocated
<000e> vlr.c:1185 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSInew-0x31E5A925:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_NEW_TMSI_ACK
<000e> vlr_lu_fsm.c:1228 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_WAIT_TMSI_CNF}: Received Event LU_COMPL_VLR_E_NEW_TMSI_ACK
<000e> gsm_04_08.c:1364 SUBSCR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925) VLR: update for IMSI=901700000023841 (MSISDN=23841)
<000e> vlr_lu_fsm.c:353 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_WAIT_TMSI_CNF}: State change to LU_COMPL_VLR_S_DONE (no timeout)
<000e> vlr_lu_fsm.c:388 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_LU_COMPL}: Received Event VLR_ULA_E_LU_COMPL_SUCCESS
<000e> vlr_lu_fsm.c:781 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_DONE}: Terminating (cause = OSMO_FSM_TERM_PARENT)
<000e> vlr_lu_fsm.c:781 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_DONE}: Removing from parent vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x55555595c4d0]
<000e> fsm.c:535 lu_compl_vlr_fsm(IMSI-901700000023841:MSISDN-23841:GERAN-A-2:LU)[0x555555959960]{LU_COMPL_VLR_S_DONE}: Deallocated
<000e> vlr_lu_fsm.c:749 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_WAIT_LU_COMPL}: State change to VLR_ULA_S_DONE (no timeout)
<000e> msc_a.c:806 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x555555958f20])
<000e> msc_a.c:806 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_DONE}: Removing from parent msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x555555958f20]
<000e> vlr_lu_fsm.c:1401 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_DONE}: fsm_lu_cleanup called with cause OSMO_FSM_TERM_PARENT
<000e> fsm.c:514 vlr_lu_fsm(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x55555595c4d0]{VLR_ULA_S_DONE}: Deferring: will deallocate with msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-2:LU)[0x555555958f20]
<000e> fsm.c:423 Process_Access_Request_VLR(TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_INIT}: Allocated
<000e> fsm.c:453 Process_Access_Request_VLR(TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_INIT}: is child of msc_a(TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595e0e0]
<000e> vlr_access_req_fsm.c:675 Process_Access_Request_VLR(TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_INIT}: rev=GSM net=GERAN Auth (no Ciph)
<000e> vlr_access_req_fsm.c:699 Process_Access_Request_VLR(TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_INIT}: Received Event PR_ARQ_E_START
<000e> vlr_access_req_fsm.c:333 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_INIT}: proc_arq_vlr_fn_post_imsi()
<000e> vlr_access_req_fsm.c:340 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_INIT}: State change to PR_ARQ_S_WAIT_AUTH (no timeout)
<000e> fsm.c:423 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_NEEDS_AUTH}: Allocated
<000e> fsm.c:453 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_NEEDS_AUTH}: is child of Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]
<000e> vlr_auth_fsm.c:628 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_NEEDS_AUTH}: Received Event VLR_AUTH_E_START
<000e> vlr_auth_fsm.c:322 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_NEEDS_AUTH}: State change to VLR_SUB_AS_WAIT_RESP (no timeout)
<000e> vlr_auth_fsm.c:286 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_WAIT_RESP}: got auth tuple: use_count=1 key_seq=1 -- will use GSM AKA (is_r99=no, at->vec.auth_types=0x3)
<000e> vlr.c:1323 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_WAIT_RESP}: Received Event VLR_AUTH_E_MS_AUTH_RESP
<000e> vlr_auth_fsm.c:136 SUBSCR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925) AUTH on GERAN received SRES/RES: b3c7269f (4 bytes)
<000e> vlr_auth_fsm.c:208 SUBSCR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925) AUTH established GSM security context
<000e> vlr_auth_fsm.c:244 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_WAIT_RESP}: Authentication terminating with result PASSED
<000e> vlr_auth_fsm.c:248 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_WAIT_RESP}: State change to VLR_SUB_AS_AUTHENTICATED (no timeout)
<000e> vlr_auth_fsm.c:253 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_AUTHENTICATED}: Terminating (cause = OSMO_FSM_TERM_REGULAR)
<000e> vlr_auth_fsm.c:253 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_AUTHENTICATED}: Removing from parent Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]
<000e> vlr_auth_fsm.c:253 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: Received Event PR_ARQ_E_AUTH_RES
<000e> vlr_access_req_fsm.c:284 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2()
<000e> vlr_access_req_fsm.c:250 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2_post_ciph()
<000e> vlr_access_req_fsm.c:222 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_node2_post_vlr()
<000e> vlr_access_req_fsm.c:207 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_pres()
<000e> vlr_access_req_fsm.c:191 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_trace()
<000e> vlr_access_req_fsm.c:169 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: _proc_arq_vlr_post_imei()
<000e> vlr_access_req_fsm.c:182 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: proc_arq_fsm_done(PASSED)
<000e> vlr_access_req_fsm.c:103 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_WAIT_AUTH}: State change to PR_ARQ_S_DONE (no timeout)
<000e> vlr_access_req_fsm.c:112 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_DONE}: Process Access Request result: PASSED
<000e> fsm.c:535 VLR_Authenticate(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x555555958ca0]{VLR_SUB_AS_AUTHENTICATED}: Deallocated
<000e> msc_a.c:806 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_DONE}: Terminating in cascade, depth 2 (cause = OSMO_FSM_TERM_PARENT, caused by: msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595e0e0])
<000e> msc_a.c:806 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_DONE}: Removing from parent msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595e0e0]
<000e> fsm.c:514 Process_Access_Request_VLR(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595d220]{PR_ARQ_S_DONE}: Deferring: will deallocate with msc_a(IMSI-901700000023841:MSISDN-23841:TMSI-0x31E5A925:GERAN-A-3:CM_SERVICE_REQ)[0x55555595e0e0]
Program received signal SIGSEGV, Segmentation fault.
0x00007ffff7536ee5 in osmo_use_count_total (uc=uc@entry=0x670) at use_count.c:76
76 use_count.c: No such file or directory.
(gdb) bt
#0 0x00007ffff7536ee5 in osmo_use_count_total (uc=uc@entry=0x670) at use_count.c:76
#1 0x00007ffff7536f94 in osmo_use_count_name_buf (buf=buf@entry=0x7fffffffdc00 "\020\334\377\377\377\177", buf_len=buf_len@entry=128, uc=uc@entry=0x670) at use_count.c:110
#2 0x0000555555567ae2 in vty_dump_one_conn (vty=vty@entry=0x5555559513b0, msub=0x55555595c9e0, idx=idx@entry=0x7fffffffdcdc) at msc_vty.c:648
#3 0x00005555555682df in subscr_dump_full_vty (vty=vty@entry=0x5555559513b0, vsub=vsub@entry=0x555555959050) at msc_vty.c:813
#4 0x000055555556a362 in show_subscr (self=<optimized out>, vty=0x5555559513b0, argc=<optimized out>, argv=<optimized out>) at msc_vty.c:971
#5 0x00007ffff7bbf7c1 in cmd_execute_command_real (vline=vline@entry=0x555555955a00, vty=vty@entry=0x5555559513b0, cmd=0x0) at command.c:2320
#6 0x00007ffff7bc2231 in cmd_execute_command (vline=vline@entry=0x555555955a00, vty=vty@entry=0x5555559513b0, cmd=cmd@entry=0x0, vtysh=vtysh@entry=0) at command.c:2353
#7 0x00007ffff7bc4740 in vty_command (buf=<optimized out>, vty=0x5555559513b0) at vty.c:431
#8 vty_execute (vty=0x5555559513b0) at vty.c:695
#9 vty_read (vty=<optimized out>) at vty.c:1419
#10 0x00007ffff7bc6831 in client_data (fd=0x5555559522c8, what=1) at telnet_interface.c:147
#11 0x00007ffff7525667 in osmo_fd_disp_fds (_eset=0x7fffffffea40, _wset=0x7fffffffe9c0, _rset=0x7fffffffe940) at select.c:223
#12 osmo_select_main (polling=0) at select.c:263
#13 0x0000555555564c7b in main (argc=3, argv=<optimized out>) at msc_main.c:747
(gdb)
Actions
