Cellular Network Infrastructure » Core Network (CN) » OsmoSGSN

20240424190543685 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 172 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190543685 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190543685 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190543685 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190543685 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190543685 DLSUA DEBUG IEI 259: Parsed Addr: RI=2,PC=188,SSN=142 (sccp2sua.c:334)

20240424190543685 DLSUA DEBUG IEI 258: Parsed Addr: RI=2,PC=189,SSN=142 (sccp2sua.c:334)

20240424190543685 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CORE,V=0,LEN=0), PART(T=Protocol Class,L=4,D=00000002), PART(T=Source Reference,L=4,D=00000002), PART(T=Destination Address,L=20,D=0002000380020008000000bc800300080000008e), PART(T=Source Address,L=20,D=0002000380020008000000bd800300080000008e), PART(T=Data,L=116,D=001340700000070003400180000f40060009f107b04300374001fa003a40080009f107ffff000000104039380801037560000141f805f4e969ca4409f107b043fa211b73432b259662004000000012708b198801000000004bc28c6620040000000100e0004f40030000170056400509f1070017) (sccp_scrc.c:472)

20240424190543685 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190543685 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190543685 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190543685 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190543685 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{IDLE}: Allocated (fsm.c:456)

20240424190543685 DLSCCP DEBUG Received CO:CORE for local reference 1 (sccp_scoc.c:1823)

20240424190543685 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{IDLE}: Received Event RCOC-CONNECT.ind (sccp_scoc.c:1857)

20240424190543685 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190543685 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190543685 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190543685 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{IDLE}: state_chg to CONN_PEND_IN (sccp_scoc.c:1018)

20240424190543685 DLSCCP DEBUG Delivering N-CONNECT.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190543685 DRANAP DEBUG sccp_sap_up(N-CONNECT.indication) (iu_client.c:865)

20240424190543685 DRANAP DEBUG N-CONNECT.ind(X->1) (iu_client.c:874)

20240424190543685 DLSCCP DEBUG Received SCCP User Primitive (N-CONNECT.response) (sccp_scoc.c:1890)

20240424190543685 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{CONN_PEND_IN}: Received Event N-CONNECT.resp (sccp_scoc.c:1931)

20240424190543685 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:COAK,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Protocol Class,L=4,D=00000002), PART(T=Destination Reference,L=4,D=00000002), PART(T=Source Reference,L=4,D=00000001), PART(T=Sequence Control,L=4,D=00000000), PART(T=Source Address,L=20,D=0002000380020008000000bc800300080000008e), PART(T=Destination Address,L=20,D=0002000380020008000000bd800300080000008e) (sccp_scrc.c:401)

20240424190543685 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190543685 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190543685 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190543685 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190543685 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190543685 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190543685 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190543685 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190543685 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{CONN_PEND_IN}: state_chg to ACTIVE (sccp_scoc.c:1045)

20240424190543685 DMM DEBUG Rx CO IM (Initial UE Message) (ranap_common_cn.c:41)

20240424190543686 DMM DEBUG Decoding message RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)

20240424190543686 DRANAP NOTICE handle_co_initial(dir=1, proc=19) (iu_client.c:600)

20240424190543686 DRANAP NOTICE New RNC 23 at RI=2,PC=189,SSN=142 (iu_client.c:223)

20240424190543686 DRANAP NOTICE RNC 23: new LAC 45123 RAC 250 (iu_client.c:306)

20240424190543686 DMM INFO MM(---/ffffffff) -> GMM ATTACH REQUEST MI(TMSI-0xE969CA44) type="GPRS attach"  (gprs_gmm.c:1283)

20240424190543686 DLGLOBAL DEBUG validating counter group 0x5d5218eceb00(sgsn:mmctx) with 11 counters (rate_ctr.c:86)

20240424190543686 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{Deregistered}: Allocated (fsm.c:456)

20240424190543686 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{Init}: Allocated (fsm.c:456)

20240424190543686 DMM DEBUG MM_STATE_Gb[0x5d521a563be0]{Idle}: Allocated (fsm.c:456)

20240424190543686 DMM DEBUG MM_STATE_Iu[0x5d521a563d10]{Detached}: Allocated (fsm.c:456)

20240424190543686 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1161)

20240424190543686 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{Deregistered}: state_chg to CommonProcedureInitiated (gprs_gmm_fsm.c:49)

20240424190543686 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{Init}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1387)

gmm_attach_allstate_action(event=0, fi->state=0)

20240424190543686 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{Init}: state_chg to CheckIdentity (gprs_gmm_attach.c:52)

20240424190543686 DMM DEBUG MM(/d5278993) <- GMM IDENTITY REQUEST: mi_type=IMEI (gprs_gmm.c:448)

20240424190543686 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 1) (iu_client.c:510)

20240424190543686 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190543686 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190543686 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000002), PART(T=Data,L=20,D=001440100000020010400403081502003b400100) (sccp_scrc.c:401)

20240424190543686 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190543686 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190543686 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190543686 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190544220 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 92 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190544220 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190544220 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190544220 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190544221 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190544221 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=50,D=0014402e0000040010400c0b0816088a06280716211501000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190544221 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190544221 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190544221 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190544221 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190544221 DRANAP DEBUG N-DATA.ind(1, 00 14 40 2e 00 00 04 00 10 40 0c 0b 08 16 08 8a 06 28 07 16 21 15 01 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190544221 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190544221 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190544221 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190544221 DMM DEBUG MM(/d5278993) -> GMM IDENTITY RESPONSE: MI=IMEI-860827061125110 (gprs_gmm.c:1101)

20240424190544221 DMM ERROR MM(/d5278993) Stopping MM timer 3370 but 0 is running (gprs_gmm.c:116)

20240424190544221 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{CheckIdentity}: Received Event E_IDEN_RESP_RECV (gprs_gmm.c:1145)

20240424190544221 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{CheckIdentity}: state_chg to CheckIdentity (gprs_gmm_attach.c:113)

20240424190544221 DMM DEBUG MM(/d5278993) <- GMM IDENTITY REQUEST: mi_type=IMSI (gprs_gmm.c:448)

20240424190544221 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 1) (iu_client.c:510)

20240424190544221 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190544221 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190544221 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000002), PART(T=Data,L=20,D=001440100000020010400403081501003b400100) (sccp_scrc.c:401)

20240424190544221 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190544221 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190544221 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190544221 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190544708 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 92 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190544708 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190544708 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190544708 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190544708 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190544708 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=50,D=0014402e0000040010400c0b0816089910070000306335000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190544708 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190544708 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190544708 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190544708 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190544708 DRANAP DEBUG N-DATA.ind(1, 00 14 40 2e 00 00 04 00 10 40 0c 0b 08 16 08 99 10 07 00 00 30 63 35 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190544708 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190544708 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190544708 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190544708 DMM DEBUG MM(/d5278993) -> GMM IDENTITY RESPONSE: MI=IMSI-901700000033653 (gprs_gmm.c:1101)

20240424190544708 DMM ERROR MM(/d5278993) Stopping MM timer 3370 but 0 is running (gprs_gmm.c:116)

20240424190544708 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{CheckIdentity}: Received Event E_IDEN_RESP_RECV (gprs_gmm.c:1145)

20240424190544708 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{CheckIdentity}: state_chg to Authenticate (gprs_gmm_attach.c:115)

20240424190544708 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{Authenticate}: state_chg to AskVLR (gprs_gmm_attach.c:143)

20240424190544708 DMM DEBUG MM(901700000033653/d5278993) Requesting authorization (sgsn_auth.c:160)

20240424190544708 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:884)

20240424190544708 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (sgsn_auth.c:173)

20240424190544708 DMM INFO MM(901700000033653/d5278993) Requesting authentication tuples (sgsn_auth.c:184)

20240424190544708 DMM DEBUG MM(901700000033653/d5278993) Requesting subscriber authentication info (gprs_subscriber.c:921)

20240424190544708 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:865)

20240424190544708 DGPRS INFO SUBSCR(901700000033653) requesting auth info (gprs_subscriber.c:805)

20240424190544708 DGPRS INFO SUBSCR(901700000033653) Sending GSUP, will send: 08 01 08 09 71 00 00 00 33 56 f3 28 01 01  (gprs_subscriber.c:210)

20240424190544708 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:930)

20240424190544709 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190544709 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190544709 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190544709 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190544725 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190544725 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190544725 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:141)

20240424190544725 DGPRS INFO SUBSCR(901700000033653) Received GSUP message OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT (gprs_subscriber.c:723)

