[srsran-users] End-toend 4G network : Difficulties with UE synchronisation

Wed Jun 29 07:59:40 UTC 2022

greetings to the srsran community,

I'm fairly new in the domain of connectivity, i'm really sorry if my
question seems to be stupid.

I'm trying to set up the End-to-end LTE network as described in this link :
https://docs.srsran.com/en/latest/usermanuals/source/1_setup.html#gen-4g.
The UE detects the cell, but doesn't seem to do much after.
(I don't think that's a problem on the epc nor enb parts, my phone can
detect the network and try to attach just fine)

My setup : 1 PC on linux, 2 bladeRF.
(I tried with 2 PCs  with 1 bladeRF connected to each, without more
success.)

here are the contents of the logs :

epc.log :
2022-06-29T07:22:00.216593 [EPC    ] [I] Using binary srsepc with
arguments:
2022-06-29T07:22:00.216653 [HSS    ] [I] Opened DB file:
/root/.config/srsran/user_db.csv
2022-06-29T07:22:00.216820 [HSS    ] [I] HSS Initialized. DB file
/root/.config/srsran/user_db.csv, MCC: 61441, MNC: 65281
2022-06-29T07:22:00.216883 [S1AP   ] [I] S1-MME Initializing
2022-06-29T07:22:00.230204 [COMN   ] [D] [    0] Successfully bound to
address 127.0.1.100:36412
2022-06-29T07:22:00.230221 [S1AP   ] [I] S1AP Initialized
2022-06-29T07:22:00.230225 [MME GTPC] [I] Initializing MME S11 interface.
2022-06-29T07:22:00.230232 [MME GTPC] [I] MME S11 Initialized
2022-06-29T07:22:00.230242 [MME GTPC] [I] MME GTP-C Initialized
2022-06-29T07:22:00.230245 [S1AP   ] [I] MME Initialized. MCC: 0xf001, MNC:
0xff01
2022-06-29T07:22:00.233050 [GTPU   ] [I] TUN file descriptor = 6
2022-06-29T07:22:00.233297 [GTPU   ] [I] Initialized SGi interface
2022-06-29T07:22:00.233303 [GTPU   ] [I] S1-U socket = 7
2022-06-29T07:22:00.233307 [GTPU   ] [I] S1-U IP = 127.0.1.100, Port = 2152
2022-06-29T07:22:00.233308 [GTPU   ] [I] Initialized S1-U interface
2022-06-29T07:22:00.233308 [GTPU   ] [I] SPGW GTP-U Initialized.
2022-06-29T07:22:00.233315 [SPGW GTPC] [I] Initializing SPGW S11 interface.
2022-06-29T07:22:00.233463 [SPGW GTPC] [I] SPGW S11 Initialized.
2022-06-29T07:22:00.233466 [SPGW   ] [I] SP-GW Initialized.
2022-06-29T07:22:00.251731 [GTPU   ] [I] IPv6 not supported yet.
2022-06-29T07:22:04.615055 [GTPU   ] [I] IPv6 not supported yet.
2022-06-29T07:22:06.870913 [S1AP   ] [I] Received S1AP msg. Size: 49
2022-06-29T07:22:06.872341 [S1AP   ] [I] Received Initiating PDU
2022-06-29T07:22:06.872343 [S1AP   ] [I] Received S1 Setup Request.
2022-06-29T07:22:06.872486 [S1AP   ] [I] Received S1 Setup Request.
2022-06-29T07:22:06.872498 [S1AP   ] [I] S1 Setup Request - eNB Name:
srsenb01, eNB id: 0x19b
2022-06-29T07:22:06.872519 [S1AP   ] [I] S1 Setup Request - MCC:001,
MNC:01, PLMN: 61712
2022-06-29T07:22:06.872522 [S1AP   ] [I] S1 Setup Request - TAC 7, B-PLMN
0xf110
2022-06-29T07:22:06.872531 [S1AP   ] [I] Adding new eNB context. eNB ID 411
2022-06-29T07:22:06.872759 [S1AP   ] [I] Sending S1 Setup Response
2022-06-29T07:22:13.318921 [GTPU   ] [I] IPv6 not supported yet.
2022-06-29T07:22:31.498920 [GTPU   ] [I] IPv6 not supported yet.
2022-06-29T07:23:06.310934 [GTPU   ] [I] IPv6 not supported yet.
2022-06-29T07:24:15.946923 [GTPU   ] [I] IPv6 not supported yet.
2022-06-29T07:25:40.571748 [S1AP   ] [I] Deleting eNB context. eNB Id: 0x19b
2022-06-29T07:25:40.615044 [HSS    ] [I] Opened DB file:
/root/.config/srsran/user_db.csv

