andrepuschmann
commented
2 years ago @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.
im-nonedone
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=4163srsENB+srsUE network setup
docker0: flags=4099Expected 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 === TypeSRSue 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
[ue.conf.txt](https://github.com/srsran/srsRAN/files/9702493/ue.conf.txt)I should note that even without
apn,apn_protocolandnetnsuncommented and configured I get the same problem.I added
ue1with the following commandip netns add ue1enb.conf
[enb.conf.txt](https://github.com/srsran/srsRAN/files/9702498/enb.conf.txt)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 stateMME log for one attach failure for UE registration
[MME log for one attach failure for UE registration.txt](https://github.com/srsran/srsRAN/files/9702428/mme.log.for.srsue.txt)INITIAL_CONTEXT_SETUP_FAILURE with Cause_Type = Radio Network and Cause_Value = 27is the first place I see where the attach failsenb.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.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