20240424190544725 DGPRS INFO SUBSCR(901700000033653) Got SendAuthenticationInfoResult, num_auth_vectors = 5 (gprs_subscriber.c:242)

20240424190544725 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 0 (gprs_subscriber.c:255)

20240424190544725 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 1 (gprs_subscriber.c:255)

20240424190544725 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 2 (gprs_subscriber.c:255)

20240424190544725 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 3 (gprs_subscriber.c:255)

20240424190544725 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 4 (gprs_subscriber.c:255)

20240424190544725 DGPRS DEBUG SUBSCR(901700000033653) Updating subscriber authentication info (gprs_subscriber.c:850)

20240424190544725 DMM INFO MM(901700000033653/d5278993) Subscriber data update (mmctx.c:430)

20240424190544725 DMM DEBUG MM(901700000033653/d5278993) Updating authorization (unknown -> authenticate) (sgsn_auth.c:217)

20240424190544725 DMM INFO MM(901700000033653/d5278993) Got authorization update: state unknown -> authenticate (sgsn_auth.c:246)

20240424190544725 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{AskVLR}: Received Event E_VLR_ANSWERED (gprs_gmm.c:1008)

20240424190544725 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{AskVLR}: state_chg to Authenticate (gprs_gmm_attach.c:256)

20240424190544725 DMM INFO MM(901700000033653/d5278993) <- GMM AUTH AND CIPHERING REQ (rand = 2b 88 cc 4f be df 03 d7 04 b6 52 78 46 af 5f 4d , mmctx_is_r99=1, vec->auth_types=0x3, autn = 09 20 bf 39 dd 03 00 00 9f 92 d8 ca 8f 56 7d 77 ) (gprs_gmm.c:500)

20240424190544725 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 1) (iu_client.c:510)

20240424190544725 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190544725 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190544725 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000002), PART(T=Data,L=57,D=00144035000002001040292808121040212b88cc4fbedf03d704b6527846af5f4d8028100920bf39dd0300009f92d8ca8f567d77003b400100) (sccp_scrc.c:401)

20240424190544725 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190544725 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190544725 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190544725 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190544725 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:775)

20240424190545648 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 104 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190545649 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190545649 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190545649 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190545649 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190545649 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=64,D=0014403c0000040010401a1908130422119f83e723098306280716211551f4290430ca42d9000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190545649 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190545649 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190545649 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190545649 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190545649 DRANAP DEBUG N-DATA.ind(1, 00 14 40 3c 00 00 04 00 10 40 1a 19 08 13 04 22 11 9f 83 e7 23 09 83 06 28 07 16 21 15 51 f4 29 04 30 ca 42 d9 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190545649 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190545649 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190545649 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190545649 DMM INFO MM(901700000033653/d5278993) -> GMM AUTH AND CIPH RESPONSE (gprs_gmm.c:637)

20240424190545649 DMM ERROR MM(901700000033653/d5278993) Stopping MM timer 3360 but 0 is running (gprs_gmm.c:116)

20240424190545649 DMM DEBUG MM(901700000033653/d5278993) checking auth: received UMTS RES = 11 9f 83 e7 30 ca 42 d9  (gprs_gmm.c:683)

20240424190545649 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{Authenticate}: Received Event E_AUTH_RESP_RECV_SUCCESS (gprs_gmm.c:698)

20240424190545649 DMM DEBUG MM(901700000033653/d5278993) Requesting authorization (sgsn_auth.c:160)

20240424190545649 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:865)

20240424190545649 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (sgsn_auth.c:173)

20240424190545649 DMM INFO MM(901700000033653/d5278993) Missing information, requesting subscriber data (sgsn_auth.c:195)

20240424190545649 DMM DEBUG MM(901700000033653/d5278993) Requesting subscriber data update (gprs_subscriber.c:894)

20240424190545649 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:865)

20240424190545649 DGPRS INFO SUBSCR(901700000033653) subscriber data is not available (gprs_subscriber.c:818)

20240424190545649 DGPRS INFO SUBSCR(901700000033653) Sending GSUP, will send: 04 01 08 09 71 00 00 00 33 56 f3 28 01 01 2a 01 02  (gprs_subscriber.c:210)

20240424190545649 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:903)

20240424190545649 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{Authenticate}: state_chg to IuSecurityCommand (gprs_gmm_attach.c:178)

20240424190545649 DMM DEBUG MM(901700000033653/d5278993) Iu Security Mode Command: not sending encryption key (UEA encryption mask = 0x1) (gprs_gmm_attach.c:275)

20240424190545649 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190545649 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190545649 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000002), PART(T=Data,L=34,D=0006001e000002000c00120808e9d6211a7b7066eb40061c50c7377659004b000140) (sccp_scrc.c:401)

20240424190545649 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190545649 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190545649 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190545649 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190545649 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190545649 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190545650 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190545650 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190545665 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190545665 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190545665 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:141)

20240424190545665 DGPRS INFO SUBSCR(901700000033653) Received GSUP message OSMO_GSUP_MSGT_INSERT_DATA_REQUEST (gprs_subscriber.c:723)

20240424190545665 DGPRS INFO SUBSCR(901700000033653) Will set PDP info, context id = 1, APN = 01 2a  (gprs_subscriber.c:367)

20240424190545665 DGPRS DEBUG SUBSCR(901700000033653) Updating subscriber data (gprs_subscriber.c:839)

20240424190545665 DMM INFO MM(901700000033653/d5278993) Subscriber data update (mmctx.c:430)

20240424190545665 DMM DEBUG MM(901700000033653/d5278993) Updating authorization (authenticate -> accepted) (sgsn_auth.c:217)

20240424190545665 DMM INFO MM(901700000033653/d5278993) Got authorization update: state authenticate -> accepted (sgsn_auth.c:246)

20240424190545665 DMM NOTICE MM(901700000033653/d5278993) Authorized, continuing procedure, IMSI=901700000033653 (gprs_gmm.c:1017)

20240424190545665 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{IuSecurityCommand}: Received Event E_VLR_ANSWERED (gprs_gmm.c:1022)

---------------------st_iu_security_cmd(event=9)

20240424190545665 DGPRS INFO SUBSCR(901700000033653) Sending GSUP, will send: 12 01 08 09 71 00 00 00 33 56 f3 28 01 01  (gprs_subscriber.c:210)

20240424190545665 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:775)

20240424190545666 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190545666 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190545666 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190545666 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190545666 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190545666 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190545666 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:141)

20240424190545666 DGPRS INFO SUBSCR(901700000033653) Received GSUP message OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT (gprs_subscriber.c:723)

20240424190545666 DGPRS DEBUG SUBSCR(901700000033653) Updating subscriber data (gprs_subscriber.c:839)

20240424190545666 DMM INFO MM(901700000033653/d5278993) Subscriber data update (mmctx.c:430)

20240424190545666 DMM DEBUG MM(901700000033653/d5278993) Updating authorization (accepted -> accepted) (sgsn_auth.c:217)

20240424190545666 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:775)

20240424190545865 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 52 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190545865 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190545865 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190545865 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190545865 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190545865 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=13,D=40060009000001000440020300) (sccp_scrc.c:472)

20240424190545865 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190545865 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190545865 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190545865 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190545865 DRANAP DEBUG N-DATA.ind(1, 40 06 00 09 00 00 01 00 04 40 02 03 00 ) (iu_client.c:923)

20240424190545865 DMM NOTICE Received unsupported RANAP unsuccessful outcome procedure Security Mode Control (CO) from RNC, ignoring (ranap_common_cn.c:237)

20240424190545865 DMM ERROR Not calling cn_ranap_handle_co() due to rc=-1 (ranap_common_cn.c:323)

20240424190545865 DMM INFO Freeing RANAP unsuccessful outcome procedure (CO) from RNC not implemented (ranap_common_cn.c:268)

20240424190551650 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{IuSecurityCommand}: Timeout of T3350 (fsm.c:317)

gmm_attach_timer_cb(fi->state=5, ctx->num_T_exp=1)

20240424190553104 DLGSUP INFO GSUP ping callback (connected, got PONG) (gsup_client.c:266)

20240424190553104 DLGSUP DEBUG GSUP sending PING (gsup_client.c:288)

20240424190553104 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190553104 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190553104 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190553104 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190553105 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190553105 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190553105 DLMI DEBUG PONG! (ipa.c:533)

20240424190553105 DLGSUP DEBUG GSUP receiving PONG (gsup_client.c:225)

