TestNon3GPP
cd ~/free5gc
./test.sh TestNon3GPP |& tee TestNon3GPP.log
Result Screen
2021-04-23T02:47:51Z [INFO][UPF][Util] Config: /home/free5gc/free5gc/NFs/upf/build/config/upfcfg.test.yaml
2021-04-23T02:47:51Z [INFO][UPF][Util] UPF config version [1.0.0]
2021-04-23T02:47:51Z [INFO][UPF][Util] Set log level: info
2021-04-23T02:47:51Z [INFO][UPF][Util] DNN routes added, main routing table:
2021-04-23T02:47:51Z [INFO][UPF][Util] DstIp Gateway Iface Priority RtProto Type
2021-04-23T02:47:51Z [INFO][UPF][Util] 60.60.0.0 /24 0.0.0.0 upfgtp 0 static unicast
2021-04-23T02:47:51Z [INFO][UPF][Util] 10.200.200.0 /24 0.0.0.0 veth1 0 kernel unicast
2021-04-23T02:47:56Z [INFO][NRF][Init] NRF Log level is set to [info] level
2021-04-23T02:47:56Z [INFO][LIB][Path] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][Path] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][MonDB] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][MonDB] set report call : false
2021-04-23T02:47:56Z [INFO][NRF][CFG] config version [1.0.0]
2021-04-23T02:47:56Z [INFO][NRF][Init] Server started
2021-04-23T02:47:56Z [INFO][NRF][Init] nrfconfig Info: Version[1.0.0] Description[NRF initial local configuration]
2021-04-23T02:47:56Z [INFO][NRF][Init] Binding addr: [127.0.0.10:8000]
2021-04-23T02:47:56Z [INFO][AMF][Init] AMF Log level is set to [info] level
2021-04-23T02:47:56Z [INFO][LIB][NAS] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][NAS] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][NGAP] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][NGAP] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][FSM] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][FSM] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][Aper] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][Aper] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][Path] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][Path] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:47:56Z [INFO][AMF][CFG] config version [1.0.1]
2021-04-23T02:47:56Z [INFO][AMF][Init] Server started
2021-04-23T02:47:56Z [INFO][AMF][Util] amfconfig Info: Version[1.0.1] Description[AMF initial local configuration]
2021-04-23T02:47:56Z [INFO][AMF][NGAP] Listen on 10.200.200.1:38412
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:47:56Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/af48a080-b3ca-4eee-9742-0864128e52cc
2021-04-23T02:47:56Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/af48a080-b3ca-4eee-9742-0864128e52cc |
2021-04-23T02:47:56Z [INFO][SMF][Init] SMF Log level is set to [info] level
2021-04-23T02:47:56Z [INFO][LIB][NAS] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][NAS] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][NGAP] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][NGAP] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][Aper] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][Aper] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][Path] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][Path] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][PFCP] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][PFCP] set report call : false
2021-04-23T02:47:56Z [INFO][SMF][CFG] SMF config version [1.0.1]
2021-04-23T02:47:56Z [INFO][SMF][CFG] UE-Routing config version [1.0.1]
2021-04-23T02:47:56Z [INFO][SMF][CTX] smfconfig Info: Version[1.0.1] Description[SMF initial local configuration]
2021-04-23T02:47:56Z [INFO][SMF][CTX] Endpoints: [10.200.200.102]
2021-04-23T02:47:56Z [INFO][SMF][Init] Server started
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:47:56Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/10d517bf-0991-40eb-aa3e-5dc9c5415f67
2021-04-23T02:47:56Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/10d517bf-0991-40eb-aa3e-5dc9c5415f67 |
2021-04-23T02:47:56Z [INFO][SMF][Init] SMF Registration to NRF {10d517bf-0991-40eb-aa3e-5dc9c5415f67 SMF REGISTERED 0 0xc000171ae0 0xc000171b40 [] [] [127.0.0.2] [] <nil> [] [] <nil> 0 0 0 <nil> <nil> <nil> <nil> 0xc0003f1c00 <nil> <nil> <nil> <nil> <nil> map[] <nil> false 0xc000171960 false false []}
2021-04-23T02:47:56Z [INFO][SMF][PFCP] Listen on 10.200.200.1:8805
2021-04-23T02:47:56Z [INFO][SMF][App] Send PFCP Association Request to UPF[10.200.200.101]
2021-04-23T02:47:56Z [INFO][UPF][Util] [PFCP] Handle PFCP association setup request
2021-04-23T02:47:56Z [INFO][UPF][Util] [PFCP] Association Setup Response
2021-04-23T02:47:56Z [INFO][SMF][PFCP] In HandlePfcpAssociationSetupResponse
2021-04-23T02:47:56Z [INFO][SMF][PFCP] Handle PFCP Association Setup Response with NodeID[10.200.200.101]
2021-04-23T02:47:56Z [INFO][SMF][PFCP] UPF(10.200.200.101)[internet] setup association
2021-04-23T02:47:56Z [INFO][LIB][PFCP] Remove Request Transaction [1]
2021-04-23T02:47:56Z [INFO][UDR][Init] UDR Log level is set to [info] level
2021-04-23T02:47:56Z [INFO][LIB][Path] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][Path] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:47:56Z [INFO][LIB][MonDB] set log level : info
2021-04-23T02:47:56Z [INFO][LIB][MonDB] set report call : false
2021-04-23T02:47:56Z [INFO][UDR][CFG] config version [1.0.0]
2021-04-23T02:47:56Z [INFO][UDR][Init] UDR Config Info: Version[1.0.0] Description[UDR initial local configuration]
2021-04-23T02:47:56Z [INFO][UDR][Init] Server started
2021-04-23T02:47:56Z [INFO][UDR][Util] udrconfig Info: Version[1.0.0] Description[UDR initial local configuration]
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:47:56Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:47:56Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/cedc79e7-7a87-44ec-b56b-0efced983709
2021-04-23T02:47:56Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/cedc79e7-7a87-44ec-b56b-0efced983709 |
2021-04-23T02:47:57Z [INFO][PCF][Init] PCF Log level is set to [info] level
2021-04-23T02:47:57Z [INFO][LIB][Path] set log level : info
2021-04-23T02:47:57Z [INFO][LIB][Path] set report call : false
2021-04-23T02:47:57Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:47:57Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:47:57Z [INFO][PCF][CFG] config version [1.0.0]
2021-04-23T02:47:57Z [INFO][PCF][Init] Server started
2021-04-23T02:47:57Z [INFO][PCF][Util] pcfconfig Info: Version[1.0.0] Description[PCF initial local configuration]
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:47:57Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/c8659387-5265-4990-b05a-4dca845e34e0
2021-04-23T02:47:57Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/c8659387-5265-4990-b05a-4dca845e34e0 |
2021-04-23T02:47:57Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:47:57Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=PCF&service-names=nudr-dr&target-nf-type=UDR |
2021-04-23T02:47:57Z [INFO][UDM][Init] UDM Log level is set to [info] level
2021-04-23T02:47:57Z [INFO][LIB][Path] set log level : info
2021-04-23T02:47:57Z [INFO][LIB][Path] set report call : false
2021-04-23T02:47:57Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:47:57Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:47:57Z [INFO][UDM][CFG] config version [1.0.0]
2021-04-23T02:47:57Z [INFO][UDM][Init] UDM Config Info: Version[1.0.0] Description[UDM initial local configuration]
2021-04-23T02:47:57Z [INFO][UDM][Init] Server started
2021-04-23T02:47:57Z [INFO][UDM][Util] udmconfig Info: Version[1.0.0] Description[UDM initial local configuration]
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:47:57Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/6ee342b6-a6f3-413c-a72a-edba5cf5f28d
2021-04-23T02:47:57Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/6ee342b6-a6f3-413c-a72a-edba5cf5f28d |
2021-04-23T02:47:57Z [INFO][NSSF][Init] NSSF Log level is set to [info] level
2021-04-23T02:47:57Z [INFO][LIB][Path] set log level : info
2021-04-23T02:47:57Z [INFO][LIB][Path] set report call : false
2021-04-23T02:47:57Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:47:57Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:47:57Z [INFO][NSSF][CFG] config version [1.0.0]
2021-04-23T02:47:57Z [INFO][NSSF][Init] Server started
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:47:57Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/0a286f3d-547d-4e8f-8c5b-d05f887cc476
2021-04-23T02:47:57Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/0a286f3d-547d-4e8f-8c5b-d05f887cc476 |
2021-04-23T02:47:57Z [INFO][AUSF][Init] AUSF Log level is set to [info] level
2021-04-23T02:47:57Z [INFO][LIB][Path] set log level : info
2021-04-23T02:47:57Z [INFO][LIB][Path] set report call : false
2021-04-23T02:47:57Z [INFO][LIB][OAPI] set log level : info
2021-04-23T02:47:57Z [INFO][LIB][OAPI] set report call : false
2021-04-23T02:47:57Z [INFO][AUSF][CFG] config version [1.0.0]
2021-04-23T02:47:57Z [INFO][AUSF][Init] Server started
2021-04-23T02:47:57Z [INFO][AUSF][Init] ausfconfig Info: Version[1.0.0] Description[AUSF initial local configuration]
ausf context = &{{{0 0} {<nil>} map[] 0} {{0 0} {<nil>} map[] 0} 9e096362-4356-437b-aefb-d1b121fbd9cb ausfGroup001 8000 127.0.0.9 127.0.0.9 http://127.0.0.9:8000 http http://127.0.0.10:8000 map[nausf-auth:{9e096362-4356-437b-aefb-d1b121fbd9cb nausf-auth 0xc000582ce0 http REGISTERED 0xc000582cc0 [] <nil> [] [] <nil> 0 0 0 <nil> <nil> }] [{208 93} {123 45}] 0xc0004c3ea0}
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Handle NFRegisterRequest
2021-04-23T02:47:57Z [INFO][NRF][MGMT] urilist update
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Create NF Profile
2021-04-23T02:47:57Z [INFO][NRF][MGMT] Location header: http://127.0.0.10:8000/nnrf-nfm/v1/nf-instances/9e096362-4356-437b-aefb-d1b121fbd9cb
2021-04-23T02:47:57Z [INFO][NRF][GIN] | 201 | 127.0.0.1 | PUT | /nnrf-nfm/v1/nf-instances/9e096362-4356-437b-aefb-d1b121fbd9cb |
=== RUN TestCN
2021-04-23T02:48:05Z [INFO][N3IWF][App] n3iwf
2021-04-23T02:48:05Z [INFO][N3IWF][App] N3IWF version:
Not specify ldflags (which link version) during go build
go version: go1.14.4 linux/amd64
2021-04-23T02:48:05Z [INFO][N3IWF][Init] N3IWF Log level is set to [info] level
2021-04-23T02:48:05Z [INFO][LIB][NGAP] set log level : info
2021-04-23T02:48:05Z [INFO][LIB][NGAP] set report call : false
2021-04-23T02:48:05Z [INFO][LIB][Aper] set log level : info
2021-04-23T02:48:05Z [INFO][LIB][Aper] set report call : false
2021-04-23T02:48:05Z [INFO][LIB][Path] set log level : info
2021-04-23T02:48:05Z [INFO][LIB][Path] set report call : false
2021-04-23T02:48:05Z [INFO][N3IWF][CFG] config version [1.0.0]
2021-04-23T02:48:05Z [INFO][N3IWF][Init] Server started
2021-04-23T02:48:05Z[33m [WARN][N3IWF][Context] No private key file path specified, load default key file...
2021-04-23T02:48:05Z[33m [WARN][N3IWF][Context] Parse PKCS8 private key failed: x509: failed to parse private key (use ParsePKCS1PrivateKey instead for this key format)
2021-04-23T02:48:05Z [INFO][N3IWF][Context] Parse using PKCS1...
2021-04-23T02:48:05Z[33m [WARN][N3IWF][Context] No certificate authority file path specified, load default CA certificate...
2021-04-23T02:48:05Z[33m [WARN][N3IWF][Context] No certificate file path specified, load default certificate...
2021-04-23T02:48:05Z [INFO][N3IWF][NGAP] [N3IWF] Send NG Setup Request
2021-04-23T02:48:05Z [INFO][N3IWF][Init] NGAP service running.
2021-04-23T02:48:05Z [INFO][N3IWF][Init] NAS TCP server successfully started.
2021-04-23T02:48:05Z [INFO][AMF][NGAP] [AMF] SCTP Accept from: 10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927
2021-04-23T02:48:05Z [INFO][N3IWF][Init] Listening NWu user plane traffic
2021-04-23T02:48:05Z [INFO][N3IWF][Init] IKE service running.
2021-04-23T02:48:05Z [INFO][N3IWF][Init] N3IWF running...
2021-04-23T02:48:05Z [INFO][AMF][NGAP] Create a new NG connection for: 10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927
2021-04-23T02:48:05Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Handle NG Setup request
2021-04-23T02:48:05Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Send NG-Setup response
2021-04-23T02:48:05Z [INFO][N3IWF][NGAP] [N3IWF] Handle NG Setup Response
MongoDB Set
=== RUN TestNon3GPPUE
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [KeyExchange] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Nonce] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [KeyExchange] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Nonce] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Handle IKE_SA_INIT
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [KeyExchange] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Nonce] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [KeyExchange] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Nonce] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Identification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [KeyExchange] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Nonce] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [KeyExchange] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Nonce] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] unmarshal(): Start unmarshalling received bytes
TestNon3GPPUE: non3gpp_test.go:581: Get SA payload
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Identification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [TrafficSelector] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [TrafficSelector] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Handle IKE_AUTH
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Identification] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [TrafficSelector] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [TrafficSelector] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Ecoding initiator for later IKE authentication
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Identification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Parsing security association
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Received traffic selector initiator from UE
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Received traffic selector initiator from UE
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Identification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Certificate] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Authentication] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Identification] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Certificate] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Authentication] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] unmarshal(): Start unmarshalling received bytes
TestNon3GPPUE: non3gpp_test.go:678: Get IDr
TestNon3GPPUE: non3gpp_test.go:682: Get CERT
TestNon3GPPUE: non3gpp_test.go:680: Get AUTH
TestNon3GPPUE: non3gpp_test.go:685: Get EAP
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Handle IKE_AUTH
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][NGAP] [N3IWF] Send Initial UE Message
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Handle Initial UE Message
2021-04-23T02:48:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Registration Request
2021-04-23T02:48:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Authentication procedure
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&target-nf-type=AUSF |
2021-04-23T02:48:12Z [INFO][AUSF][UeAuthPost] HandleUeAuthPostRequest
2021-04-23T02:48:12Z [INFO][AUSF][UeAuthPost] Serving network authorized
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AUSF&service-names=nudm-ueau&target-nf-type=UDM |
2021-04-23T02:48:12Z [INFO][UDM][UEAU] Handle GenerateAuthDataRequest
2021-04-23T02:48:12Z [INFO][LIB][3GPP] suciPart [suci 0 208 93 0 0 0 00007487]
2021-04-23T02:48:12Z [INFO][LIB][3GPP] scheme 0
2021-04-23T02:48:12Z [INFO][LIB][3GPP] SUPI type is IMSI
http://127.0.0.10:8000
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle QueryAuthSubsData
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-subscription |
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle ModifyAuthentication
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 204 | 127.0.0.1 | PATCH | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-subscription |
AUTN = ef04d58ef31780001503eb744a3520a0
2021-04-23T02:48:12Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | POST | /nudm-ueau/v1/suci-0-208-93-0-0-0-00007487/security-information/generate-auth-data |
2021-04-23T02:48:12Z [INFO][AUSF][UeAuthPost] Add SuciSupiPair (suci-0-208-93-0-0-0-00007487, imsi-2089300007487) to map.
2021-04-23T02:48:12Z [INFO][AUSF][UeAuthPost] Use 5G AKA auth method
2021-04-23T02:48:12Z [INFO][AUSF][5gAkaAuth] XresStar = 6566306464333732623736306536383133623561313032343034303064343733
2021-04-23T02:48:12Z [INFO][AUSF][GIN] | 201 | 127.0.0.1 | POST | /nausf-auth/v1/ue-authentications |
2021-04-23T02:48:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Send Authentication Request
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2021-04-23T02:48:12Z [INFO][N3IWF][NGAP] [N3IWF] Handle Downlink NAS Transport
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Handle IKE_AUTH
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][NGAP] [N3IWF] Send Uplink NAS Transport
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Handle Uplink Nas Transport
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 0)
2021-04-23T02:48:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1] Handle Authentication Response
2021-04-23T02:48:12Z [INFO][AUSF][5gAkaAuth] Auth5gAkaComfirmRequest
2021-04-23T02:48:12Z [INFO][AUSF][5gAkaAuth] res*: 6566306464333732623736306536383133623561313032343034303064343733
Xres*: 6566306464333732623736306536383133623561313032343034303064343733
2021-04-23T02:48:12Z [INFO][AUSF][5gAkaAuth] 5G AKA confirmation succeeded
2021-04-23T02:48:12Z [INFO][UDM][UEAU] Handle ConfirmAuthDataRequest
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle CreateAuthenticationStatus
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 204 | 127.0.0.1 | PUT | /nudr-dr/v1/subscription-data/imsi-2089300007487/authentication-data/authentication-status |
2021-04-23T02:48:12Z [INFO][UDM][GIN] | 201 | 127.0.0.1 | POST | /nudm-ueau/v1/imsi-2089300007487/auth-events |
2021-04-23T02:48:12Z [INFO][AUSF][GIN] | 200 | 127.0.0.1 | PUT | /nausf-auth/v1/ue-authentications/suci-0-208-93-0-0-0-00007487/5g-aka-confirmation |
2021-04-23T02:48:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Send Security Mode Command
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2021-04-23T02:48:12Z [INFO][N3IWF][NGAP] [N3IWF] Handle Downlink NAS Transport
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Handle IKE_AUTH
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP][Expanded] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][NGAP] [N3IWF] Send Uplink NAS Transport
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Handle Uplink Nas Transport
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 0)
2021-04-23T02:48:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle Security Mode Complete
2021-04-23T02:48:12Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle InitialRegistration
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=UDM |
2021-04-23T02:48:12Z [INFO][UDM][SDM] Handle GetNssai
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle QueryAmData
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/am-data?supported-features= |
2021-04-23T02:48:12Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/nssai?plmn-id=20893 |
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=UDM |
2021-04-23T02:48:12Z [INFO][UDM][UECM] Handle RegisterAmfNon3gppAccessRequest
http://127.0.0.10:8000
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle CreateAmfContextNon3gpp
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 204 | 127.0.0.1 | PUT | /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/amf-non-3gpp-access |
2021-04-23T02:48:12Z [INFO][UDM][GIN] | 201 | 127.0.0.1 | PUT | /nudm-uecm/v1/imsi-2089300007487/registrations/amf-non-3gpp-access |
2021-04-23T02:48:12Z [INFO][UDM][SDM] Handle GetAmData
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle QueryAmData
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/am-data?supported-features=20893 |
2021-04-23T02:48:12Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/am-data?plmn-id=20893 |
2021-04-23T02:48:12Z [INFO][UDM][SDM] Handle GetSmfSelectData
http://127.0.0.10:8000
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle QuerySmfSelectData
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/smf-selection-subscription-data?supported-features= |
2021-04-23T02:48:12Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/smf-select-data?plmn-id=20893 |
2021-04-23T02:48:12Z [INFO][UDM][SDM] Handle GetUeContextInSmfData
http://127.0.0.10:8000
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle QuerySmfRegList
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/smf-registrations?supported-features= |
2021-04-23T02:48:12Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/ue-context-in-smf-data |
2021-04-23T02:48:12Z [INFO][UDM][SDM] Handle Subscribe
http://127.0.0.10:8000
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=UDM&target-nf-type=UDR |
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle CreateSdmSubscriptions
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 201 | 127.0.0.1 | POST | /nudr-dr/v1/subscription-data/imsi-2089300007487/context-data/sdm-subscriptions |
2021-04-23T02:48:12Z [INFO][UDM][GIN] | 201 | 127.0.0.1 | POST | /nudm-sdm/v1/imsi-2089300007487/sdm-subscriptions |
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&supi=imsi-2089300007487&target-nf-type=PCF |
2021-04-23T02:48:12Z [INFO][PCF][Ampolicy] Handle AM Policy Create Request
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=PCF&target-nf-type=UDR |
2021-04-23T02:48:12Z [INFO][UDR][DRepo] Handle PolicyDataUesUeIdAmDataGet
2021-04-23T02:48:12Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/policy-data/ues/imsi-2089300007487/am-data |
2021-04-23T02:48:12Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:12Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?guami=%7B%22plmnId%22%3A%7B%22mcc%22%3A%22208%22%2C%22mnc%22%3A%2293%22%7D%2C%22amfId%22%3A%22cafe00%22%7D&requester-nf-type=PCF&target-nf-type=AMF |
2021-04-23T02:48:12Z [INFO][AMF][Comm] Handle AMF Status Change Subscribe Request
2021-04-23T02:48:12Z [INFO][AMF][Comm] new AMF Status Subscription[1]
2021-04-23T02:48:12Z [INFO][AMF][GIN] | 201 | 127.0.0.1 | POST | /namf-comm/v1/subscriptions |
2021-04-23T02:48:12Z [INFO][PCF][GIN] | 201 | 127.0.0.1 | POST | /npcf-am-policy-control/v1/policies |
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Send Initial Context Setup Request
2021-04-23T02:48:12Z [INFO][N3IWF][NGAP] [N3IWF] Handle Initial Context Setup Request
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [EAP] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Authentication] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Configuration] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Handle IKE_AUTH
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Authentication] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Configuration] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Authentication] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Configuration] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [TrafficSelector] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [TrafficSelector] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][NGAP] [N3IWF] Send Initial Context Setup Response
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Handle Initial Context Setup Response
2021-04-23T02:48:12Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Send Downlink Nas Transport
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Authentication] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Configuration] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [SecurityAssociation] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [TrafficSelector] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [TrafficSelector] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:12Z [INFO][N3IWF][IKE] [Notification] unmarshal(): Start unmarshalling received bytes
TestNon3GPPUE: non3gpp_test.go:969: Get Authentication from N3IWF
2021-04-23T02:48:12Z [INFO][N3IWF][NGAP] [N3IWF] Handle Downlink NAS Transport
2021-04-23T02:48:12Z[33m [WARN][N3IWF][NGAP] No NAS signalling session found, retry...
2021-04-23T02:48:13Z [INFO][N3IWF][NGAP] [N3IWF] Send Uplink NAS Transport
2021-04-23T02:48:13Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Handle Uplink Nas Transport
2021-04-23T02:48:13Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 0)
2021-04-23T02:48:13Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle Registration Complete
2021-04-23T02:48:13Z [INFO][NAS][Message] Encode ExtendedProtocolConfigurationOptions in EncodePDUSessionEstablishmentRequest
2021-04-23T02:48:13Z [INFO][N3IWF][NGAP] [N3IWF] Send Uplink NAS Transport
2021-04-23T02:48:13Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Handle Uplink Nas Transport
2021-04-23T02:48:13Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Uplink NAS Transport (RAN UE NGAP ID: 0)
2021-04-23T02:48:13Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Handle UL NAS Transport
2021-04-23T02:48:13Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Transport 5GSM Message to SMF
2021-04-23T02:48:13Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] Select SMF [snssai: {Sst:1 Sd:010203}, dnn: internet]
2021-04-23T02:48:13Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:13Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=AMF&target-nf-type=NSSF |
2021-04-23T02:48:13Z [INFO][NSSF][NsSelect] Handle NSSelectionGet
2021-04-23T02:48:13Z [INFO][NSSF][GIN] | 200 | 127.0.0.1 | GET | /nnssf-nsselection/v1/network-slice-information?nf-id=af48a080-b3ca-4eee-9742-0864128e52cc&nf-type=AMF&slice-info-request-for-pdu-session=%7B%22sNssai%22%3A%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D%2C%22roamingIndication%22%3A%22NON_ROAMING%22%7D |
2021-04-23T02:48:13Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:13Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?dnn=internet&requester-nf-type=AMF&service-names=nsmf-pdusession&snssais=%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D&target-nf-type=SMF&target-plmn-list=%7B%22mcc%22%3A%22208%22%2C%22mnc%22%3A%2293%22%7D |
2021-04-23T02:48:13Z [INFO][SMF][PduSess] Recieve Create SM Context Request
2021-04-23T02:48:13Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextCreate
2021-04-23T02:48:13Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:13Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-type=UDM |
2021-04-23T02:48:13Z [INFO][SMF][PduSess] Send NF Discovery Serving UDM Successfully
2021-04-23T02:48:13Z [INFO][SMF][CTX] Allocated UE IP address: 60.60.0.1
2021-04-23T02:48:13Z [INFO][SMF][CTX] Selected UPF: UPF
2021-04-23T02:48:13Z [INFO][SMF][PduSess] UE[imsi-2089300007487] PDUSessionID[10] IP[60.60.0.1]
2021-04-23T02:48:13Z [INFO][UDM][SDM] Handle GetSmData
2021-04-23T02:48:13Z [INFO][UDM][SDM] getSmDataProcedure: SUPI[imsi-2089300007487] PLMNID[20893] DNN[internet] SNssai[{"sst":1,"sd":"010203"}]
2021-04-23T02:48:13Z [INFO][UDR][DRepo] Handle QuerySmData
2021-04-23T02:48:13Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/subscription-data/imsi-2089300007487/20893/provisioned-data/sm-data?single-nssai=%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D |
2021-04-23T02:48:13Z [INFO][UDM][GIN] | 200 | 127.0.0.1 | GET | /nudm-sdm/v1/imsi-2089300007487/sm-data?dnn=internet&plmn-id=20893&single-nssai=%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D |
2021-04-23T02:48:13Z [INFO][SMF][GSM] In HandlePDUSessionEstablishmentRequest
2021-04-23T02:48:13Z [INFO][NAS][Convert] ProtocolOrContainerList: [0xc000563720 0xc000563780 0xc0005637c0]
2021-04-23T02:48:13Z [INFO][SMF][GSM] Protocol Configuration Options
2021-04-23T02:48:13Z [INFO][SMF][GSM] &{[0xc000563720 0xc000563780 0xc0005637c0]}
2021-04-23T02:48:13Z [INFO][SMF][GSM] Didn't Implement container type IPAddressAllocationViaNASSignallingUL
2021-04-23T02:48:13Z [INFO][SMF][PduSess] PCF Selection for SMContext SUPI[imsi-2089300007487] PDUSessionID[10]
2021-04-23T02:48:13Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:13Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-type=PCF |
2021-04-23T02:48:13Z [INFO][PCF][SMpolicy] Handle CreateSmPolicy
2021-04-23T02:48:13Z [INFO][UDR][DRepo] Handle PolicyDataUesUeIdSmDataGet
2021-04-23T02:48:13Z [INFO][UDR][GIN] | 200 | 127.0.0.1 | GET | /nudr-dr/v1/policy-data/ues/imsi-2089300007487/sm-data?dnn=internet&snssai=%7B%22sst%22%3A1%2C%22sd%22%3A%22010203%22%7D |
2021-04-23T02:48:13Z [INFO][PCF][GIN] | 201 | 127.0.0.1 | POST | /npcf-smpolicycontrol/v1/sm-policies |
2021-04-23T02:48:13Z [INFO][SMF][PduSess] SUPI[imsi-2089300007487] has no pre-config route
2021-04-23T02:48:13Z [INFO][NRF][DSCV] Handle NFDiscoveryRequest
2021-04-23T02:48:13Z [INFO][NRF][GIN] | 200 | 127.0.0.1 | GET | /nnrf-disc/v1/nf-instances?requester-nf-type=SMF&target-nf-instance-id=af48a080-b3ca-4eee-9742-0864128e52cc&target-nf-type=AMF |
2021-04-23T02:48:13Z [INFO][SMF][Consumer] SendNFDiscoveryServingAMF ok
2021-04-23T02:48:13Z [INFO][SMF][GIN] | 201 | 127.0.0.1 | POST | /nsmf-pdusession/v1/sm-contexts |
2021-04-23T02:48:13Z [INFO][AMF][GMM][AMF_UE_NGAP_ID:1][SUPI:imsi-2089300007487] create smContext[pduSessionID: 10] Success
2021-04-23T02:48:13Z [INFO][UPF][Util] [PFCP] Handle PFCP session establishment request
2021-04-23T02:48:13Z [INFO][UPF][Util] [PFCP] Session Establishment Response
2021-04-23T02:48:13Z [INFO][SMF][PFCP] In HandlePfcpSessionEstablishmentResponse
&{1000 Kbps 1000 Kbps}
2021-04-23T02:48:13Z [INFO][LIB][PFCP] Remove Request Transaction [2]
2021-04-23T02:48:13Z [INFO][AMF][Producer] Handle N1N2 Message Transfer Request
2021-04-23T02:48:13Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927][AMF_UE_NGAP_ID:1] Send PDU Session Resource Setup Request
2021-04-23T02:48:13Z [INFO][AMF][GIN] | 200 | 127.0.0.1 | POST | /namf-comm/v1/ue-contexts/imsi-2089300007487/n1-n2-messages |
2021-04-23T02:48:13Z [INFO][N3IWF][NGAP] [N3IWF] Handle PDU Session Resource Setup Request
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [SecurityAssociation] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Nonce] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [TrafficSelector] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [TrafficSelector] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Notification] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Notification] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
TestNon3GPPUE: non3gpp_test.go:1110: IKE message exchange type: 36
TestNon3GPPUE: non3gpp_test.go:1111: IKE message ID: 6
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [SecurityAssociation] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Nonce] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [TrafficSelector] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [TrafficSelector] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Notification] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Notification] unmarshal(): Start unmarshalling received bytes
TestNon3GPPUE: non3gpp_test.go:1133: Received Qos Flow settings
TestNon3GPPUE: non3gpp_test.go:1136: UP IP Address: [10 0 0 1]
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [SecurityAssociation] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [TrafficSelector] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [TrafficSelector] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Nonce] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
TestNon3GPPUE: non3gpp_test.go:1197: State function: encr: 12, auth: 0
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Decoding IKE message
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Encrypted] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Handle CREATE_CHILD_SA
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE message
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Encoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Encrypted] marshal(): Start marshalling
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] Decoding IKE payloads
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [SecurityAssociation] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [TrafficSelector] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [TrafficSelector] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][IKE] [Nonce] unmarshal(): Start unmarshalling received bytes
2021-04-23T02:48:13Z [INFO][N3IWF][NGAP] [N3IWF] Send PDU Session Resource Setup Response
2021-04-23T02:48:14Z [INFO][AMF][NGAP][10.200.200.1/192.168.160.136/192.168.56.101/60.60.0.1/10.200.200.2/192.168.127.1/10.0.0.1:48927] Handle PDU Session Resource Setup Response
2021-04-23T02:48:14Z [INFO][SMF][PduSess] Recieve Update SM Context Request
2021-04-23T02:48:14Z [INFO][SMF][PduSess] In HandlePDUSessionSMContextUpdate
2021-04-23T02:48:14Z [INFO][UPF][Util] [PFCP] Handle PFCP session modification request
2021-04-23T02:48:14Z [INFO][UPF][Util] [PFCP] Session Modification Response
2021-04-23T02:48:14Z [INFO][SMF][PFCP] In HandlePfcpSessionModificationResponse
2021-04-23T02:48:14Z [INFO][SMF][PduSess] [SMF] PFCP Modification Resonse Accept
2021-04-23T02:48:14Z [INFO][SMF][PFCP] PFCP Session Modification Success[1]
2021-04-23T02:48:14Z [INFO][SMF][GIN] | 200 | 127.0.0.1 | POST | /nsmf-pdusession/v1/sm-contexts/urn:uuid:40cf7b2b-228d-45b8-908e-d4f0cc8dcecf/modify |
2021-04-23T02:48:14Z [INFO][LIB][PFCP] Remove Request Transaction [3]
TestNon3GPPUE: non3gpp_test.go:1273: 24 bytes from 60.60.0.101: icmp_seq=0 time=448.606µs
TestNon3GPPUE: non3gpp_test.go:1273: 24 bytes from 60.60.0.101: icmp_seq=1 time=784.645µs
TestNon3GPPUE: non3gpp_test.go:1273: 24 bytes from 60.60.0.101: icmp_seq=2 time=392.892µs
TestNon3GPPUE: non3gpp_test.go:1273: 24 bytes from 60.60.0.101: icmp_seq=3 time=257.499µs
TestNon3GPPUE: non3gpp_test.go:1273: 24 bytes from 60.60.0.101: icmp_seq=4 time=532.836µs
TestNon3GPPUE: non3gpp_test.go:1277:
--- 60.60.0.101 ping statistics ---
TestNon3GPPUE: non3gpp_test.go:1278: 5 packets transmitted, 5 packets received, 0% packet loss
TestNon3GPPUE: non3gpp_test.go:1280: round-trip min/avg/max/stddev = 257.499µs/483.295µs/784.645µs/175.286µs
--- PASS: TestNon3GPPUE (9.84s)
PASS
ok test 9.903s
2021-04-23T02:48:25Z [INFO][UPF][Util] Removing DNN routes
ls: cannot access '*sslkey.log': No such file or directory
signal: terminated
2021-04-23T02:48:26Z [INFO][PCF][Init] Terminating PCF...
2021-04-23T02:48:26Z [INFO][PCF][Consumer] Send Deregister NFInstance
2021-04-23T02:48:26Z [INFO][UDM][Init] Terminating UDM...
2021-04-23T02:48:26Z [INFO][UDM][Consumer] Send Deregister NFInstance
2021-04-23T02:48:26Z [INFO][NSSF][Init] Terminating NSSF...
2021-04-23T02:48:26Z [INFO][NSSF][App] Send Deregister NFInstance
2021-04-23T02:48:26Z [INFO][AUSF][Init] Terminating AUSF...
2021-04-23T02:48:26Z [INFO][AUSF][App] Send Deregister NFInstance
2021-04-23T02:48:26Z [INFO][AMF][Init] Terminating AMF...
2021-04-23T02:48:26Z [INFO][AMF][Consumer] [AMF] Send Deregister NFInstance
2021-04-23T02:48:26Z [INFO][SMF][Init] Terminating SMF...
2021-04-23T02:48:26Z [INFO][SMF][Consumer] Send Deregister NFInstance
2021-04-23T02:48:26Z [INFO][UDR][Init] Terminating UDR...
2021-04-23T02:48:26Z [INFO][UDR][Consumer] Send Deregister NFInstance
2021-04-23T02:48:26Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:48:26Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:48:26Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:48:26Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:48:26Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:48:26Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:48:26Z [INFO][NRF][MGMT] Handle NFDeregisterRequest
2021-04-23T02:48:27Z [INFO][NRF][GIN] | 204 | 127.0.0.1 | DELETE | /nnrf-nfm/v1/nf-instances/c8659387-5265-4990-b05a-4dca845e34e0 |
2021-04-23T02:48:27Z [INFO][PCF][Init] Deregister from NRF successfully
2021-04-23T02:48:27Z [INFO][PCF][Init] PCF terminated
ok test 31.115s