Giter VIP home page Giter VIP logo

aether-onramp's Introduction

Aether OnRamp

Aether OnRamp provides a low-overhead way to bring up a Kubernetes cluster, deploy a 5G version of SD-Core on that cluster, and then connect that Core to either an emulated 5G RAN or a network of physical gNBs. (OnRamp also supports a 4G configuration that connects physical eNBs.)

To download Aether OnRamp (and all the submodules it depends on), type:

$ git clone --recursive https://github.com/opennetworkinglab/aether-onramp.git

Taking a quick look at your aether-onramp directory, you will find the following.

  1. The deps directory contains Ansible deployment specifications for all the Aether subsystems. Each of these subdirectories (e.g., deps/5gc) is self-contained, but the Makefile in the main OnRamp directory imports the per-subsystem Makefiles, meaning all the steps required to install Aether can be (and typically should be) managed from this main directory.

  2. File vars/main.yml defines all the Ansible variables you will potentially need to modify to specify your deployment scenario. This file is the union of all the per-component var/main.yml files you find in the corresponding deps directory. This top-level variable file overrides the per-component files, so you will not need to modify the latter. The vars directory contains several variants of main.yml, each tailored for a different deployment scenario, with the default main.yml supporting the Quick Start deployment described below.

  3. File hosts.ini specifies the set of servers (physical or virtual) that Ansible targets with various playbooks. The default version included with OnRamp is simplified to run everything on a single server (the one you've cloned the repo onto). Example multi-node inventories are commented out.

Aether OnRamp assumes Ansible is installed. (See the Aether Guide for instructions on doing this, along with additional guidance if this is your first attempt to install OnRamp.) Then, once you edit hosts.ini to match your local details, type the following to verify the setup:

$ make aether-pingall

Quick Start

Edit vars/main.yml to reflect your local scenario. For the Quick Start deployment, this means setting variable data_iface to your server's network interface. Note there are two lines that define this variable, one in the core section and one in the gnbsim section. You also need to set the IP address of the AMF (in the core section) to your servers's IP address.

You are now ready to install a one-node Kubernetes cluster. Type:

$ make aether-k8s-install

Once Kubernetes is running (which you can verify with kubectl), you are ready to intall the 5G version of SD-Core. Type:

$ make aether-5gc-install

Once the Core is running (which you can verify by using kubectl to check the omec name space), you are ready to emulate a RAN workload. Type:

$ make aether-gnbsim-install
$ make aether-gnbsim-run

The second command can be executed multiple times, where the results of each run are saved in a file within the Docker container running the test. You can access that file by typing:

$ docker exec -it gnbsim-1 cat summary.log

Finally, you can bring up the Aether Management Platform (AMP) to view dashboards showing different aspects of Aether's runtime behavior. Type:

$ make aether-amp-install

You can access the dashboards for Aether's Runtime Control system and Aether's Monitoring system at the following URLs:

http://<server_ip>:31194
http://<server_ip>:30950

You will probably want to rerun make aether-gnbsim-run to generate trafffic for the monitoring system to display.

When you are ready to tear down your Quick Start deployment of Aether, execute the following commands:

$ make aether-gnbsim-uninstall
$ make aether-amp-uninstall
$ make aether-5gc-uninstall
$ make aether-k8s-uninstall

Or alternatively:

$ make aether-uninstall

Beyond Quick Start

Being able to support more complex configurations of Aether is whole point of OnRamp. See the OnRamp documentation available as part of the Aether Guide for details.

You might also check the vars directory of this repo, where file main.yml specifies global variables used to configure how Aether is deployed. By default, it is configured for the Quick Start deployment. The other files define other common configurations, any one of which you can copy to main.yml, and then edit to account for your local details. These alternative configurations are identified in the README.

aether-onramp's People

Contributors

gab-arrobo avatar jnfoster avatar llpeterson avatar mbilal92 avatar sureshmarikkannu avatar woojoong88 avatar

Stargazers

 avatar  avatar  avatar  avatar  avatar  avatar  avatar  avatar  avatar  avatar  avatar  avatar  avatar  avatar  avatar

Watchers

 avatar  avatar  avatar  avatar  avatar

aether-onramp's Issues

make aether-5gc-install error, https://charts.onosproject.org is unreachable

Hi,
when I run this command

make aether-5gc-install

it shows this error

fatal: [node1]: FAILED! => {"changed": false, "command": "/usr/local/bin/helm repo add onosproject https://charts.onosproject.org", "msg": "Failure when executing Helm command. Exited 1.\nstdout: \nstderr: Error: looks like \"https://charts.onosproject.org\" is not a valid chart repository or cannot be reached: Get \"https://charts.onosproject.org/index.yaml\": dial tcp: lookup charts.onosproject.org on 127.0.0.53:53: no such host\n", "stderr": "Error: looks like \"https://charts.onosproject.org\" is not a valid chart repository or cannot be reached: Get \"https://charts.onosproject.org/index.yaml\": dial tcp: lookup charts.onosproject.org on 127.0.0.53:53: no such host\n", "stderr_lines": ["Error: looks like \"https://charts.onosproject.org\" is not a valid chart repository or cannot be reached: Get \"https://charts.onosproject.org/index.yaml\": dial tcp: lookup charts.onosproject.org on 127.0.0.53:53: no such host"], "stdout": ", "stdout_lines": []}

And I found the URL https://charts.onosproject.org is unreachable, can you help me solve this problem?

Emulated RAN Tests Are Unstable

Hello,

I am following the Quick Start Documentation to run the simple setup, but I am encountering issues when I run the command: make aether-gnbsim-run. Sometimes, only 1 or 2 UEs pass the tests while the others fail. Only once, all UEs pass the tests. I am attaching the following details for reference:

The core network is running properly:
image

The systemd-networkd is configured as expected, I am using 22.04 Ubuntu live server. The IPv6 is enabled to my interface.
I configured the two files hosts.ini and main.yml correctly.
image

After running the gnb, these are the errors that I receive:

image

image

I tried to reset the core again and restart the gnb make aether-resetcore, but still the issues persisted.

Regarding with this hint, "Another possibility is that you have multiple SD-Cores running in the same broadcast domain. This causes ARP to behave in unexpected ways, which interferes with OnRamp's ability to establish a route to the UPF pod", I think i only run one SD-Core in the machine. Can you let me know what command should i execute to observe this ?

image

PacketRusher: a new gNB/UE simulator

Hi OpenNetworking team,

In case you are looking for another gNB/UE Simulator or a 5G Core stress tester to evaluate the performance of the 5G Core, you may try my new open-source tool PacketRusher.
It is similar to the gnbSim tool but supports the simulation of multiples UEs and gNodeBs right from a single tool in parallel, provide an high performance N3 tunnel, and it supports some "advanced" control plane features such as roaming, Xn/NGAP handover, Service Request...

If you need any help, feel free to reach out to me :-) I'm looking forward to collaborate!

Cheers,
Valentin

GNBSIM is not connecting to the core

Hello,

When I ran the command

make aether-gnbsim-run

Summary.log

time="2024-07-23T00:48:20Z" level=info msg="Profile Name: profile2 , Profile Type: pdusessest" category=Summary component=GNBSIM
time="2024-07-23T00:48:20Z" level=info msg="Ue's Passed: 0 , Ue's Failed: 3" category=Summary component=GNBSIM
time="2024-07-23T00:48:20Z" level=info msg="Profile Errors:" category=Summary component=GNBSIM
time="2024-07-23T00:48:20Z" level=error msg="imsi:imsi-208930100007489, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=Summary component=GNBSIM
time="2024-07-23T00:48:20Z" level=error msg="imsi:imsi-208930100007488, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=Summary component=GNBSIM
time="2024-07-23T00:48:20Z" level=error msg="imsi:imsi-208930100007487, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=Summary component=GNBSIM
time="2024-07-23T00:48:20Z" level=info msg="Profile Status: FAIL" category=Summary component=GNBSIM

Error on the command line after running the command

TASK [simulator : force stop gnbsim] *********************************************************************************************************************************************************************************
fatal: [node1]: FAILED! => {"changed": true, "cmd": "pkill -9 -f /gnbsim/bin/gnbsim\n", "delta": "0:00:00.029917", "end": "2024-07-23 00:48:35.639238", "msg": "non-zero return code", "rc": -9, "start": "2024-07-23 00:48:35.609321", "stderr": "", "stderr_lines": [], "stdout": "", "stdout_lines": []}

gnbsim.log

time="2024-07-23T00:48:18Z" level=info msg="App Name: GNBSIM" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Setting log level to: info" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="GNodeB IP:  GNodeB Port: 9487" category=GNodeB component=GNBSIM gnb=gnb1
time="2024-07-23T00:48:18Z" level=info msg="User Plane transport listening on: 172.20.0.2:2152" category=GNodeB component=GNBSIM subcategory=UserPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Current range selector value: 217" category=GNodeB component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Current ID range start: 3640655872 end: 3657433087" category=GNodeB component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Connected to AMF, AMF IP: 10.80.103.82 AMF Port: 38412" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Wrote 61 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:18.490405587 +0000 UTC m=+0.022420237  1 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Read 48 bytes from 10.80.103.82:38412\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="NG Setup Successful: true" category=GNodeB component=GNBSIM gnb=gnb1
time="2024-07-23T00:48:18Z" level=error msg="disabled profileType  register" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Init profile: profile2 , profile type: pdusessest" category=Profile component=GNBSIM profile=profile2
time="2024-07-23T00:48:18Z" level=info msg="Adding new GnbCpUe for RANUENGAPID: 3640655872" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:18Z" level=info msg="Connected to gNodeB, Name:gnb1, IP:, Port:9487" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="SIM UE Init complete" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Adding new GnbCpUe for RANUENGAPID: 3640655873" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:18Z" level=info msg="Connected to gNodeB, Name:gnb1, IP:, Port:9487" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="SIM UE Init complete" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Adding new GnbCpUe for RANUENGAPID: 3640655874" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:18Z" level=info msg="Connected to gNodeB, Name:gnb1, IP:, Port:9487" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="SIM UE Init complete" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=error msg="disabled profileType  anrelease" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=error msg="disabled profileType  uetriggservicereq" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=error msg="disabled profileType  deregister" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=error msg="disabled profileType  nwtriggeruedereg" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=error msg="disabled profileType  uereqpdusessrelease" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=error msg="disabled profileType  nwreqpdusessrelease" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=error msg="disabled profileType  custom" category=App component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="ExecuteProfile started " category=Profile component=GNBSIM profile=profile2
time="2024-07-23T00:48:18Z" level=info msg="ExecuteProfile ExecInParallel true. Waiting for for all UEs to finish processing" category=Profile component=GNBSIM profile=profile2
time="2024-07-23T00:48:18Z" level=info msg="Handling event: CONNECTION-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Handling event: CONNECTION-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Handling event: CONNECTION-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Execute procedure  REGISTRATION-PROCEDURE" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Execute procedure  REGISTRATION-PROCEDURE" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Waiting for procedure result from imsiStateMachine" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Execute procedure  REGISTRATION-PROCEDURE" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling event: PROC-START-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Waiting for procedure result from imsiStateMachine" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Waiting for procedure result from imsiStateMachine" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling event: PROC-START-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Handling event: PROC-START-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Start new procedure  REGISTRATION-PROCEDURE" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Initiating Registration Procedure" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling: REGISTRATION-REQUEST-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling event: REGISTRATION-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling event: REGISTRATION-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Start new procedure  REGISTRATION-PROCEDURE" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Start new procedure  REGISTRATION-PROCEDURE" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Received Event: REG_PROC_START:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 3 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Initiating Registration Procedure" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Handling: REGISTRATION-REQUEST-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Handling event: REGISTRATION-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Handling event: REGISTRATION-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Initiating Registration Procedure" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Handling: REGISTRATION-REQUEST-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Received Event: REG_REQ_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 4 1}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Received Event: REG_PROC_START:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 3 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Handling event: REGISTRATION-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Received Event: REG_REQ_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 4 2}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Handling event: REGISTRATION-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Received Event: REG_PROC_START:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 3 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Received Event: REG_REQ_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 4 3}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:18.494850399 +0000 UTC m=+0.026865071  1 1}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007489" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Wrote 73 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Wrote 73 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:18.495010898 +0000 UTC m=+0.027025527  1 2}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007488" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Wrote 73 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:18.49515934 +0000 UTC m=+0.027174012  1 3}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007487" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:18.697038972 +0000 UTC m=+0.229053637  2 4}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Read 71 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655874" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:18Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Received Message Type: 86" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling event: AUTHENTICATION-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Next Event: AUTHENTICATION-RESPONSE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling: AUTHENTICATION-RESPONSE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Received Event: AUTH_REQ_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 5 4}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007489" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Time[us] between Reg Req & Auth Req  202188" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Received Event: AUTH_RSP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 6 5}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Handling event: AUTHENTICATION-RESPONSE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:18Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Wrote 69 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:18.699853526 +0000 UTC m=+0.231868093  1 5}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007489" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Read 71 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:18.887278128 +0000 UTC m=+0.419292654  2 6}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655872" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:18Z" level=info msg="Read 71 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655873" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:18Z" level=info msg="Received Message Type: 86" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:18.887750352 +0000 UTC m=+0.419764950  2 7}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Handling event: AUTHENTICATION-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Next Event: AUTHENTICATION-RESPONSE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Handling: AUTHENTICATION-RESPONSE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Received Event: AUTH_REQ_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 5 6}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007487" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Time[us] between Reg Req & Auth Req  392118" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Handling event: AUTHENTICATION-RESPONSE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:18Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Received Event: AUTH_RSP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 6 8}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Received Message Type: 86" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Handling event: AUTHENTICATION-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Next Event: AUTHENTICATION-RESPONSE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Handling: AUTHENTICATION-RESPONSE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Received Event: AUTH_REQ_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 5 7}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007488" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Time[us] between Reg Req & Auth Req  392739" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Received Event: AUTH_RSP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 6 9}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Handling event: AUTHENTICATION-RESPONSE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:18Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:18Z" level=info msg="Wrote 69 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:18.888836888 +0000 UTC m=+0.420851675  1 8}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007487" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Wrote 69 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:18Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:18.889327578 +0000 UTC m=+0.421342153  1 9}" category=Stats component=GNBSIM
time="2024-07-23T00:48:18Z" level=info msg="Find the UE  imsi-208930100007488" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:19.001714491 +0000 UTC m=+0.533729267  2 10}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Read 48 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655874" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:19Z" level=info msg="Read 48 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x0)" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="NAS integrity key: 132002aeadee2197a7b366d64af75590" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:19.00218133 +0000 UTC m=+0.534195973  2 11}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="cmac value: 0xc1ce291c" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Received Message Type: 93" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling event: SECURITY-MODE-COMMAND-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655872" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:19Z" level=info msg="Received Event: SECM_CMD_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 7 10}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007489" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Time[us] between Auth Rsp and Sec M Req  301861" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling: SECURITY-MODE-COMPLETE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Handling event: SECURITY-MODE-COMPLETE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Received Event: SECM_CMP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 8 12}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x0)" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="NAS integrity key: 543452e20929852366a1929fb3d93e6d" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="cmac value: 0xa190fc1e" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Received Message Type: 93" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Handling event: SECURITY-MODE-COMMAND-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Handling: SECURITY-MODE-COMPLETE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Received Event: SECM_CMD_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 7 11}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007487" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling event: SECURITY-MODE-COMPLETE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Time[us] between Auth Rsp and Sec M Req  113344" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: SECM_CMP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 8 13}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.003099551 +0000 UTC m=+0.535114262  1 12}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007489" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 99 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Wrote 99 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.003205542 +0000 UTC m=+0.535220130  1 13}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007487" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:19.100965596 +0000 UTC m=+0.632980249  2 14}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Read 48 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655873" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x0)" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="NAS integrity key: 4e5eaf1ba7595a442c4b040a4f531836" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="cmac value: 0xcfc2a656" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Received Message Type: 93" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling event: SECURITY-MODE-COMMAND-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling: SECURITY-MODE-COMPLETE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling event: SECURITY-MODE-COMPLETE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Received Event: SECM_CMD_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 7 14}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007488" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Time[us] between Auth Rsp and Sec M Req  211638" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: SECM_CMP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 8 15}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 99 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.10200884 +0000 UTC m=+0.634023444  1 15}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007488" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Read 153 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:19.294922374 +0000 UTC m=+0.826936982  2 16}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655874" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:19Z" level=info msg="Handling event: INITIAL-CONTEXT-SETUP-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x1)" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="NAS integrity key: 132002aeadee2197a7b366d64af75590" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="cmac value: 0x56724143" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Received Message Type: 66" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling event: REGISTRATION-ACCEPT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling: REGISTRATION-COMPLETE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Received Event: ICS_REQ_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 9 16}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007489" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling event: REGISTRATION-COMPLETE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Time[us] between Sec Mod Cmd & ICSReq  291822" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: REG_COMP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 10 17}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.296060455 +0000 UTC m=+0.828074985  1 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 24 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="imsiStateMachine received result " category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="procedure: REGISTRATION-PROCEDURE, status: PROC-PASS-EVENT, E2E latency [ms]: 802" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Procedure Result: PASS, imsi: imsi-208930100007489" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Execute procedure  PDU-SESSION-ESTABLISHMENT-PROCEDURE" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Waiting for procedure result from imsiStateMachine" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling event: PROC-START-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Start new procedure  PDU-SESSION-ESTABLISHMENT-PROCEDURE" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Initiating UE Requested PDU Session Establishment Procedure" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling: PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Received Event: REG_PROC_START:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 3 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: PDU_SESS_REQ_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007489 18 18}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling event: PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Wrote 58 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.296675426 +0000 UTC m=+0.828690001  1 17}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007489" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 101 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.297114197 +0000 UTC m=+0.829128763  1 18}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007489" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Read 153 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:19.490082097 +0000 UTC m=+1.022096933  2 19}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655872" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:19Z" level=info msg="Handling event: INITIAL-CONTEXT-SETUP-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x1)" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="NAS integrity key: 543452e20929852366a1929fb3d93e6d" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="cmac value: 0x1eb7d5e2" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Received Message Type: 66" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Handling event: REGISTRATION-ACCEPT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Received Event: ICS_REQ_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 9 19}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling: REGISTRATION-COMPLETE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007487" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Time[us] between Sec Mod Cmd & ICSReq  486876" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 24 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Handling event: REGISTRATION-COMPLETE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="imsiStateMachine received result " category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="procedure: REGISTRATION-PROCEDURE, status: PROC-PASS-EVENT, E2E latency [ms]: 997" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Procedure Result: PASS, imsi: imsi-208930100007487" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Received Event: REG_COMP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 10 20}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.49132537 +0000 UTC m=+1.023340050  1 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Execute procedure  PDU-SESSION-ESTABLISHMENT-PROCEDURE" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Waiting for procedure result from imsiStateMachine" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Handling event: PROC-START-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Start new procedure  PDU-SESSION-ESTABLISHMENT-PROCEDURE" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Initiating UE Requested PDU Session Establishment Procedure" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Handling: PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Received Event: REG_PROC_START:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 3 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: PDU_SESS_REQ_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007487 18 21}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling event: PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:19Z" level=info msg="Wrote 58 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.491907205 +0000 UTC m=+1.023922125  1 20}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007487" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 101 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.492308523 +0000 UTC m=+1.024323047  1 21}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007487" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:19.686157003 +0000 UTC m=+1.218171596  2 22}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Read 153 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655873" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:19Z" level=info msg="Handling event: INITIAL-CONTEXT-SETUP-REQUEST-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x1)" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="NAS integrity key: 4e5eaf1ba7595a442c4b040a4f531836" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="cmac value: 0xb1d06daa" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Received Message Type: 66" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling event: REGISTRATION-ACCEPT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling: REGISTRATION-COMPLETE-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Received Event: ICS_REQ_IN:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 9 22}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 24 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007488" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Time[us] between Sec Mod Cmd & ICSReq  584148" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling event: REGISTRATION-COMPLETE-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Received Event: REG_COMP_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 10 23}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.687481632 +0000 UTC m=+1.219496187  1 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="imsiStateMachine received result " category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="procedure: REGISTRATION-PROCEDURE, status: PROC-PASS-EVENT, E2E latency [ms]: 1193" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Procedure Result: PASS, imsi: imsi-208930100007488" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Execute procedure  PDU-SESSION-ESTABLISHMENT-PROCEDURE" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Waiting for procedure result from imsiStateMachine" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling event: PROC-START-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Start new procedure  PDU-SESSION-ESTABLISHMENT-PROCEDURE" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Initiating UE Requested PDU Session Establishment Procedure" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling: PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling event: PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Received Event: REG_PROC_START:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 3 0}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: PDU_SESS_REQ_OUT:  &{0001-01-01 00:00:00 +0000 UTC imsi-208930100007488 18 24}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 58 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Handling event: UL-INFO-TRANSFER-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.688051336 +0000 UTC m=+1.220065974  1 23}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007488" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Wrote 101 bytes\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_OUT:  &{2024-07-23 00:48:19.688480787 +0000 UTC m=+1.220495396  1 24}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Find the UE  imsi-208930100007488" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:19.795011443 +0000 UTC m=+1.327026000  2 25}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Read 49 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655874" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x2)" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="NAS integrity key: 132002aeadee2197a7b366d64af75590" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="cmac value: 0xaae6f028" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Received Message Type: 104" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Payload contaner type:{1}" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling event: PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=error msg="CheckCurrentEvent returned: triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=error msg="Failed to handle event: PDU-SESSION-ESTABLISHMENT-REJECT-EVENT Error: triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling: QUIT-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Real UE terminated" category=RealUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Sim UE terminated" category=SimUe component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="imsiStateMachine received result " category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Handling event: QUIT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="gNB Control-Plane UE context terminated" category=GNodeB component=GNBSIM ranuengapid=3640655874 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="procedure: PDU-SESSION-ESTABLISHMENT-PROCEDURE, status: PROC-FAIL-EVENT, E2E latency [ms]: 499" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Result: FAIL, imsi:imsi-208930100007489, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="imsiStateMachine ended" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007489
time="2024-07-23T00:48:19Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:19.995813015 +0000 UTC m=+1.527827676  2 26}" category=Stats component=GNBSIM
time="2024-07-23T00:48:19Z" level=info msg="Read 49 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:19Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655873" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x2)" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="NAS integrity key: 4e5eaf1ba7595a442c4b040a4f531836" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="cmac value: 0x2a19c228" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Received Message Type: 104" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Payload contaner type:{1}" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling event: PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=error msg="CheckCurrentEvent returned: triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=error msg="Failed to handle event: PDU-SESSION-ESTABLISHMENT-REJECT-EVENT Error: triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling: QUIT-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Real UE terminated" category=RealUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Sim UE terminated" category=SimUe component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Handling event: QUIT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="imsiStateMachine received result " category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="gNB Control-Plane UE context terminated" category=GNodeB component=GNBSIM ranuengapid=3640655873 subcategory=GnbCpUe
time="2024-07-23T00:48:19Z" level=info msg="procedure: PDU-SESSION-ESTABLISHMENT-PROCEDURE, status: PROC-FAIL-EVENT, E2E latency [ms]: 308" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="Result: FAIL, imsi:imsi-208930100007488, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:19Z" level=info msg="imsiStateMachine ended" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007488
time="2024-07-23T00:48:20Z" level=info msg="Read 49 bytes from 10.80.103.82\n" category=GNodeB component=GNBSIM subcategory=ControlPlaneTransport
time="2024-07-23T00:48:20Z" level=info msg="Received Event: MSG_IN:  &{2024-07-23 00:48:20.098952253 +0000 UTC m=+1.630966906  2 27}" category=Stats component=GNBSIM
time="2024-07-23T00:48:20Z" level=info msg="Fetching GnbCpUe for RANUENGAPID: 3640655872" category=GNodeB component=GNBSIM subcategory=GnbUeDao
time="2024-07-23T00:48:20Z" level=info msg="Handling event: DOWNLINK-NAS-TRANSPORT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:20Z" level=info msg="Handling event: DL-INFO-TRANSFER-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Handling: DL-INFO-TRANSFER-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Calculate NAS MAC (algorithm: 2, DLCount: 0x2)" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="NAS integrity key: 543452e20929852366a1929fb3d93e6d" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="cmac value: 0x9da4c14f" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Received Message Type: 104" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Payload contaner type:{1}" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Handling event: PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=error msg="CheckCurrentEvent returned: triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=error msg="Failed to handle event: PDU-SESSION-ESTABLISHMENT-REJECT-EVENT Error: triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Handling: QUIT-EVENT" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Real UE terminated" category=RealUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="imsiStateMachine received result " category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="procedure: PDU-SESSION-ESTABLISHMENT-PROCEDURE, status: PROC-FAIL-EVENT, E2E latency [ms]: 608" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Handling event: QUIT-EVENT" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:20Z" level=info msg="Result: FAIL, imsi:imsi-208930100007487, procedure:PDU-SESSION-ESTABLISHMENT-PROCEDURE, error:triggering event:PDU-SESSION-ESTABLISHMENT-REQUEST-EVENT, expected event:PDU-SESSION-ESTABLISHMENT-ACCEPT-EVENT, received event:PDU-SESSION-ESTABLISHMENT-REJECT-EVENT" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="Sim UE terminated" category=SimUe component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="imsiStateMachine ended" category=ProfUeCtx component=GNBSIM supi=imsi-208930100007487
time="2024-07-23T00:48:20Z" level=info msg="gNB Control-Plane UE context terminated" category=GNodeB component=GNBSIM ranuengapid=3640655872 subcategory=GnbCpUe
time="2024-07-23T00:48:20Z" level=info msg="ExecuteProfile ended" category=Profile component=GNBSIM profile=profile2
time="2024-07-23T00:48:25Z" level=info msg="Dump all metrics" category=Stats component=GNBSIM
time="2024-07-23T00:48:25Z" level=info msg="UE: imsi-208930100007487, TotalRegTime[us]: 992338, RegReqAuthReq[us]: 392118,  AuthRspSecMReq[us]: 113344, SecModeRspICReq[us]: 486876" category=Stats component=GNBSIM
time="2024-07-23T00:48:25Z" level=info msg="UE: imsi-208930100007488, TotalRegTime[us]: 1188525, RegReqAuthReq[us]: 392739,  AuthRspSecMReq[us]: 211638, SecModeRspICReq[us]: 584148" category=Stats component=GNBSIM
time="2024-07-23T00:48:25Z" level=info msg="UE: imsi-208930100007489, TotalRegTime[us]: 795871, RegReqAuthReq[us]: 202188,  AuthRspSecMReq[us]: 301861, SecModeRspICReq[us]: 291822" category=Stats component=GNBSIM