20240424190558778 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 136 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190558778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190558778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190558778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190558778 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190558778 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=95,D=0014405b00000400104039380801037560000141f805f4e969ca4409f107b043fa211b73432b259662004000000012708b198801000000004bc28c6620040000000100e0000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190558778 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190558778 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190558778 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190558778 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190558778 DRANAP DEBUG N-DATA.ind(1, 00 14 40 5b 00 00 04 00 10 40 39 38 08 01 03 75 60 00 01 41 f8 05 f4 e9 69 ca 44 09 f1 07 b0 43 fa 21 1b 73 43 2b 25 96 62 00 40 00 00 00 12 70 8b 19 88 01 00 00 00 00 4b c2 8c 66 20 04 00 00 00 01 00 e0 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190558778 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190558778 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190558778 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190558778 DMM INFO MM(901700000033653/d5278993) -> GMM ATTACH REQUEST MI(TMSI-0xE969CA44) type="GPRS attach"  (gprs_gmm.c:1283)

20240424190558779 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{CommonProcedureInitiated}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1161)

20240424190558779 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{IuSecurityCommand}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1387)

gmm_attach_allstate_action(event=0, fi->state=5)

20240424190603098 DGPRS DEBUG Checking for inactive LLMEs, time = 27096 (sgsn.c:128)

20240424190613105 DLGSUP INFO GSUP ping callback (connected, got PONG) (gsup_client.c:266)

20240424190613105 DLGSUP DEBUG GSUP sending PING (gsup_client.c:288)

20240424190613105 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190613105 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190613105 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190613105 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190613106 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190613106 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190613106 DLMI DEBUG PONG! (ipa.c:533)

20240424190613106 DLGSUP DEBUG GSUP receiving PONG (gsup_client.c:225)

20240424190613778 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 136 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190613778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190613778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190613778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190613778 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190613778 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=95,D=0014405b00000400104039380801037560000141f805f4e969ca4409f107b043fa211b73432b259662004000000012708b198801000000004bc28c6620040000000100e0000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190613778 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190613778 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190613778 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190613778 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190613778 DRANAP DEBUG N-DATA.ind(1, 00 14 40 5b 00 00 04 00 10 40 39 38 08 01 03 75 60 00 01 41 f8 05 f4 e9 69 ca 44 09 f1 07 b0 43 fa 21 1b 73 43 2b 25 96 62 00 40 00 00 00 12 70 8b 19 88 01 00 00 00 00 4b c2 8c 66 20 04 00 00 00 01 00 e0 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190613778 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190613778 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190613778 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190613778 DMM INFO MM(901700000033653/d5278993) -> GMM ATTACH REQUEST MI(TMSI-0xE969CA44) type="GPRS attach"  (gprs_gmm.c:1283)

20240424190613779 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{CommonProcedureInitiated}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1161)

20240424190613779 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{IuSecurityCommand}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1387)

gmm_attach_allstate_action(event=0, fi->state=5)

20240424190628878 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 136 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190628878 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190628878 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190628878 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190628878 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190628878 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=95,D=0014405b00000400104039380801037560000141f805f4e969ca4409f107b043fa211b73432b259662004000000012708b198801000000004bc28c6620040000000100e0000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190628878 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190628878 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190628878 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190628878 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190628878 DRANAP DEBUG N-DATA.ind(1, 00 14 40 5b 00 00 04 00 10 40 39 38 08 01 03 75 60 00 01 41 f8 05 f4 e9 69 ca 44 09 f1 07 b0 43 fa 21 1b 73 43 2b 25 96 62 00 40 00 00 00 12 70 8b 19 88 01 00 00 00 00 4b c2 8c 66 20 04 00 00 00 01 00 e0 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190628878 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190628878 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190628878 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190628878 DMM INFO MM(901700000033653/d5278993) -> GMM ATTACH REQUEST MI(TMSI-0xE969CA44) type="GPRS attach"  (gprs_gmm.c:1283)

20240424190628878 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{CommonProcedureInitiated}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1161)

20240424190628878 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{IuSecurityCommand}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1387)

gmm_attach_allstate_action(event=0, fi->state=5)

20240424190633099 DGPRS DEBUG Checking for inactive LLMEs, time = 27126 (sgsn.c:128)

20240424190633106 DLGSUP INFO GSUP ping callback (connected, got PONG) (gsup_client.c:266)

20240424190633106 DLGSUP DEBUG GSUP sending PING (gsup_client.c:288)

20240424190633107 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190633107 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190633107 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190633107 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190633107 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190633107 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190633108 DLMI DEBUG PONG! (ipa.c:533)

20240424190633108 DLGSUP DEBUG GSUP receiving PONG (gsup_client.c:225)

20240424190644248 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 136 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190644248 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190644248 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190644248 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190644248 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190644248 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=95,D=0014405b00000400104039380801037560000141f805f4e969ca4409f107b043fa211b73432b259662004000000012708b198801000000004bc28c6620040000000100e0000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190644248 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190644248 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190644248 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190644248 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190644248 DRANAP DEBUG N-DATA.ind(1, 00 14 40 5b 00 00 04 00 10 40 39 38 08 01 03 75 60 00 01 41 f8 05 f4 e9 69 ca 44 09 f1 07 b0 43 fa 21 1b 73 43 2b 25 96 62 00 40 00 00 00 12 70 8b 19 88 01 00 00 00 00 4b c2 8c 66 20 04 00 00 00 01 00 e0 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190644248 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190644248 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190644248 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190644248 DMM INFO MM(901700000033653/d5278993) -> GMM ATTACH REQUEST MI(TMSI-0xE969CA44) type="GPRS attach"  (gprs_gmm.c:1283)

20240424190644248 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{CommonProcedureInitiated}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1161)

20240424190644248 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{IuSecurityCommand}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1387)

gmm_attach_allstate_action(event=0, fi->state=5)

20240424190653107 DLGSUP INFO GSUP ping callback (connected, got PONG) (gsup_client.c:266)

20240424190653107 DLGSUP DEBUG GSUP sending PING (gsup_client.c:288)

20240424190653107 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190653107 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190653107 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190653107 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190653107 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190653107 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190653107 DLMI DEBUG PONG! (ipa.c:533)

20240424190653107 DLGSUP DEBUG GSUP receiving PONG (gsup_client.c:225)

20240424190658519 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 52 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190658519 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190658519 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190658519 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190658519 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190658519 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=13,D=000b40090000010004400209c0) (sccp_scrc.c:472)

20240424190658519 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190658519 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190658519 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190658519 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190658519 DRANAP DEBUG N-DATA.ind(1, 00 0b 40 09 00 00 01 00 04 40 02 09 c0 ) (iu_client.c:923)

20240424190658519 DMM DEBUG Rx CO IM (Iu Release Request) (ranap_common_cn.c:41)

20240424190658519 DMM DEBUG Decoding message RANAP_Iu_ReleaseRequestIEs (ranap_decoder.c:2571) (ranap_decoder.c:2571)

20240424190658519 DRANAP NOTICE handle_co(dir=1, proc=11) (iu_client.c:623)

20240424190658519 DRANAP INFO Received Iu Release Request, Sending Release Command (iu_client.c:565)

20240424190658519 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190658519 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190658519 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000002), PART(T=Data,L=13,D=000100090000010004400209c0) (sccp_scrc.c:401)

20240424190658519 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190658519 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190658519 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190658519 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190658524 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 48 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190658524 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190658524 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190658524 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190658524 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190658524 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=7,D=20010003000000) (sccp_scrc.c:472)

20240424190658524 DLSCCP DEBUG Received CO:CODT for local reference 1 (sccp_scoc.c:1823)

20240424190658524 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190658524 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190658524 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190658524 DRANAP DEBUG N-DATA.ind(1, 20 01 00 03 00 00 00 ) (iu_client.c:923)

20240424190658524 DMM DEBUG Rx CO SO (Iu Release) (ranap_common_cn.c:134)

20240424190658524 DMM DEBUG Decoding message RANAP_Iu_ReleaseCompleteIEs (ranap_decoder.c:73) (ranap_decoder.c:73)

20240424190658524 DRANAP NOTICE handle_co(dir=2, proc=1) (iu_client.c:623)

20240424190658524 DRANAP DEBUG Submit Iu event to upper layer: RANAP_IU_EVENT_IU_RELEASE (iu_client.c:131)

20240424190658524 DMM INFO MM(901700000033653/d5278993) IU release (cause=RANAP_IU_EVENT_IU_RELEASE) (gprs_ranap.c:141)

20240424190658524 DMM DEBUG MM_STATE_Iu(1)[0x5d521a563d10]{Detached}: Received Event E_PMM_PS_CONN_RELEASE (gprs_ranap.c:142)