enb.log :
2022-06-29T07:22:06.768832 [ENB    ] [I] Using binary srsenb with
arguments:
2022-06-29T07:22:06.837446 [ENB    ] [I] Built in Release mode using commit
f2dff0b7a on branch master.
2022-06-29T07:22:06.870729 [COMN   ] [D] [    0] Setting RTO_INFO options
on SCTP socket. Association 0, Initial RTO 3000, Minimum RTO 1000, Maximum
RTO 6000
2022-06-29T07:22:06.870731 [COMN   ] [D] [    0] Setting SCTP_INITMSG
options on SCTP socket. Max attempts 3, Max init attempts timeout 5000
2022-06-29T07:22:06.870747 [COMN   ] [D] [    0] Successfully bound to
address 127.0.1.1:0
2022-06-29T07:22:06.870830 [COMN   ] [D] [    0] RxSockets: socket fd=6 has
been registered.
2022-06-29T07:22:06.870903 [COMN   ] [D] [    0] Successfully bound to
address 127.0.1.1:2152
2022-06-29T07:22:06.870905 [COMN   ] [D] [    0] RxSockets: socket fd=7 has
been registered.
2022-06-29T07:25:40.571990 [COMN   ] [D] [    0] RxSockets: Socket fd=6 has
been successfully removed
2022-06-29T07:25:40.571993 [COMN   ] [D] [    0] RxSockets: Socket fd=6 has
been successfully removed
2022-06-29T07:25:40.572028 [COMN   ] [D] [    0] Setting RTO_INFO options
on SCTP socket. Association 0, Initial RTO 3000, Minimum RTO 1000, Maximum
RTO 6000
2022-06-29T07:25:40.572030 [COMN   ] [D] [    0] Setting SCTP_INITMSG
options on SCTP socket. Max attempts 3, Max init attempts timeout 5000
2022-06-29T07:25:40.572040 [COMN   ] [D] [    0] Successfully bound to
address 127.0.1.1:0
2022-06-29T07:25:40.572073 [COMN   ] [I] [    0] Failed to establish socket
connection to 127.0.1.100
2022-06-29T07:25:40.572096 [COMN   ] [W] [    0] RxSockets: The socket fd=6
to be removed does not exist
2022-06-29T07:25:43.935029 [COMN   ] [D] [    0] RxSockets: Closing rx
socket handler thread
2022-06-29T07:25:43.935091 [COMN   ] [D] [    0] RxSockets: closed.

ue.log :
2022-06-29T07:22:58.773156 [UE     ] [I] Using binary srsue with arguments:
2022-06-29T07:22:58.804508 [UE     ] [I] Built in Release mode using commit
f2dff0b7a on branch master.
2022-06-29T07:23:02.824705 [STCK   ] [W] Detected slow task processing
(sync_queue_len=9).
2022-06-29T07:23:02.824710 [STCK   ] [W] Detected slow task processing
(sync_queue_len=8).
2022-06-29T07:23:02.824711 [STCK   ] [W] Detected slow task processing
(sync_queue_len=7).
2022-06-29T07:23:02.824712 [STCK   ] [W] Detected slow task processing
(sync_queue_len=6).
2022-06-29T07:23:03.225100 [PHY    ] [W] [   90] SYNC:  TTI jump of 1898
limited to 1000
2022-06-29T07:23:03.332329 [PHY    ] [W] [  117] SYNC:  radio time seems to
be going backwards (rx_time=0.617101, tti_ts=3.292855)
2022-06-29T07:23:04.166076 [PHY    ] [W] [ 6301] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.166172 [PHY    ] [W] [ 6302] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.166253 [PHY    ] [W] [ 6303] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.168584 [PHY    ] [W] [ 6304] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.168623 [PHY    ] [W] [ 6305] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.168655 [PHY    ] [W] [ 6306] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.168676 [PHY    ] [W] [ 6307] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.168713 [PHY    ] [W] [ 6308] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.168751 [PHY    ] [W] [ 6309] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.168887 [PHY    ] [W] [ 6310] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.169133 [PHY    ] [W] [ 6311] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.170112 [PHY    ] [W] [ 6312] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.171199 [PHY    ] [W] [ 6313] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.172143 [PHY    ] [W] [ 6314] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.173131 [PHY    ] [W] [ 6315] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.174170 [PHY    ] [W] [ 6316] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.175143 [PHY    ] [W] [ 6317] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.176113 [PHY    ] [W] [ 6318] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.177172 [PHY    ] [W] [ 6319] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.178357 [PHY    ] [W] [ 6320] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.179112 [PHY    ] [W] [ 6321] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.180189 [PHY    ] [W] [ 6322] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.181127 [PHY    ] [W] [ 6323] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.182109 [PHY    ] [W] [ 6324] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.184378 [PHY    ] [W] [ 6325] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.184398 [PHY    ] [W] [ 6326] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.185111 [PHY    ] [W] [ 6327] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.186165 [PHY    ] [W] [ 6328] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.186222 [PHY    ] [W] [ 6328] SNR=3.0 dB RSRP=-79.6 dBm,
sync=out-of-sync from channel estimator
2022-06-29T07:23:04.187140 [PHY    ] [W] [ 6329] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.187182 [PHY    ] [W] [ 6329] SNR=2.8 dB RSRP=-79.8 dBm,
sync=out-of-sync from channel estimator
2022-06-29T07:23:04.188483 [PHY    ] [W] [ 6330] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.188530 [PHY    ] [W] [ 6330] SNR=2.6 dB RSRP=-79.9 dBm,
sync=out-of-sync from channel estimator
2022-06-29T07:23:04.189164 [PHY    ] [W] [ 6331] SFN not yet synchronized,
sending out-of-sync
[...Same 2 lines for a long time....]