FAILED - RETRYING: [node1]: async check gnbsims

Hi there,
When I want to run the command

make aether-gnbsim-run

It keeps showing the error:

FAILED - RETRYING: [node1]: async check gnbsims (500 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (499 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (498 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (497 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (496 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (495 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (494 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (493 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (492 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (491 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (490 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (489 retries left).
FAILED - RETRYING: [node1]: async check gnbsims (488 retries left).
...

TASK [simulator : force stop gnbsims] **********************************************************************************************************************************************************
fatal: [node1]: FAILED! => {"changed": true, "cmd": "pkill -9 -f /gnbsim/bin/gnbsim\n", "delta": "0:00:00.026968", "end": "2024-04-22 20:36:39.871594", "msg": "non-zero return code", "rc": -9, "start": "2024-04-22 20:36:39.844626", "stderr": "", "stderr_lines": [], "stdout": "", "stdout_lines": []}
...ignoring


Can you help me solve this problem?
The summary.log looks like this:

docker exec -it gnbsim-1 cat summary.log
time="2024-04-23T02:36:27Z" level=info msg="Profile Name: profile3 , Profile Type: anrelease" category=Summary component=GNBSIM
time="2024-04-23T02:36:27Z" level=info msg="Ue's Passed: 2 , Ue's Failed: 3" category=Summary component=GNBSIM
time="2024-04-23T02:36:27Z" level=info msg="Profile Errors:" category=Summary component=GNBSIM
time="2024-04-23T02:36:27Z" level=error msg="imsi:imsi-208930100007499, profile timeout" category=Summary component=GNBSIM
time="2024-04-23T02:36:27Z" level=error msg="imsi:imsi-208930100007500, profile timeout" category=Summary component=GNBSIM
time="2024-04-23T02:36:27Z" level=error msg="imsi:imsi-208930100007501, profile timeout" category=Summary component=GNBSIM
time="2024-04-23T02:36:27Z" level=info msg="Profile Status: FAIL" category=Summary component=GNBSIM

Error when running make 5gc install

I am working using the quickstart guide on Aether website but when I run the following command :
make aether-5gc-install

Get the following failure error from one of the Ansible tasks in the path /home/atlas-support/projects/aether-onramp/deps/5gc/roles/core/tasks/install.yml:

- name: deploy aether 5gc
  block:
    - name: deploy aether 5gc
      kubernetes.core.helm:
        update_repo_cache: true
        name: sd-core
        release_namespace: omec
        create_namespace: true
        chart_ref: "{{ core.helm.chart_ref }}"
        chart_version: "{{ core.helm.chart_version }}"
        values_files:
          - /tmp/sdcore-5g-values.yaml
        wait: true
        wait_timeout: "1m30s"
        force: true
      when: inventory_hostname in groups['master_nodes'] 


TASK [core : deploy aether 5gc] ****************************************************************************************************************
fatal: [node1]: FAILED! => {"changed": false, "command": "/usr/local/bin/helm --version=0.12.6 upgrade -i --reset-values --wait --timeout 1m30s --create-namespace --values=/tmp/sdcore-5g-values.yaml sd-core aether/sd-core", "msg": "Failure when executing Helm command. Exited 1.\nstdout: Release \"sd-core\" does not exist. Installing it now.\n\nstderr: coalesce.go:175: warning: skipped value for kafka.config: Not a table.\nError: client rate limiter Wait returned an error: rate: Wait(n=1) would exceed context deadline\n", "stderr": "coalesce.go:175: warning: skipped value for kafka.config: Not a table.\nError: client rate limiter Wait returned an error: rate: Wait(n=1) would exceed context deadline\n", "stderr_lines": ["coalesce.go:175: warning: skipped value for kafka.config: Not a table.", "Error: client rate limiter Wait returned an error: rate: Wait(n=1) would exceed context deadline"], "stdout": "Release \"sd-core\" does not exist. Installing it now.\n", "stdout_lines": ["Release \"sd-core\" does not exist. Installing it now."]}

The status shows one of the deployed pods crashes. Any suggestions on where are things going wrong ?

kubectl get pods -n omec

NAME READY STATUS RESTARTS AGE
amf-5887bbf6c5-f9nbb 1/1 Running 0 13m
ausf-6dbb7655c7-mlcgb 1/1 Running 0 11m
kafka-0 1/1 Running 1 (12m ago) 13m
metricfunc-b9f8c667b-9wfgq 1/1 Running 0 13m
mongodb-0 1/1 Running 0 13m
mongodb-1 1/1 Running 0 12m
mongodb-arbiter-0 1/1 Running 0 13m
nrf-54bf88c78c-tmn8f 1/1 Running 0 13m
nssf-5b85b8978d-dxcrv 1/1 Running 0 13m
pcf-758d7cfb48-tlflp 1/1 Running 0 13m
sd-core-zookeeper-0 1/1 Running 0 13m
simapp-6cccd6f787-ng7f4 1/1 Running 0 13m
smf-7f89c6d849-njxvk 1/1 Running 0 13m
udm-768b9987b4-ppckx 1/1 Running 0 13m
udr-8566897d45-6d82h 1/1 Running 0 13m
upf-0 3/5 CrashLoopBackOff 13 (15s ago) 11m
webui-5894ffd49d-4bcgg 1/1 Running 0 13m

UE does not get internet

Hi,
I have built aether-onramp successfully and did make aether-5gc-install but my UE quectel RM-520N-GL with 3GPP release 16 does not get internet. For your kind note upf is able to ping 8.8.8.8. Then my host linux network setting is set to be as follows:

ipv4-forwarding enable in sysctl
sudo iptables -t nat -A POSTROUTING -o dn-interface -j MASQUERADE
firewall is also disabled
Could you please help me to get internet to UE?

Error installing 5gc-router

When I issue the make aether-5gc-install I get the following error:

ansible-playbook -i /home/cc/aether-onramp/hosts.ini /home/cc/aether-onramp/deps/5gc/router.yml --tags install
--extra-vars "ROOT_DIR=/home/cc/aether-onramp" --extra-vars "@/home/cc/aether-onramp/vars/main.yml"

PLAY [provision router] **********************************************************************************************************************************************************************

TASK [Gathering Facts] ***********************************************************************************************************************************************************************
ok: [node1]

TASK [router : set_fact] *********************************************************************************************************************************************************************
ok: [node1]

TASK [router : set net.ipv4.conf.all.arp_filter to 1] ****************************************************************************************************************************************
ok: [node1]

TASK [router : set net.ipv4.conf.all.arp_ignore to 1] ****************************************************************************************************************************************
ok: [node1]

TASK [router : set net.ipv4.conf.all.arp_announce to 2] **************************************************************************************************************************************
ok: [node1]

TASK [router : set net.ipv4.conf.all.rp_filter to 2] *****************************************************************************************************************************************
ok: [node1]

TASK [router : copy 10-aether-access.netdev to /etc/systemd/network/10-aether-access.netdev] *************************************************************************************************
ok: [node1]

TASK [router : copy 20-aether-access.network to /etc/systemd/network/20-aether-access.network] ***********************************************************************************************
ok: [node1]

TASK [router : copy 10-aether-core.netdev to /etc/systemd/network/10-aether-core.netdev] *****************************************************************************************************
ok: [node1]

TASK [router : copy 20-aether-core.network to /etc/systemd/network/20-aether-core.network] ***************************************************************************************************
ok: [node1]

TASK [router : find eno1's netplan network directory] ****************************************************************************************************************************************
fatal: [node1]: FAILED! => {"changed": true, "cmd": "basename $(find /*/systemd/network -maxdepth 1 -not -type d -name '*eno1.network' -print)", "delta": "0:00:00.006468", "end": "2023-09-07 01:09:35.396305", "msg": "non-zero return code", "rc": 1, "start": "2023-09-07 01:09:35.389837", "stderr": "basename: missing operand\nTry 'basename --help' for more information.", "stderr_lines": ["basename: missing operand", "Try 'basename --help' for more information."], "stdout": "", "stdout_lines": []}

PLAY RECAP ***********************************************************************************************************************************************************************************
node1 : ok=10 changed=0 unreachable=0 failed=1 skipped=0 rescued=0 ignored=0

SMF does not connect via PFCP

Hello everyone!

I'm starting to use Aether to test some gNB solutions. At first I installed OnRamp, following the instructions here: https://docs.aetherproject.org/master/onramp/start.html

I changed some parameters to match my network: plmnid, mcc, mnc, imsi and dnn

After uploading k8s, 5gc and amp, I was finally able to connect gNB to authenticate a UE.

The problem is that it still hasn't authenticated. After some time trying to figure out what's going on, I noticed that there are some flaws in the SMF pod. Does anyone know how to solve this?

image

Edit: I couldn't find any endpoint with this address 10.42.112.112

failed on install k8s

During execute the command of " make aether-k8s-install", the install will stopped at the phase as below, and can't find the detailed failed reason, could you help to check any problems?

task path: /home/summer/aether/aether-onramp/deps/k8s/roles/rke2/tasks/install.yml:38
Using module file /home/summer/.local/pipx/venvs/ansible/lib/python3.8/site-packages/ansible/modules/file.py
Pipelining is enabled.
<192.168.100.113> ESTABLISH SSH CONNECTION FOR USER: summer
<192.168.100.113> SSH: EXEC sshpass -d10 ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o ControlMaster=auto -o ControlPersist=5m -o LogLevel=QUIET -o ForwardAgent=yes -o StrictHostKeyChecking=no -o 'IdentityFile="/home/summer/.ssh/id_rsa"' -o 'User="summer"' -o ConnectTimeout=10 -o 'ControlPath="/tmp/ansible-%r@%h:%p"' 192.168.100.113 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=txskhqaohwfstyndrkspcxkrpdledlvm] password:" -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-txskhqaohwfstyndrkspcxkrpdledlvm ; /usr/bin/python3'"'"'"'"'"'"'"'"' && sleep 0'"'"''
Escalation succeeded
<192.168.100.113> (0, b'\n{"path": "/etc/rancher/rke2", "changed": false, "diff": {"before": {"path": "/etc/rancher/rke2"}, "after": {"path": "/etc/rancher/rke2"}}, "uid": 0, "gid": 0, "owner": "root", "group": "root", "mode": "0755", "state": "directory", "size": 4096, "invocation": {"module_args": {"path": "/etc/rancher/rke2", "state": "directory", "recurse": false, "force": false, "follow": true, "modification_time_format": "%Y%m%d%H%M.%S", "access_time_format": "%Y%m%d%H%M.%S", "unsafe_writes": false, "_original_basename": null, "_diff_peek": null, "src": null, "modification_time": null, "access_time": null, "mode": null, "owner": null, "group": null, "seuser": null, "serole": null, "selevel": null, "setype": null, "attributes": null}}}\n', b'')
ok: [node1] => {
"changed": false,
"diff": {
"after": {
"path": "/etc/rancher/rke2"
},
"before": {
"path": "/etc/rancher/rke2"
}
},
"gid": 0,
"group": "root",
"invocation": {
"module_args": {
"_diff_peek": null,
"_original_basename": null,
"access_time": null,
"access_time_format": "%Y%m%d%H%M.%S",
"attributes": null,
"follow": true,
"force": false,
"group": null,
"mode": null,
"modification_time": null,
"modification_time_format": "%Y%m%d%H%M.%S",
"owner": null,
"path": "/etc/rancher/rke2",
"recurse": false,
"selevel": null,
"serole": null,
"setype": null,
"seuser": null,
"src": null,
"state": "directory",
"unsafe_writes": false
}
},
"mode": "0755",
"owner": "root",
"path": "/etc/rancher/rke2",
"size": 4096,
"state": "directory",
"uid": 0
}
Read vars_file 'vars/main.yml'

TASK [rke2 : install rke2 on masters] ***************************************************************************************************************************************************************************************************
task path: /home/summer/aether/aether-onramp/deps/k8s/roles/rke2/tasks/install.yml:44
Using module file /home/summer/.local/pipx/venvs/ansible/lib/python3.8/site-packages/ansible/modules/command.py
Pipelining is enabled.
<192.168.100.113> ESTABLISH SSH CONNECTION FOR USER: summer
<192.168.100.113> SSH: EXEC sshpass -d10 ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -o ControlMaster=auto -o ControlPersist=5m -o LogLevel=QUIET -o ForwardAgent=yes -o StrictHostKeyChecking=no -o 'IdentityFile="/home/summer/.ssh/id_rsa"' -o 'User="summer"' -o ConnectTimeout=10 -o 'ControlPath="/tmp/ansible-%r@%h:%p"' 192.168.100.113 '/bin/sh -c '"'"'sudo -H -S -p "[sudo via ansible, key=bbqzsqjyrkjfzxhgkbeltovfbinjxokr] password:" -u root /bin/sh -c '"'"'"'"'"'"'"'"'echo BECOME-SUCCESS-bbqzsqjyrkjfzxhgkbeltovfbinjxokr ; INSTALL_RKE2_VERSION=v1.23.4+rke2r1 /usr/bin/python3'"'"'"'"'"'"'"'"' && sleep 0'"'"''
Escalation succeeded

Recommend Projects

  • React photo React

    A declarative, efficient, and flexible JavaScript library for building user interfaces.

  • Vue.js photo Vue.js

    ๐Ÿ–– Vue.js is a progressive, incrementally-adoptable JavaScript framework for building UI on the web.

  • Typescript photo Typescript

    TypeScript is a superset of JavaScript that compiles to clean JavaScript output.

  • TensorFlow photo TensorFlow

    An Open Source Machine Learning Framework for Everyone

  • Django photo Django

    The Web framework for perfectionists with deadlines.

  • D3 photo D3

    Bring data to life with SVG, Canvas and HTML. ๐Ÿ“Š๐Ÿ“ˆ๐ŸŽ‰

Recommend Topics

  • javascript

    JavaScript (JS) is a lightweight interpreted programming language with first-class functions.

  • web

    Some thing interesting about web. New door for the world.

  • server

    A server is a program made to process requests and deliver data to clients.

  • Machine learning

    Machine learning is a way of modeling and interpreting data that allows a piece of software to respond intelligently.

  • Game

    Some thing interesting about game, make everyone happy.

Recommend Org

  • Facebook photo Facebook

    We are working to build community through open source technology. NB: members must have two-factor auth.

  • Microsoft photo Microsoft

    Open source projects and samples from Microsoft.

  • Google photo Google

    Google โค๏ธ Open Source for everyone.

  • D3 photo D3

    Data-Driven Documents codes.