20240424190658524 DMM ERROR MM_STATE_Iu(1)[0x5d521a563d10]{Detached}: Event E_PMM_PS_CONN_RELEASE not permitted (gprs_ranap.c:142)

20240424190658524 DLSCCP DEBUG Received SCCP User Primitive (N-DISCONNECT.request) (sccp_scoc.c:1890)

20240424190658524 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_scoc.c:1931)

20240424190658524 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELRE,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000002), PART(T=Source Reference,L=4,D=00000001), PART(T=Cause,L=4,D=00000300) (sccp_scrc.c:401)

20240424190658524 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190658524 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190658524 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190658524 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190658524 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{ACTIVE}: state_chg to DISCONN_PEND (sccp_scoc.c:1213)

20240424190658524 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{IuSecurityCommand}: Received Event E_REJECT (gprs_ranap.c:148)

gmm_attach_allstate_action(event=8, fi->state=5)

20240424190658524 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{IuSecurityCommand}: state_chg to Reject (gprs_gmm_attach.c:407)

20240424190658524 DREF DEBUG subscr 901700000033653 usage increases to: 2 (mmctx.c:326)

20240424190658524 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:178)

20240424190658524 DGPRS INFO SUBSCR(901700000033653) purging MS subscriber (gprs_subscriber.c:785)

20240424190658524 DGPRS INFO SUBSCR(901700000033653) Sending GSUP, will send: 0c 01 08 09 71 00 00 00 33 56 f3 09 00 28 01 01  (gprs_subscriber.c:210)

20240424190658524 DREF DEBUG subscr 901700000033653 usage decreases to: 0 (mmctx.c:328)

20240424190658524 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563ab0]{Reject}: Deallocated (fsm.c:568)

20240424190658524 DMM DEBUG MM_STATE_Gb[0x5d521a563be0]{Idle}: Deallocated (fsm.c:568)

20240424190658524 DMM DEBUG MM_STATE_Iu(1)[0x5d521a563d10]{Detached}: Deallocated (fsm.c:568)

20240424190658524 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{CommonProcedureInitiated}: Deallocated (fsm.c:568)

20240424190658525 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190658525 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190658525 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190658525 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190658525 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190658525 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190658526 DGPRS INFO GSUP(901700000033653) Completing purge MS (gprs_subscriber.c:579)

20240424190658526 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190658526 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190658526 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190658526 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190658526 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190658526 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:RELCO,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000001), PART(T=Source Reference,L=4,D=00000002) (sccp_scrc.c:472)

20240424190658526 DLSCCP DEBUG Received CO:RELCO for local reference 1 (sccp_scoc.c:1823)

20240424190658526 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind (sccp_scoc.c:1857)

20240424190658526 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{DISCONN_PEND}: state_chg to IDLE (sccp_scoc.c:1331)

20240424190658526 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (sccp_scoc.c:551)

20240424190658526 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{IDLE}: Freeing instance (sccp_scoc.c:551)

20240424190658526 DLSCCP DEBUG SCCP-SCOC(1)[0x5d521a5622b0]{IDLE}: Deallocated (fsm.c:568)

20240424190703100 DGPRS DEBUG Checking for inactive LLMEs, time = 27156 (sgsn.c:128)

20240424190713108 DLGSUP INFO GSUP ping callback (connected, got PONG) (gsup_client.c:266)

20240424190713108 DLGSUP DEBUG GSUP sending PING (gsup_client.c:288)

20240424190713108 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190713108 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190713108 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190713108 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190713109 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190713109 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190713109 DLMI DEBUG PONG! (ipa.c:533)

20240424190713109 DLGSUP DEBUG GSUP receiving PONG (gsup_client.c:225)

20240424190714503 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 172 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190714503 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190714503 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190714503 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190714503 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190714503 DLSUA DEBUG IEI 259: Parsed Addr: RI=2,PC=188,SSN=142 (sccp2sua.c:334)

20240424190714503 DLSUA DEBUG IEI 258: Parsed Addr: RI=2,PC=189,SSN=142 (sccp2sua.c:334)

20240424190714504 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CORE,V=0,LEN=0), PART(T=Protocol Class,L=4,D=00000002), PART(T=Source Reference,L=4,D=00000003), PART(T=Destination Address,L=20,D=0002000380020008000000bc800300080000008e), PART(T=Source Address,L=20,D=0002000380020008000000bd800300080000008e), PART(T=Data,L=116,D=001340700000070003400180000f40060009f107b04300374001fa003a40080009f107ffff000000104039380801037560000141f805f4e969ca4409f107b043fa211b73432b259662004000000012708b198801000000004bc28c6620040000000100e0004f40030000170056400509f1070017) (sccp_scrc.c:472)

20240424190714504 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190714504 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190714504 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190714504 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190714504 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{IDLE}: Allocated (fsm.c:456)

20240424190714504 DLSCCP DEBUG Received CO:CORE for local reference 2 (sccp_scoc.c:1823)

20240424190714504 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{IDLE}: Received Event RCOC-CONNECT.ind (sccp_scoc.c:1857)

20240424190714504 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190714504 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190714504 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190714504 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{IDLE}: state_chg to CONN_PEND_IN (sccp_scoc.c:1018)

20240424190714504 DLSCCP DEBUG Delivering N-CONNECT.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190714504 DRANAP DEBUG sccp_sap_up(N-CONNECT.indication) (iu_client.c:865)

20240424190714504 DRANAP DEBUG N-CONNECT.ind(X->2) (iu_client.c:874)

20240424190714504 DLSCCP DEBUG Received SCCP User Primitive (N-CONNECT.response) (sccp_scoc.c:1890)

20240424190714504 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{CONN_PEND_IN}: Received Event N-CONNECT.resp (sccp_scoc.c:1931)

20240424190714504 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:COAK,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Protocol Class,L=4,D=00000002), PART(T=Destination Reference,L=4,D=00000003), PART(T=Source Reference,L=4,D=00000002), PART(T=Sequence Control,L=4,D=00000000), PART(T=Source Address,L=20,D=0002000380020008000000bc800300080000008e), PART(T=Destination Address,L=20,D=0002000380020008000000bd800300080000008e) (sccp_scrc.c:401)

20240424190714504 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190714504 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190714504 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190714504 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190714504 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190714504 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190714504 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190714504 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190714504 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{CONN_PEND_IN}: state_chg to ACTIVE (sccp_scoc.c:1045)

20240424190714504 DMM DEBUG Rx CO IM (Initial UE Message) (ranap_common_cn.c:41)

20240424190714504 DMM DEBUG Decoding message RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)

20240424190714504 DRANAP NOTICE handle_co_initial(dir=1, proc=19) (iu_client.c:600)

20240424190714504 DMM INFO MM(---/ffffffff) -> GMM ATTACH REQUEST MI(TMSI-0xE969CA44) type="GPRS attach"  (gprs_gmm.c:1283)

20240424190714504 DLGLOBAL DEBUG validating counter group 0x5d5218eceb00(sgsn:mmctx) with 11 counters (rate_ctr.c:86)

20240424190714504 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{Deregistered}: Allocated (fsm.c:456)

20240424190714504 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{Init}: Allocated (fsm.c:456)

20240424190714504 DMM DEBUG MM_STATE_Gb[0x5d521a563be0]{Idle}: Allocated (fsm.c:456)

20240424190714504 DMM DEBUG MM_STATE_Iu[0x5d521a563ab0]{Detached}: Allocated (fsm.c:456)

20240424190714504 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1161)

20240424190714504 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{Deregistered}: state_chg to CommonProcedureInitiated (gprs_gmm_fsm.c:49)

20240424190714504 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{Init}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1387)

gmm_attach_allstate_action(event=0, fi->state=0)

20240424190714504 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{Init}: state_chg to CheckIdentity (gprs_gmm_attach.c:52)

20240424190714504 DMM DEBUG MM(/eadd6c93) <- GMM IDENTITY REQUEST: mi_type=IMEI (gprs_gmm.c:448)

20240424190714504 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 2) (iu_client.c:510)

20240424190714504 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190714504 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190714504 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000003), PART(T=Data,L=20,D=001440100000020010400403081502003b400100) (sccp_scrc.c:401)

20240424190714504 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190714504 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190714504 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190714504 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190714778 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 92 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190714778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190714778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190714778 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190714778 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190714778 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000002), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=50,D=0014402e0000040010400c0b0816088a06280716211501000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190714778 DLSCCP DEBUG Received CO:CODT for local reference 2 (sccp_scoc.c:1823)

