srsRAN_4G
srsRAN_4G copied to clipboard
Published
20 hours ago •
srsran
srsran
srsUE can't connect to MagmaCore EPC - Attach failure
Issue Description
I'm trying to attach srsUE to srsENB to Magma's AGW (EPC).
Currently I've managed, what appears to, successfully connect srsenb to the EPC, but when trying to initiate the srsue in the environment, the attach process fails (5 retries are attempted before disregarding), and there is no communication between the UE and the EPC (i.e. pinging uplink does not work nor downlink because no IP is assigned to the UE).
My biggest uncertainty is the configuration of the UE and the ENB (in Additional Information section). I'm feeling like I might be configuring something wrong, network-wise, because the exact configuration worked for this repo with Magma.
Setup Details
Operating System: ubuntu-20.04.3-live-server-amd64
This same OS is used for both Magma's EPC (which is running on a VMware VM) and the srsenb+srsue (which run together on another VM).
I'm using bridged network for both of the VM's and they can ping each other with no problem,
EPC network setup
dhcp0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet6 fe80::c06c:32ff:fea8:a94f prefixlen 64 scopeid 0x20<link>
ether c2:6c:32:a8:a9:4f txqueuelen 1000 (Ethernet)
RX packets 23 bytes 1628 (1.6 KB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 20 bytes 1496 (1.4 KB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
envoy_cntr: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 10.5.0.2 netmask 255.255.255.0 broadcast 10.5.0.255
inet6 fe80::50f5:a2ff:fe22:4854 prefixlen 64 scopeid 0x20<link>
ether 52:f5:a2:22:48:54 txqueuelen 1000 (Ethernet)
RX packets 470 bytes 46859 (46.8 KB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 468 bytes 26630 (26.6 KB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 10.0.0.110 netmask 255.255.255.0 broadcast 10.0.0.255
inet6 2a06:c701:9d7d:e900:20c:29ff:fe1e:ca94 prefixlen 64 scopeid 0x0<global>
inet6 fe80::20c:29ff:fe1e:ca94 prefixlen 64 scopeid 0x20<link>
ether 00:0c:29:1e:ca:94 txqueuelen 1000 (Ethernet)
RX packets 158494 bytes 13432697 (13.4 MB)
RX errors 2 dropped 2354 overruns 0 frame 0
TX packets 56630 bytes 25321589 (25.3 MB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
device interrupt 19 base 0x2000
eth1: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 10.0.0.120 netmask 255.255.255.0 broadcast 10.0.0.255
inet6 2a06:c701:9d7d:e900:20c:29ff:fe1e:ca9e prefixlen 64 scopeid 0x0<global>
inet6 fe80::20c:29ff:fe1e:ca9e prefixlen 64 scopeid 0x20<link>
ether 00:0c:29:1e:ca:9e txqueuelen 1000 (Ethernet)
RX packets 131823 bytes 10018662 (10.0 MB)
RX errors 1 dropped 2354 overruns 0 frame 0
TX packets 14038 bytes 2031272 (2.0 MB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
device interrupt 16 base 0x2080
gtp_br0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 192.168.128.1 netmask 255.255.255.0 broadcast 192.168.128.255
inet6 fe80::9ceb:c5ff:fe64:4540 prefixlen 64 scopeid 0x20<link>
ether 9e:eb:c5:64:45:40 txqueuelen 1000 (Ethernet)
RX packets 15 bytes 1064 (1.0 KB)
RX errors 0 dropped 3 overruns 0 frame 0
TX packets 19 bytes 1426 (1.4 KB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
gtpu_sys_2152: flags=4305<UP,POINTOPOINT,RUNNING,NOARP,MULTICAST> mtu 65499
inet6 fe80::3ed1:cfbf:352d:9320 prefixlen 64 scopeid 0x20<link>
unspec 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00 txqueuelen 1000 (UNSPEC)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 0 bytes 0 (0.0 B)
TX errors 15 dropped 0 overruns 0 carrier 0 collisions 0
ipfix0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 127.0.0.10 netmask 255.255.255.0 broadcast 127.0.0.255
inet6 fe80::4a0:eeff:fe68:a219 prefixlen 64 scopeid 0x20<link>
ether 06:a0:ee:68:a2:19 txqueuelen 1000 (Ethernet)
RX packets 19 bytes 1348 (1.3 KB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 20 bytes 1496 (1.4 KB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
li_port: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 127.1.0.0 netmask 255.255.255.0 broadcast 127.1.0.255
inet6 fe80::b037:76ff:fe91:9430 prefixlen 64 scopeid 0x20<link>
ether b2:37:76:91:94:30 txqueuelen 1000 (Ethernet)
RX packets 16 bytes 1140 (1.1 KB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 20 bytes 1496 (1.4 KB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
lo: flags=73<UP,LOOPBACK,RUNNING> mtu 65536
inet 127.0.0.1 netmask 255.0.0.0
inet6 ::1 prefixlen 128 scopeid 0x10<host>
loop txqueuelen 1000 (Local Loopback)
RX packets 454110 bytes 60336454 (60.3 MB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 454110 bytes 60336454 (60.3 MB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
mtr0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 10.1.0.1 netmask 255.255.255.0 broadcast 10.1.0.255
inet6 fe80::4c78:1eff:fe19:f36e prefixlen 64 scopeid 0x20<link>
ether 4e:78:1e:19:f3:6e txqueuelen 1000 (Ethernet)
RX packets 18 bytes 1292 (1.2 KB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 20 bytes 1496 (1.4 KB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
proxy_port: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet6 fe80::8c8b:2dff:fe33:41dd prefixlen 64 scopeid 0x20<link>
ether 8e:8b:2d:33:41:dd txqueuelen 1000 (Ethernet)
RX packets 123 bytes 9570 (9.5 KB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 41 bytes 3382 (3.3 KB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
uplink_br0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet6 fe80::d0df:b9ff:fe32:b4e prefixlen 64 scopeid 0x20<link>
ether d2:df:b9:32:0b:4e txqueuelen 1000 (Ethernet)
RX packets 22 bytes 1552 (1.5 KB)
RX errors 0 dropped 1 overruns 0 frame 0
TX packets 20 bytes 1496 (1.4 KB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
srsENB+srsUE network setup
docker0: flags=4099<UP,BROADCAST,MULTICAST> mtu 1500
inet 172.17.0.1 netmask 255.255.0.0 broadcast 172.17.255.255
ether 02:42:14:2a:50:20 txqueuelen 0 (Ethernet)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 0 bytes 0 (0.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
ens33: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 10.0.0.130 netmask 255.255.255.0 broadcast 10.0.0.255
inet6 fe80::20c:29ff:fef0:5b14 prefixlen 64 scopeid 0x20<link>
inet6 2a06:c701:9d7d:e900:20c:29ff:fef0:5b14 prefixlen 64 scopeid 0x0<global>
ether 00:0c:29:f0:5b:14 txqueuelen 1000 (Ethernet)
RX packets 179038 bytes 228311168 (228.3 MB)
RX errors 3 dropped 693 overruns 0 frame 0
TX packets 42426 bytes 3763675 (3.7 MB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
device interrupt 19 base 0x2000
lo: flags=73<UP,LOOPBACK,RUNNING> mtu 65536
inet 127.0.0.1 netmask 255.0.0.0
inet6 ::1 prefixlen 128 scopeid 0x10<host>
loop txqueuelen 1000 (Local Loopback)
RX packets 7795592 bytes 231068499626 (231.0 GB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 7795592 bytes 231068499626 (231.0 GB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
Expected Behavior
I would like the srsue to be assigned an IP as seen in your 5G sa End-To-End documentation and be able to send traffic from the UE to the EPC (I followed this link for some of the configurations as I will show below).
Actual Behaviour
SRSenb connecting successfully, disconnecting UE once registered on repeat
Active RF plugins: libsrsran_rf_zmq.so
Inactive RF plugins:
--- Software Radio Systems LTE eNodeB ---
Couldn't open , trying /root/.config/srsran/enb.conf
Reading configuration file /root/.config/srsran/enb.conf...
Couldn't open sib.conf, trying /root/.config/srsran/sib.conf
Couldn't open rr.conf, trying /root/.config/srsran/rr.conf
Couldn't open rb.conf, trying /root/.config/srsran/rb.conf
Built in Release mode using commit ce8a3cae1 on branch master.
Opening 1 channels in RF device=zmq with args=fail_on_disconnect=true,tx_port=tcp://*:2000,rx_port=tcp://localhost:2001,id=enb,base_srate=23.04e6
Supported RF device list: zmq file
CHx base_srate=23.04e6
CHx id=enb
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
CH0 rx_port=tcp://localhost:2001
CH0 tx_port=tcp://*:2000
CH0 fail_on_disconnect=true
==== eNodeB started ===
Type <t> to view trace
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Setting frequency: DL=2680.0 Mhz, UL=2560.0 MHz for cc_idx=0 nof_prb=50
RACH: tti=341, cc=0, pci=1, preamble=6, offset=0, temp_crnti=0x46
User 0x46 connected
Disconnecting rnti=0x46.
RACH: tti=4981, cc=0, pci=1, preamble=34, offset=0, temp_crnti=0x47
User 0x47 connected
Disconnecting rnti=0x47.
RACH: tti=9621, cc=0, pci=1, preamble=49, offset=0, temp_crnti=0x48
User 0x48 connected
Disconnecting rnti=0x48.
RACH: tti=4021, cc=0, pci=1, preamble=14, offset=0, temp_crnti=0x49
User 0x49 connected
Disconnecting rnti=0x49.
RACH: tti=8661, cc=0, pci=1, preamble=5, offset=0, temp_crnti=0x4a
User 0x4a connected
Disconnecting rnti=0x4a.
RACH: tti=6311, cc=0, pci=1, preamble=3, offset=0, temp_crnti=0x4b
SRSue stuck in attach failure on repeat
Active RF plugins: libsrsran_rf_zmq.so
Inactive RF plugins:
Couldn't open , trying /root/.config/srsran/ue.conf
Reading configuration file /root/.config/srsran/ue.conf...
Built in Release mode using commit ce8a3cae1 on branch master.
Opening 1 channels in RF device=zmq with args=tx_port=tcp://*:2001,rx_port=tcp://localhost:2000,id=ue,base_srate=23.04e6
Supported RF device list: zmq file
CHx base_srate=23.04e6
CHx id=ue
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
CH0 rx_port=tcp://localhost:2000
CH0 tx_port=tcp://*:2001
Waiting PHY to initialize ... done!
Attaching UE...
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
.
Found Cell: Mode=FDD, PCI=1, PRB=50, Ports=1, CP=Normal, CFO=-0.2 KHz
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Found PLMN: Id=99970, TAC=1
Random Access Transmission: seq=6, tti=341, ra-rnti=0x2
RRC Connected
Random Access Complete. c-rnti=0x46, ta=0
Received RRC Connection Release (releaseCause: other)
RRC IDLE
Attach failed (attempt 1/5)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
.
Found Cell: Mode=FDD, PCI=1, PRB=50, Ports=1, CP=Normal, CFO=-0.2 KHz
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Found PLMN: Id=99970, TAC=1
Random Access Transmission: seq=34, tti=4981, ra-rnti=0x2
RRC Connected
Random Access Complete. c-rnti=0x47, ta=0
Received RRC Connection Release (releaseCause: other)
RRC IDLE
Attach failed (attempt 2/5)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
.
Found Cell: Mode=FDD, PCI=1, PRB=50, Ports=1, CP=Normal, CFO=-0.2 KHz
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Found PLMN: Id=99970, TAC=1
Random Access Transmission: seq=49, tti=9621, ra-rnti=0x2
RRC Connected
Random Access Complete. c-rnti=0x48, ta=0
Received RRC Connection Release (releaseCause: other)
RRC IDLE
Attach failed (attempt 3/5)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
.
Found Cell: Mode=FDD, PCI=1, PRB=50, Ports=1, CP=Normal, CFO=-0.2 KHz
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Found PLMN: Id=99970, TAC=1
Random Access Transmission: seq=14, tti=4021, ra-rnti=0x2
RRC Connected
Random Access Complete. c-rnti=0x49, ta=0
Received RRC Connection Release (releaseCause: other)
RRC IDLE
Attach failed (attempt 4/5)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
Current sample rate is 1.92 MHz with a base rate of 23.04 MHz (x12 decimation)
.
Found Cell: Mode=FDD, PCI=1, PRB=50, Ports=1, CP=Normal, CFO=-0.2 KHz
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Current sample rate is 11.52 MHz with a base rate of 23.04 MHz (x2 decimation)
Found PLMN: Id=99970, TAC=1
Random Access Transmission: seq=5, tti=8661, ra-rnti=0x2
RRC Connected
Random Access Complete. c-rnti=0x4a, ta=0
Received RRC Connection Release (releaseCause: other)
RRC IDLE
Attach failed (attempt 5/5)
Steps to reproduce the problem
I've deployed Magma AGW using this video guide. Alternatively one could install Magma's EPC following their official documentation.
I've following this guide to configure the UE and ENB, with ZMQ and the SRSran built beforehand.
Additional Information
Subscribers in Magma EPC (only one)
root@magma-agw:~# subscriber_cli.py list
IMSI999700000054350
root@magma-agw:~# subscriber_cli.py get IMSI999700000054350
sid {
id: "999700000054350"
}
lte {
state: ACTIVE
auth_key: "\201ly=r^\307h\013\373|\303\244\035\223\016"
auth_opc: "\305}\315\271\227\206\342\216\245\234|Q\231\217\253\017"
}
network_id {
id: "lte"
}
state {
lte_auth_next_seq: 12
}
sub_profile: "default"
non_3gpp {
ambr {
max_bandwidth_ul: 2000000000
max_bandwidth_dl: 4000000000
}
apn_config {
service_selection: "internet"
qos_profile {
class_id: 9
priority_level: 15
}
ambr {
max_bandwidth_ul: 1000000
max_bandwidth_dl: 1000000
}
}
}
sub_network {
}
ue.conf
-
I should note that even without
apn,apn_protocolandnetnsuncommented and configured I get the same problem. -
I added
ue1with the following commandip netns add ue1
enb.conf
rr.conf
mac_cnfg = { phr_cnfg = { dl_pathloss_change = "dB3"; // Valid: 1, 3, 6 or INFINITY periodic_phr_timer = 50; prohibit_phr_timer = 0; }; ulsch_cnfg = { max_harq_tx = 4; periodic_bsr_timer = 20; // in ms retx_bsr_timer = 320; // in ms }; time_alignment_timer = -1; // -1 is infinity
};
phy_cnfg =
{
phich_cnfg =
{
duration = "Normal";
resources = "1/6";
};
pusch_cnfg_ded =
{
beta_offset_ack_idx = 6;
beta_offset_ri_idx = 6;
beta_offset_cqi_idx = 6;
};
// PUCCH-SR resources are scheduled on time-frequeny domain first, then multiplexed in the same resource.
sched_request_cnfg =
{
dsr_trans_max = 64;
period = 20; // in ms
//subframe = [1, 11]; // Optional vector of subframe indices allowed for SR transmissions (default uses all)
nof_prb = 1; // number of PRBs on each extreme used for SR (total prb is twice this number)
};
cqi_report_cnfg =
{
mode = "periodic";
simultaneousAckCQI = true;
period = 40; // in ms
//subframe = [0, 10, 20, 30]; // Optional vector of subframe indices every period where CQI resources will be allocated (default uses all)
m_ri = 8; // RI period in CQI period
//subband_k = 1; // If enabled and > 0, configures sub-band CQI reporting and defines K (see 36.213 7.2.2). If disabled, configures wideband CQI
};
};
cell_list =
(
{
// rf_port = 0;
cell_id = 0x01;
tac = 0x0001;
pci = 1;
// root_seq_idx = 204;
dl_earfcn = 3350;
//ul_earfcn = 21400;
ho_active = false;
//meas_gap_period = 0; // 0 (inactive), 40 or 80
//meas_gap_offset_subframe = [6, 12, 18, 24, 30];
// target_pusch_sinr = -1;
// target_pucch_sinr = -1;
// enable_phr_handling = false;
// min_phr_thres = 0;
// allowed_meas_bw = 6;
// t304 = 2000; // in msec. possible values: 50, 100, 150, 200, 500, 1000, 2000
// CA cells
scell_list = (
// {cell_id = 0x02; cross_carrier_scheduling = false; scheduling_cell_id = 0x02; ul_allowed = true}
)
// Cells available for handover
meas_cell_list =
(
{
eci = 0x19C02;
dl_earfcn = 2850;
pci = 2;
//direct_forward_path_available = false;
//allowed_meas_bw = 6;
//cell_individual_offset = 0;
}
);
// Select measurement report configuration (all reports are combined with all measurement objects)
meas_report_desc =
(
{
eventA = 3
a3_offset = 6;
hysteresis = 0;
time_to_trigger = 480;
trigger_quant = "RSRP";
max_report_cells = 1;
report_interv = 120;
report_amount = 1;
}
);
meas_quant_desc = {
// averaging filter coefficient
rsrq_config = 4;
rsrp_config = 4;
};
}
// Add here more cells
);
nr_cell_list =
(
// no NR cells
);
MME log for ENB registration
008591 Tue Oct 04 02:28:09 2022 7FF77A3F0700 DEBUG MME-AP tasks/mme_app/mme_app_state_mana:0094 Inside get_state with read_from_db 0
008592 Tue Oct 04 02:28:41 2022 7FF773BE3700 DEBUG UTIL tasks/grpc_service/S1apServiceIm:0041 Received GetENBState GRPC request
008593 Tue Oct 04 02:28:56 2022 7FF7753E6700 DEBUG SCTP tasks/sctp/sctp_itti_messaging.c:0065 Ppid S1AP in sctp_itti_send_new_association
008594 Tue Oct 04 02:28:56 2022 7FF771BDF700 DEBUG SCTP tasks/sctp/sctp_itti_messaging.c:0096 Ppid S1AP in sctp_itti_send_new_message_ind
008595 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP tasks/s1ap/s1ap_mme.cpp :0116 S1AP ZMQ latency: 666.
008596 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP tasks/s1ap/s1ap_mme_handlers.cpp:3740 Create eNB context for assoc_id: 34
008597 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP tasks/s1ap/s1ap_state_converter.:0044 No entries in mmeid2associd map
008598 Tue Oct 04 02:28:56 2022 7FF7763E8700 ERROR S1AP tasks/s1ap/s1ap_state_converter.:0051 Updating num_eNBs from maintained to actual count 0->1
008599 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP include/state_manager.hpp :0156 Finished writing state
008600 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP tasks/s1ap/s1ap_mme.cpp :0116 S1AP ZMQ latency: 702.
008601 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP tasks/s1ap/s1ap_mme_handlers.cpp:0594 New s1 setup request incoming from srsenb01 macro eNB id: 0019b
008602 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP tasks/s1ap/s1ap_mme_handlers.cpp:0693 Adding eNB with enb_id :411 to the list of served eNBs
008603 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP tasks/s1ap/s1ap_state_converter.:0200 Writing Supported TAI list at index 0
008604 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP tasks/s1ap/s1ap_state_converter.:0044 No entries in mmeid2associd map
008605 Tue Oct 04 02:28:56 2022 7FF7763E8700 ERROR S1AP tasks/s1ap/s1ap_state_converter.:0051 Updating num_eNBs from maintained to actual count 2->1
008606 Tue Oct 04 02:28:56 2022 7FF7763E8700 DEBUG S1AP include/state_manager.hpp :0156 Finished writing state
MME log for one attach failure for UE registration
- In the log,
INITIAL_CONTEXT_SETUP_FAILURE with Cause_Type = Radio Network and Cause_Value = 27is the first place I see where the attach fails
enb.log
2022-10-04T02:43:18.081858 [ENB ] [I] Using binary srsenb/src/srsenb with arguments:
2022-10-04T02:43:18.120319 [ENB ] [I] Built in Release mode using commit ce8a3cae1 on branch master.
2022-10-04T02:43:18.121489 [ENB ] [I] Using sync queue size of one for ZMQ based radio.
2022-10-04T02:43:18.186288 [COMN ] [D] [ 0] Setting RTO_INFO options on SCTP socket. Association 0, Initial RTO 3000, Minimum RTO 1000, Maximum RTO 6000
2022-10-04T02:43:18.186290 [COMN ] [D] [ 0] Setting SCTP_INITMSG options on SCTP socket. Max attempts 3, Max init attempts timeout 5000
2022-10-04T02:43:18.186329 [COMN ] [D] [ 0] Successfully bound to address 10.0.0.130:0
2022-10-04T02:43:18.187208 [COMN ] [D] [ 0] RxSockets: socket fd=6 has been registered.
2022-10-04T02:43:18.187516 [COMN ] [D] [ 0] Successfully bound to address 10.0.0.130:2152
2022-10-04T02:43:18.187531 [COMN ] [D] [ 0] RxSockets: socket fd=7 has been registered.
2022-10-04T02:43:23.897366 [S1AP ] [E] IPv6 addresses not currently supported
2022-10-04T02:44:01.085948 [S1AP ] [E] IPv6 addresses not currently supported
2022-10-04T02:44:38.148261 [S1AP ] [E] IPv6 addresses not currently supported
2022-10-04T02:45:14.856938 [S1AP ] [E] IPv6 addresses not currently supported
2022-10-04T02:45:52.507862 [S1AP ] [E] IPv6 addresses not currently supported
2022-10-04T02:47:55.366666 [COMN ] [D] [ 0] RxSockets: Closing rx socket handler thread
2022-10-04T02:47:55.367388 [COMN ] [D] [ 0] RxSockets: closed.
- provided INFO because DEBUG was enormous. Will provide DEBUG if necessary.
ue.log
2022-10-04T02:43:22.629649 [UE ] [I] Using binary srsue/src/srsue with arguments:
2022-10-04T02:43:22.669845 [UE ] [I] Built in Release mode using commit ce8a3cae1 on branch master.
2022-10-04T02:43:45.678478 [NAS ] [W] Timer T3410 expired after attach attempt 1/5: starting T3411
2022-10-04T02:44:22.743967 [NAS ] [W] Timer T3410 expired after attach attempt 2/5: starting T3411
2022-10-04T02:44:59.571928 [NAS ] [W] Timer T3410 expired after attach attempt 3/5: starting T3411
2022-10-04T02:45:37.244090 [NAS ] [W] Timer T3410 expired after attach attempt 4/5: starting T3411
2022-10-04T02:46:14.025145 [NAS ] [W] Timer T3410 expired. Maximum attempts reached. Starting T3402
- provided INFO because DEBUG was enormous. Will provide DEBUG if necessary.
@albukirky1 Please excuse the long delay in answering. I appreciate your very detailed and excellent issue report. The UE failing to attach is simply because the eNB didn't complete the S1 registration with the core. So it will reject each UE. Please check in the EPC/eNB logs and configuration and make sure the S1 setup completes successfully.
I'm having a similar issue. I had commented over the Magma issues https://github.com/magma/magma/issues/14074 . This is annoying me, because srsRAN_4G was my default tool to debug a core for over two years and I have just recently switched to magma, and I can't get 4G to work with srsRAN.