2022-06-29T07:23:04.345160 [PHY    ] [W] [ 6487] SNR=1.9 dB RSRP=-79.2 dBm,
sync=out-of-sync from channel estimator
2022-06-29T07:23:04.346159 [PHY    ] [W] [ 6488] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.346205 [PHY    ] [W] [ 6488] SNR=1.8 dB RSRP=-79.4 dBm,
sync=out-of-sync from channel estimator
2022-06-29T07:23:04.347117 [PHY    ] [W] [ 6489] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.347168 [PHY    ] [W] [ 6489] SNR=1.7 dB RSRP=-79.5 dBm,
sync=out-of-sync from channel estimator
2022-06-29T07:23:04.348191 [PHY    ] [W] [ 6490] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.348260 [PHY    ] [W] [ 6490] SNR=1.6 dB RSRP=-79.6 dBm,
sync=out-of-sync from channel estimator
2022-06-29T07:23:04.350532 [PHY    ] [W] [ 6491] SYNC:  Out-of-sync
detected in PSS/SSS
2022-06-29T07:23:04.351526 [PHY    ] [W] [ 6492] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.352580 [PHY    ] [W] [ 6493] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.353550 [PHY    ] [W] [ 6494] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.354549 [PHY    ] [W] [ 6495] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.355577 [PHY    ] [W] [ 6496] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.356546 [PHY    ] [W] [ 6497] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.357518 [PHY    ] [W] [ 6498] SFN not yet synchronized,
sending out-of-sync
2022-06-29T07:23:04.358578 [PHY    ] [W] [ 6499] SFN not yet synchronized,
sending out-of-sync

What should I do ?

Configuration and log files are attached to this mail.

Thank you for your help.

Suzanne.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.srsran.com/pipermail/srsran-users/attachments/20220629/dd6cbf4e/attachment-0001.htm>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: enb.log
Type: text/x-log
Size: 1814 bytes
Desc: not available
URL: <https://lists.srsran.com/pipermail/srsran-users/attachments/20220629/dd6cbf4e/attachment-0003.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: epc.log
Type: text/x-log
Size: 2773 bytes
Desc: not available
URL: <https://lists.srsran.com/pipermail/srsran-users/attachments/20220629/dd6cbf4e/attachment-0004.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: ue.log
Type: text/x-log
Size: 38571 bytes
Desc: not available
URL: <https://lists.srsran.com/pipermail/srsran-users/attachments/20220629/dd6cbf4e/attachment-0005.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: ue.conf
Type: application/octet-stream
Size: 19110 bytes
Desc: not available
URL: <https://lists.srsran.com/pipermail/srsran-users/attachments/20220629/dd6cbf4e/attachment-0003.obj>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: enb.conf
Type: application/octet-stream
Size: 18267 bytes
Desc: not available
URL: <https://lists.srsran.com/pipermail/srsran-users/attachments/20220629/dd6cbf4e/attachment-0004.obj>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: epc.conf
Type: application/octet-stream
Size: 3736 bytes
Desc: not available
URL: <https://lists.srsran.com/pipermail/srsran-users/attachments/20220629/dd6cbf4e/attachment-0005.obj>