20240424190714778 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190714778 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190714778 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190714778 DRANAP DEBUG N-DATA.ind(2, 00 14 40 2e 00 00 04 00 10 40 0c 0b 08 16 08 8a 06 28 07 16 21 15 01 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190714778 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190714778 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190714778 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190714778 DMM DEBUG MM(/eadd6c93) -> GMM IDENTITY RESPONSE: MI=IMEI-860827061125110 (gprs_gmm.c:1101)

20240424190714778 DMM ERROR MM(/eadd6c93) Stopping MM timer 3370 but 0 is running (gprs_gmm.c:116)

20240424190714778 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{CheckIdentity}: Received Event E_IDEN_RESP_RECV (gprs_gmm.c:1145)

20240424190714778 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{CheckIdentity}: state_chg to CheckIdentity (gprs_gmm_attach.c:113)

20240424190714778 DMM DEBUG MM(/eadd6c93) <- GMM IDENTITY REQUEST: mi_type=IMSI (gprs_gmm.c:448)

20240424190714778 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 2) (iu_client.c:510)

20240424190714778 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190714778 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190714778 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000003), PART(T=Data,L=20,D=001440100000020010400403081501003b400100) (sccp_scrc.c:401)

20240424190714778 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190714778 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190714778 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190714778 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190715048 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 92 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190715048 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190715048 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190715048 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190715048 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190715048 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000002), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=50,D=0014402e0000040010400c0b0816089910070000306335000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190715048 DLSCCP DEBUG Received CO:CODT for local reference 2 (sccp_scoc.c:1823)

20240424190715048 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190715048 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190715048 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190715048 DRANAP DEBUG N-DATA.ind(2, 00 14 40 2e 00 00 04 00 10 40 0c 0b 08 16 08 99 10 07 00 00 30 63 35 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190715048 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190715048 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190715048 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190715048 DMM DEBUG MM(/eadd6c93) -> GMM IDENTITY RESPONSE: MI=IMSI-901700000033653 (gprs_gmm.c:1101)

20240424190715048 DMM ERROR MM(/eadd6c93) Stopping MM timer 3370 but 0 is running (gprs_gmm.c:116)

20240424190715048 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{CheckIdentity}: Received Event E_IDEN_RESP_RECV (gprs_gmm.c:1145)

20240424190715048 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{CheckIdentity}: state_chg to Authenticate (gprs_gmm_attach.c:115)

20240424190715048 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{Authenticate}: state_chg to AskVLR (gprs_gmm_attach.c:143)

20240424190715048 DMM DEBUG MM(901700000033653/eadd6c93) Requesting authorization (sgsn_auth.c:160)

20240424190715048 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:884)

20240424190715048 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (sgsn_auth.c:173)

20240424190715048 DMM INFO MM(901700000033653/eadd6c93) Requesting authentication tuples (sgsn_auth.c:184)

20240424190715048 DMM DEBUG MM(901700000033653/eadd6c93) Requesting subscriber authentication info (gprs_subscriber.c:921)

20240424190715048 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:865)

20240424190715048 DGPRS INFO SUBSCR(901700000033653) requesting auth info (gprs_subscriber.c:805)

20240424190715048 DGPRS INFO SUBSCR(901700000033653) Sending GSUP, will send: 08 01 08 09 71 00 00 00 33 56 f3 28 01 01  (gprs_subscriber.c:210)

20240424190715048 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:930)

20240424190715048 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190715048 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190715049 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190715049 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190715065 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190715066 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190715066 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:141)

20240424190715066 DGPRS INFO SUBSCR(901700000033653) Received GSUP message OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT (gprs_subscriber.c:723)

20240424190715066 DGPRS INFO SUBSCR(901700000033653) Got SendAuthenticationInfoResult, num_auth_vectors = 5 (gprs_subscriber.c:242)

20240424190715066 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 0 (gprs_subscriber.c:255)

20240424190715066 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 1 (gprs_subscriber.c:255)

20240424190715066 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 2 (gprs_subscriber.c:255)

20240424190715066 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 3 (gprs_subscriber.c:255)

20240424190715066 DGPRS DEBUG SUBSCR(901700000033653) Adding auth tuple, cksn = 4 (gprs_subscriber.c:255)

20240424190715066 DGPRS DEBUG SUBSCR(901700000033653) Updating subscriber authentication info (gprs_subscriber.c:850)

20240424190715066 DMM INFO MM(901700000033653/eadd6c93) Subscriber data update (mmctx.c:430)

20240424190715066 DMM DEBUG MM(901700000033653/eadd6c93) Updating authorization (unknown -> authenticate) (sgsn_auth.c:217)

20240424190715066 DMM INFO MM(901700000033653/eadd6c93) Got authorization update: state unknown -> authenticate (sgsn_auth.c:246)

20240424190715066 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{AskVLR}: Received Event E_VLR_ANSWERED (gprs_gmm.c:1008)

20240424190715066 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{AskVLR}: state_chg to Authenticate (gprs_gmm_attach.c:256)

20240424190715066 DMM INFO MM(901700000033653/eadd6c93) <- GMM AUTH AND CIPHERING REQ (rand = 97 b9 8b 7c 87 e8 a9 39 33 5f d6 fe e7 8c 56 5a , mmctx_is_r99=1, vec->auth_types=0x3, autn = 0a ae 66 9c f3 40 00 00 45 39 ef 82 46 49 2b 8b ) (gprs_gmm.c:500)

20240424190715066 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 2) (iu_client.c:510)

20240424190715066 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190715066 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190715066 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000003), PART(T=Data,L=57,D=001440350000020010402928081210102197b98b7c87e8a939335fd6fee78c565a8028100aae669cf34000004539ef8246492b8b003b400100) (sccp_scrc.c:401)

20240424190715066 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190715066 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190715066 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190715066 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190715066 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:775)

20240424190715508 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 104 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190715508 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190715508 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190715508 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190715508 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190715508 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000002), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=64,D=0014403c0000040010401a19081301224e1e630023098306280716211551f42904afb0d661000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190715508 DLSCCP DEBUG Received CO:CODT for local reference 2 (sccp_scoc.c:1823)

20240424190715508 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190715508 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190715508 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190715508 DRANAP DEBUG N-DATA.ind(2, 00 14 40 3c 00 00 04 00 10 40 1a 19 08 13 01 22 4e 1e 63 00 23 09 83 06 28 07 16 21 15 51 f4 29 04 af b0 d6 61 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190715509 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190715509 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190715509 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190715509 DMM INFO MM(901700000033653/eadd6c93) -> GMM AUTH AND CIPH RESPONSE (gprs_gmm.c:637)

20240424190715509 DMM ERROR MM(901700000033653/eadd6c93) Stopping MM timer 3360 but 0 is running (gprs_gmm.c:116)

20240424190715509 DMM DEBUG MM(901700000033653/eadd6c93) checking auth: received UMTS RES = 4e 1e 63 00 af b0 d6 61  (gprs_gmm.c:683)

20240424190715509 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{Authenticate}: Received Event E_AUTH_RESP_RECV_SUCCESS (gprs_gmm.c:698)

20240424190715509 DMM DEBUG MM(901700000033653/eadd6c93) Requesting authorization (sgsn_auth.c:160)

20240424190715509 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:865)

20240424190715509 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (sgsn_auth.c:173)

20240424190715509 DMM INFO MM(901700000033653/eadd6c93) Missing information, requesting subscriber data (sgsn_auth.c:195)

20240424190715509 DMM DEBUG MM(901700000033653/eadd6c93) Requesting subscriber data update (gprs_subscriber.c:894)

20240424190715509 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:865)

20240424190715509 DGPRS INFO SUBSCR(901700000033653) subscriber data is not available (gprs_subscriber.c:818)

20240424190715509 DGPRS INFO SUBSCR(901700000033653) Sending GSUP, will send: 04 01 08 09 71 00 00 00 33 56 f3 28 01 01 2a 01 02  (gprs_subscriber.c:210)

20240424190715509 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:903)

20240424190715509 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{Authenticate}: state_chg to IuSecurityCommand (gprs_gmm_attach.c:178)

20240424190715509 DMM DEBUG MM(901700000033653/eadd6c93) Iu Security Mode Command: not sending encryption key (UEA encryption mask = 0x1) (gprs_gmm_attach.c:275)

20240424190715509 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190715509 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190715509 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000003), PART(T=Data,L=34,D=0006001e000002000c001208082b1e4f0ee850fcc3d1130492a24fac3e004b000140) (sccp_scrc.c:401)

20240424190715509 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190715509 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190715509 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190715509 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190715509 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190715509 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190715509 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190715509 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190715526 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190715526 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190715526 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:141)

20240424190715526 DGPRS INFO SUBSCR(901700000033653) Received GSUP message OSMO_GSUP_MSGT_INSERT_DATA_REQUEST (gprs_subscriber.c:723)

20240424190715526 DGPRS INFO SUBSCR(901700000033653) Will set PDP info, context id = 1, APN = 01 2a  (gprs_subscriber.c:367)

20240424190715526 DGPRS DEBUG SUBSCR(901700000033653) Updating subscriber data (gprs_subscriber.c:839)

20240424190715526 DMM INFO MM(901700000033653/eadd6c93) Subscriber data update (mmctx.c:430)

20240424190715526 DMM DEBUG MM(901700000033653/eadd6c93) Updating authorization (authenticate -> accepted) (sgsn_auth.c:217)

20240424190715526 DMM INFO MM(901700000033653/eadd6c93) Got authorization update: state authenticate -> accepted (sgsn_auth.c:246)

20240424190715526 DMM NOTICE MM(901700000033653/eadd6c93) Authorized, continuing procedure, IMSI=901700000033653 (gprs_gmm.c:1017)

20240424190715526 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{IuSecurityCommand}: Received Event E_VLR_ANSWERED (gprs_gmm.c:1022)

---------------------st_iu_security_cmd(event=9)

20240424190715526 DGPRS INFO SUBSCR(901700000033653) Sending GSUP, will send: 12 01 08 09 71 00 00 00 33 56 f3 28 01 01  (gprs_subscriber.c:210)

20240424190715526 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:775)

20240424190715526 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190715526 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190715526 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190715526 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190715526 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190715527 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190715527 DREF DEBUG subscr 901700000033653 usage increases to: 2 (gprs_subscriber.c:141)

20240424190715527 DGPRS INFO SUBSCR(901700000033653) Received GSUP message OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT (gprs_subscriber.c:723)

20240424190715527 DGPRS DEBUG SUBSCR(901700000033653) Updating subscriber data (gprs_subscriber.c:839)

20240424190715527 DMM INFO MM(901700000033653/eadd6c93) Subscriber data update (mmctx.c:430)

20240424190715527 DMM DEBUG MM(901700000033653/eadd6c93) Updating authorization (accepted -> accepted) (sgsn_auth.c:217)

20240424190715527 DREF DEBUG subscr 901700000033653 usage decreases to: 1 (gprs_subscriber.c:775)

20240424190715787 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 52 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190715787 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190715787 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190715787 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190715787 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190715787 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000002), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=12,D=200600080000010006000100) (sccp_scrc.c:472)

20240424190715787 DLSCCP DEBUG Received CO:CODT for local reference 2 (sccp_scoc.c:1823)

20240424190715787 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190715787 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190715787 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190715787 DRANAP DEBUG N-DATA.ind(2, 20 06 00 08 00 00 01 00 06 00 01 00 ) (iu_client.c:923)

20240424190715787 DMM DEBUG Rx CO SO (Security Mode Control) (ranap_common_cn.c:134)

20240424190715787 DMM DEBUG Decoding message RANAP_SecurityModeCompleteIEs (ranap_decoder.c:1623) (ranap_decoder.c:1623)

20240424190715787 DRANAP NOTICE handle_co(dir=2, proc=6) (iu_client.c:623)

20240424190715787 DRANAP DEBUG Submit Iu event to upper layer: RANAP_IU_EVENT_SECURITY_MODE_COMPLETE (iu_client.c:131)

20240424190715787 DRANAP INFO Transmitting RANAP CommonID (SCCP conn_id 2) (iu_client.c:366)

20240424190715787 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190715787 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190715787 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000003), PART(T=Data,L=20,D=000f4010000001001740095009710000003356f3) (sccp_scrc.c:401)

20240424190715787 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190715787 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190715787 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190715787 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190715787 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{IuSecurityCommand}: Received Event E_IU_SECURITY_CMD_COMPLETE (gprs_ranap.c:165)

---------------------st_iu_security_cmd(event=4)

20240424190715787 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{IuSecurityCommand}: state_chg to WaitAttachComplete (gprs_gmm_attach.c:299)

st_accept_on_enter

20240424190715787 DMM INFO MM(901700000033653/eadd6c93) <- GMM ATTACH ACCEPT (new P-TMSI=0xeadd6c93) (gprs_gmm.c:316)

20240424190715787 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 2) (iu_client.c:510)

20240424190715787 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190715787 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190715787 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000003), PART(T=Data,L=37,D=0014402100000200104015140802012a4409f107b043fa17161805f4eadd6c93003b400100) (sccp_scrc.c:401)

20240424190715787 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190715787 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190715787 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190715787 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190716068 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 80 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190716068 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190716068 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190716068 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190716068 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190716068 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000002), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=41,D=0014402500000400104003020803000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190716068 DLSCCP DEBUG Received CO:CODT for local reference 2 (sccp_scoc.c:1823)

20240424190716068 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190716068 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190716068 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190716068 DRANAP DEBUG N-DATA.ind(2, 00 14 40 25 00 00 04 00 10 40 03 02 08 03 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190716068 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190716068 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190716068 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190716068 DMM INFO MM(901700000033653/eadd6c93) -> GMM ATTACH COMPLETE (gprs_gmm.c:1414)

20240424190716068 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190716068 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190716068 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000003), PART(T=Data,L=12,D=000100080000010004400122) (sccp_scrc.c:401)

20240424190716068 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190716068 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190716068 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190716068 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190716069 DMM ERROR MM(901700000033653/eadd6c93) Stopping MM timer 3350 but 0 is running (gprs_gmm.c:116)

20240424190716069 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{CommonProcedureInitiated}: Received Event E_GMM_ATTACH_SUCCESS (gprs_gmm.c:1426)

20240424190716069 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{CommonProcedureInitiated}: state_chg to Registered.NORMAL (gprs_gmm_fsm.c:72)

20240424190716069 DMM DEBUG MM_STATE_Iu(2)[0x5d521a563ab0]{Detached}: Received Event E_PMM_PS_ATTACH (gprs_gmm.c:1429)

20240424190716069 DMM DEBUG MM_STATE_Iu(2)[0x5d521a563ab0]{Detached}: state_chg to Connected (gprs_mm_state_iu_fsm.c:65)

20240424190716069 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{WaitAttachComplete}: Received Event E_ATTACH_COMPLETE_RECV (gprs_gmm.c:1441)

20240424190716069 DMM DEBUG GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x5d521a563d10]{WaitAttachComplete}: state_chg to Init (gprs_gmm_attach.c:215)

20240424190716073 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 48 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190716073 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190716073 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190716073 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190716073 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190716073 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000002), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=7,D=20010003000000) (sccp_scrc.c:472)

20240424190716073 DLSCCP DEBUG Received CO:CODT for local reference 2 (sccp_scoc.c:1823)

20240424190716073 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190716073 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190716073 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190716073 DRANAP DEBUG N-DATA.ind(2, 20 01 00 03 00 00 00 ) (iu_client.c:923)

20240424190716073 DMM DEBUG Rx CO SO (Iu Release) (ranap_common_cn.c:134)

20240424190716073 DMM DEBUG Decoding message RANAP_Iu_ReleaseCompleteIEs (ranap_decoder.c:73) (ranap_decoder.c:73)

20240424190716073 DRANAP NOTICE handle_co(dir=2, proc=1) (iu_client.c:623)

20240424190716073 DRANAP DEBUG Submit Iu event to upper layer: RANAP_IU_EVENT_IU_RELEASE (iu_client.c:131)

20240424190716073 DMM INFO MM(901700000033653/eadd6c93) IU release (cause=RANAP_IU_EVENT_IU_RELEASE) (gprs_ranap.c:141)

20240424190716073 DMM DEBUG MM_STATE_Iu(2)[0x5d521a563ab0]{Connected}: Received Event E_PMM_PS_CONN_RELEASE (gprs_ranap.c:142)

20240424190716073 DLSCCP DEBUG Received SCCP User Primitive (N-DISCONNECT.request) (sccp_scoc.c:1890)

20240424190716073 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_scoc.c:1931)

20240424190716073 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELRE,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000003), PART(T=Source Reference,L=4,D=00000002), PART(T=Cause,L=4,D=00000300) (sccp_scrc.c:401)

20240424190716073 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190716073 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190716073 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190716073 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190716073 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{ACTIVE}: state_chg to DISCONN_PEND (sccp_scoc.c:1213)

20240424190716073 DMM DEBUG MM_STATE_Iu(2)[0x5d521a563ab0]{Connected}: state_chg to Idle (gprs_mm_state_iu_fsm.c:79)

20240424190716074 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190716074 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190716074 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190716074 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190716074 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190716074 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:RELCO,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000002), PART(T=Source Reference,L=4,D=00000003) (sccp_scrc.c:472)

20240424190716074 DLSCCP DEBUG Received CO:RELCO for local reference 2 (sccp_scoc.c:1823)

20240424190716074 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind (sccp_scoc.c:1857)

20240424190716074 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{DISCONN_PEND}: state_chg to IDLE (sccp_scoc.c:1331)

20240424190716074 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (sccp_scoc.c:551)

20240424190716074 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{IDLE}: Freeing instance (sccp_scoc.c:551)

20240424190716074 DLSCCP DEBUG SCCP-SCOC(2)[0x5d521a5622b0]{IDLE}: Deallocated (fsm.c:568)

20240424190717862 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 128 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190717862 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190717862 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190717862 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190717862 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190717862 DLSUA DEBUG IEI 259: Parsed Addr: RI=2,PC=188,SSN=142 (sccp2sua.c:334)

20240424190717862 DLSUA DEBUG IEI 258: Parsed Addr: RI=2,PC=189,SSN=142 (sccp2sua.c:334)

20240424190717862 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CORE,V=0,LEN=0), PART(T=Protocol Class,L=4,D=00000002), PART(T=Source Reference,L=4,D=00000004), PART(T=Destination Address,L=20,D=0002000380020008000000bc800300080000008e), PART(T=Source Address,L=20,D=0002000380020008000000bd800300080000008e), PART(T=Data,L=73,D=001340450000070003400180000f40060009f107b04300374001fa003a40080009f107ffff00000010400e0d080c0005f4eadd6c9332020000004f40030000170056400509f1070017) (sccp_scrc.c:472)

20240424190717862 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190717862 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190717862 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190717862 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190717862 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{IDLE}: Allocated (fsm.c:456)

20240424190717862 DLSCCP DEBUG Received CO:CORE for local reference 3 (sccp_scoc.c:1823)

20240424190717862 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{IDLE}: Received Event RCOC-CONNECT.ind (sccp_scoc.c:1857)

20240424190717862 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190717862 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190717862 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190717862 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{IDLE}: state_chg to CONN_PEND_IN (sccp_scoc.c:1018)

20240424190717862 DLSCCP DEBUG Delivering N-CONNECT.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190717862 DRANAP DEBUG sccp_sap_up(N-CONNECT.indication) (iu_client.c:865)

20240424190717862 DRANAP DEBUG N-CONNECT.ind(X->3) (iu_client.c:874)

20240424190717862 DLSCCP DEBUG Received SCCP User Primitive (N-CONNECT.response) (sccp_scoc.c:1890)

20240424190717862 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{CONN_PEND_IN}: Received Event N-CONNECT.resp (sccp_scoc.c:1931)

20240424190717862 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:COAK,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Protocol Class,L=4,D=00000002), PART(T=Destination Reference,L=4,D=00000004), PART(T=Source Reference,L=4,D=00000003), PART(T=Sequence Control,L=4,D=00000000), PART(T=Source Address,L=20,D=0002000380020008000000bc800300080000008e), PART(T=Destination Address,L=20,D=0002000380020008000000bd800300080000008e) (sccp_scrc.c:401)

20240424190717862 DLSUA DEBUG sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190717862 DLSUA DEBUG SUA IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG SUA IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bc 80 03 00 08 00 00 00 8e  (sua.c:386)

20240424190717862 DLSUA DEBUG SUA IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:441)

20240424190717862 DLSUA DEBUG SUA IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:441)

20240424190717862 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190717862 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190717862 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190717862 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190717862 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{CONN_PEND_IN}: state_chg to ACTIVE (sccp_scoc.c:1045)

20240424190717862 DMM DEBUG Rx CO IM (Initial UE Message) (ranap_common_cn.c:41)

20240424190717862 DMM DEBUG Decoding message RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)

20240424190717862 DRANAP NOTICE handle_co_initial(dir=1, proc=19) (iu_client.c:600)

20240424190717862 DMM INFO MM(---/ffffffff) -> GMM SERVICE REQUEST MI(TMSI-0xEADD6C93) type="signalling"  (gprs_gmm.c:1945)

20240424190717862 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{Registered.NORMAL}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1980)

20240424190717862 DMM DEBUG GMM(gmm_fsm)[0x5d521a560230]{Registered.NORMAL}: state_chg to CommonProcedureInitiated (gprs_gmm_fsm.c:81)

20240424190717862 DMM DEBUG MM(901700000033653/eadd6c93) Iu Security Mode Command: not sending encryption key (UEA encryption mask = 0x1) (gprs_gmm.c:945)

20240424190717862 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190717862 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190717862 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000004), PART(T=Data,L=34,D=0006001e000002000c001208082b1e4f0ee850fcc3d1130492a24fac3e004b000100) (sccp_scrc.c:401)

20240424190717862 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190717862 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190717862 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190717862 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190718148 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 52 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190718148 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190718148 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190718148 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190718148 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190718148 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000003), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=12,D=200600080000010006000100) (sccp_scrc.c:472)

20240424190718148 DLSCCP DEBUG Received CO:CODT for local reference 3 (sccp_scoc.c:1823)

20240424190718148 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190718148 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190718148 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190718148 DRANAP DEBUG N-DATA.ind(3, 20 06 00 08 00 00 01 00 06 00 01 00 ) (iu_client.c:923)

20240424190718148 DMM DEBUG Rx CO SO (Security Mode Control) (ranap_common_cn.c:134)

20240424190718148 DMM DEBUG Decoding message RANAP_SecurityModeCompleteIEs (ranap_decoder.c:1623) (ranap_decoder.c:1623)

20240424190718148 DRANAP NOTICE handle_co(dir=2, proc=6) (iu_client.c:623)

20240424190718148 DRANAP DEBUG Submit Iu event to upper layer: RANAP_IU_EVENT_SECURITY_MODE_COMPLETE (iu_client.c:131)

20240424190718148 DRANAP INFO Transmitting RANAP CommonID (SCCP conn_id 3) (iu_client.c:366)

20240424190718148 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190718148 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190718148 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000004), PART(T=Data,L=20,D=000f4010000001001740095009710000003356f3) (sccp_scrc.c:401)

20240424190718148 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190718148 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190718148 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190718148 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190718148 DMM DEBUG MM_STATE_Iu(2)[0x5d521a563ab0]{Idle}: Received Event E_PMM_PS_CONN_ESTABLISH (gprs_gmm.c:980)

20240424190718148 DMM DEBUG MM_STATE_Iu(2)[0x5d521a563ab0]{Idle}: state_chg to Connected (gprs_mm_state_iu_fsm.c:102)

20240424190718148 DMM INFO MM(901700000033653/eadd6c93) <- GMM SERVICE ACCEPT (P-TMSI=0xeadd6c93) (gprs_gmm.c:830)

20240424190718148 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 3) (iu_client.c:510)

20240424190718148 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190718148 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190718148 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000004), PART(T=Data,L=19,D=0014400f0000020010400302080d003b400100) (sccp_scrc.c:401)

20240424190718148 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190718148 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190718148 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190718148 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190718378 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 140 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190718378 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190718378 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190718378 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190718378 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190718378 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000003), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=101,D=001440610000040010403f3e0a41050b0e03001f6000000004000000000000020121280908696e7465726e6574271a8080211001010010810600000000830600000000000d00001000a1000f40060009f107b04300374001fa003a40080009f107ffff0000) (sccp_scrc.c:472)

20240424190718378 DLSCCP DEBUG Received CO:CODT for local reference 3 (sccp_scoc.c:1823)

20240424190718378 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190718378 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190718378 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190718378 DRANAP DEBUG N-DATA.ind(3, 00 14 40 61 00 00 04 00 10 40 3f 3e 0a 41 05 0b 0e 03 00 1f 60 00 00 00 04 00 00 00 00 00 00 02 01 21 28 09 08 69 6e 74 65 72 6e 65 74 27 1a 80 80 21 10 01 01 00 10 81 06 00 00 00 00 83 06 00 00 00 00 00 0d 00 00 10 00 a1 00 0f 40 06 00 09 f1 07 b0 43 00 37 40 01 fa 00 3a 40 08 00 09 f1 07 ff ff 00 00 ) (iu_client.c:923)

20240424190718378 DMM DEBUG Rx CO IM (Direct Transfer) (ranap_common_cn.c:41)

20240424190718378 DMM DEBUG Decoding message RANAP_DirectTransferIEs (ranap_decoder.c:3772) (ranap_decoder.c:3772)

20240424190718378 DRANAP NOTICE handle_co(dir=1, proc=20) (iu_client.c:623)

20240424190718378 DMM INFO MM(901700000033653/eadd6c93) -> ACTIVATE PDP CONTEXT REQ: SAPI=11 NSAPI=5 IETF IPv4  (gprs_sm.c:468)

20240424190718378 DMM INFO MM(901700000033653/eadd6c93) Found GGSN 0 for APN 'internet' (requested 'internet') (mmctx.c:578)

20240424190718378 DMM DEBUG MM(901700000033653/eadd6c93) Using GGSN 0 (gprs_sm.c:312)

20240424190718378 DLGLOBAL DEBUG validating counter group 0x5d5218ecec20(sgsn:pdpctx) with 4 counters (rate_ctr.c:86)

20240424190718378 DLGTP DEBUG Begin pdp_tidset tid = 5356330000007109 (pdp.c:277)

20240424190718378 DLGTP DEBUG End pdp_tidset (pdp.c:286)

20240424190718378 DGPRS NOTICE PDP(901700000033653/0) Create PDP Context (sgsn_libgtp.c:181)

20240424190718379 DLGTP DEBUG Registering seq=4096 in restransmit req queue (gtp.c:399)

20240424190718379 DLGTP DEBUG Packet from 10.10.0.2:2123, length: 111 content: 32 11 00 67 00 00 00 01 10 00 00 00 01 80 08 00 0e 0b 10 00 00 00 01 11 00 00 00 01 7f 00 00 00 01 80 00 06 f1 21 c0 a8 2a 01 84 00 22 80 80 21 10 02 01 00 10 81 06 08 08 08 08 83 06 08 08 04 04 00 0d 04 08 08 08 08 00 0d 04 08 08 04 04 85 00 04 0a 0a 00 02 85 00 04 0a 0a 00 02 87 00 0f 00 03 00 1f 60 00 00 00 04 00 00 00 00 00 00 : Freeing seq=4096 from retransmit req queue (gtp.c:440)

20240424190718379 DGPRS DEBUG libgtp cb_conf(type=16, cause=128, pdp=0x7e6057501058, cbp=0x5d521a5630f0) (sgsn_libgtp.c:629)

20240424190718379 DGPRS INFO PDP(901700000033653/0) Received CREATE PDP CTX CONF, cause=128(Request accepted) (sgsn_libgtp.c:399)

20240424190718379 DRANAP DEBUG Assigning RAB: rab_id=5, ggsn_ip=a0a0002, teid_gn=1, use_x213_nsap=1 (gprs_ranap.c:222)

20240424190718380 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190718380 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190718380 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000004), PART(T=Data,L=85,D=000000510000010036404a0000010035003b380a12de1869ff800c34ff001f400806089e00000000b1400560018cba7f0000113e3500010a0a00020000000000000000000000000000000000014006781c00000000) (sccp_scrc.c:401)

20240424190718380 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190718380 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190718380 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190718380 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190719635 DLSS7 DEBUG 0: asp-asp-clnt-OsmoSGSN: xua_cli_read_cb(): sctp_recvmsg() returned 72 (flags=0x0) (osmo_ss7_asp.c:1030)

20240424190719635 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: Received M3UA Message (XFER:DATA) (m3ua.c:713)

20240424190719635 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer (m3ua.c:542)

20240424190719635 DLM3UA DEBUG 0: asp-asp-clnt-OsmoSGSN: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=188=0.23.4 (m3ua.c:565)

20240424190719635 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=188=0.23.4 as local (osmo_ss7_hmrt.c:282)

20240424190719635 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000003), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=30,D=6000001a000001003440130000010033400c60287c0a0a00140000000001) (sccp_scrc.c:472)

20240424190719635 DLSCCP DEBUG Received CO:CODT for local reference 3 (sccp_scoc.c:1823)

20240424190719635 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857)

20240424190719635 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSGSN-IuPS' (sccp_user.c:176)

20240424190719635 DRANAP DEBUG sccp_sap_up(N-DATA.indication) (iu_client.c:865)

20240424190719635 DRANAP DEBUG N-DATA.ind(3, 60 00 00 1a 00 00 01 00 34 40 13 00 00 01 00 33 40 0c 60 28 7c 0a 0a 00 14 00 00 00 00 01 ) (iu_client.c:923)

20240424190719635 DMM DEBUG Rx CO O (RAB Assignment) (ranap_common_cn.c:191)

20240424190719635 DMM DEBUG Decoding message RANAP_RAB_AssignmentResponseIEs (ranap_decoder.c:4327) (ranap_decoder.c:4327)

20240424190719635 DRANAP NOTICE handle_co(dir=4, proc=0) (iu_client.c:623)

20240424190719635 DRANAP INFO Rx RAB Assignment Response for UE conn_id 3 (iu_client.c:574)

20240424190719635 DMM DEBUG Decoding message RANAP_RAB_SetupOrModifiedItemIEs (ranap_common.c:571) (ranap_common.c:571)

20240424190719635 DRANAP DEBUG Submit Iu event to upper layer: RANAP_IU_EVENT_RAB_ASSIGN (iu_client.c:131)

 Setup: (5/0a 0a 00 14 )20240424190719635 DRANAP DEBUG Updating TEID on RNC side from 0x00000001 to 0x00000001 (gprs_ranap.c:103)

20240424190719635 DLGTP DEBUG Registering seq=4097 in restransmit req queue (gtp.c:399)

20240424190719635 DGPRS INFO PDP(901700000033653/0) <- ACTIVATE PDP CONTEXT ACK (gprs_sm.c:190)

20240424190719635 DRANAP INFO Transmitting L3 Message as RANAP DT (SCCP conn_id 3) (iu_client.c:510)

20240424190719635 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890)

20240424190719635 DLSCCP DEBUG SCCP-SCOC(3)[0x5d521a5622b0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931)

20240424190719635 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00000004), PART(T=Data,L=80,D=0014404c000002001040403f8a420b0e23621f72993f3f1143ffff000000042b060121c0a82a0127228080211002010010810608080808830608080404000d0408080808000d0408080404003b400100) (sccp_scrc.c:401)

20240424190719635 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing (osmo_ss7_hmrt.c:286)

20240424190719635 DLSS7 DEBUG Found route for dpc=189=0.23.5: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-OsmoSGSN proto=m3ua (osmo_ss7_hmrt.c:227)

20240424190719635 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=189=0.23.5 (osmo_ss7_hmrt.c:241)

20240424190719635 DLSS7 DEBUG XUA_AS(as-clnt-OsmoSGSN)[0x5d521a55a590]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:507)

20240424190719636 DLGTP DEBUG Packet from 10.10.0.2:2123, length: 63 content: 32 13 00 37 00 00 00 01 10 01 00 00 01 80 0e 0b 10 00 00 00 01 11 00 00 00 01 7f 00 00 00 01 85 00 04 0a 0a 00 02 85 00 04 0a 0a 00 02 87 00 0f 00 03 00 1f 60 00 00 00 04 00 00 00 00 00 00 : Freeing seq=4097 from retransmit req queue (gtp.c:440)

20240424190719636 DGPRS DEBUG libgtp cb_conf(type=18, cause=128, pdp=0x7e6057501058, cbp=0x5d521a5630f0) (sgsn_libgtp.c:629)

20240424190719636 DGPRS INFO PDP(901700000033653/0) Received Update PDP CTX CONF, cause=128(Request accepted) (sgsn_libgtp.c:523)

20240424190733101 DGPRS DEBUG Checking for inactive LLMEs, time = 27186 (sgsn.c:128)

20240424190733109 DLGSUP INFO GSUP ping callback (connected, got PONG) (gsup_client.c:266)

20240424190733109 DLGSUP DEBUG GSUP sending PING (gsup_client.c:288)

20240424190733109 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190733109 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190733109 DLINP DEBUG 10.10.0.1:4222 connected write (ipa.c:154)

20240424190733109 DLINP DEBUG 10.10.0.1:4222 sending data (ipa.c:94)

20240424190733109 DLINP DEBUG 10.10.0.1:4222 connected read (ipa.c:150)

20240424190733109 DLINP DEBUG 10.10.0.1:4222 message received (ipa.c:59)

20240424190733109 DLMI DEBUG PONG! (ipa.c:533)

20240424190733109 DLGSUP DEBUG GSUP receiving PONG (gsup_client.c:225)