Skip to content

Instantly share code, notes, and snippets.

@tnmtechnologies

tnmtechnologies/5GC-namf-mt-quarkus-native-cold-start.log

Created November 10, 2020 13:26
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save tnmtechnologies/5d7537551fee0df28df8ec6b949676df to your computer and use it in GitHub Desktop.
Save tnmtechnologies/5d7537551fee0df28df8ec6b949676df to your computer and use it in GitHub Desktop.
Download ZIP
[quarkus/native] cold start of namf-mt service with registration to NRF
Raw
5GC-namf-mt-quarkus-native-cold-start.log
./5gc-amf-mt-server-runner.exe -Dfile.encoding=UTF-8 -Dquarkus.log.category.'"com.bcom"'.level=INFO -Dquarkus.log.category.'"com.bcom.newedge._3gpp._5gc.nf.commons.jaxrs"'.level=DEBUG -Dquarkus.log.category.'"org.apache.http"'.level=DEBUG -Duser.country=US -Duser.language=en -Ddebug=true -Dnf.apiRoot=http://127.0.0.1:8381 -Dnf.nrf.apiRoot=http://127.0.0.1:8080 -Dnf.nfInstanceId=83810000-0000-0000-0000-000000000000 -Dnf.nrf.nfm.heartBeatTimer=10 -Dnf.service.defaultDuration=3600 -Dnf.service.minDuration=1800 -Dresteasy.allowGzip=true -Dquarkus.http.port=8381 -Dquarkus.http.ssl-port=8744 -Dthorntail.jaeger.remote-reporter-http-endpoint=http://localhost:14268/api/traces -Duser.country=US -Duser.language=en -Dquarkus.http.ssl.certificate.key-store-file=classes/namf-mt.jks
__ ____ __ _____ ___ __ ____ ______
--/ __ \/ / / / _ | / _ \/ //_/ / / / __/
-/ /_/ / /_/ / __ |/ , _/ ,< / /_/ /\ \
--\___\_\____/_/ |_/_/|_/_/|_|\____/___/
3GPP 5GC Access and Mobility Management Function - MT service
Namf_MT service (15.2.0)
Technical specification document http://www.3gpp.org/ftp//Specs/archive/29_series/29.518/29518-f20.zip
Exposed endpoints
-----------------
- Namf_MT API
{apiRoot}/namf-mt/v1
- OpenAPI
{apiRoot}/openapi
- Health check
{apiRoot}/health
{apiRoot}/health/live
{apiRoot}/health/ready
- Metrics
{apiRoot}/metrics
{apiRoot}/metrics/application
{apiRoot}/metrics/vendor
- Swagger UI
{apiRoot}/swagger-ui
Build informations
------------------
version 5.5.4-SNAPSHOT
date 2020-11-09T18:24:03Z
commit 0d5cbe9
A b<>com software (https://b-com.com)
Powered by Quarkus 1.8.3.Final
2020-11-10 14:19:38,401 INFO [com.bco.new._3g._5g.nf.com.mic.con.con.BaseConverter] (main) AbstractConverter()
2020-11-10 14:19:38,401 INFO [com.bco.new._3g._5g.nf.com.mic.con.con.PlmnIdConverter] (main) PlmnIdConverter()
2020-11-10 14:19:38,401 INFO [com.bco.new._3g._5g.nf.com.mic.con.con.BaseConverter] (main) AbstractConverter()
2020-11-10 14:19:38,401 INFO [com.bco.new._3g._5g.nf.com.mic.con.con.SnssaiConverter] (main) SnssaiConverter()
2020-11-10 14:19:38,786 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFManagementBean] (main) NFManagementBean()
2020-11-10 14:19:38,786 INFO [com.bco.new._3g._5g.amf.mt.ser.mod.NFApiDataProducer] (main) create()
2020-11-10 14:19:38,786 INFO [com.bco.new._3g._5g.amf.mt.ser.mod.NFApiDataProducer] (main) create()
2020-11-10 14:19:38,786 INFO [com.bco.new._3g._5g.nf.com.mod.NFServiceProducer] (main) createNFService(nfApiRoot=http://127.0.0.1:8381, defaultDuration=3,600, nfApiData=NFApiData(nfType=AMF, serviceName=namf-mt, apiFullVersion=1.0.0, apiVersionInUri=v1))
2020-11-10 14:19:38,786 INFO [com.bco.new._3g._5g.nf.com.mod.NFProfileProducer] (main) getNfApiRootUrl(nfApiRoot=http://127.0.0.1:8381)
2020-11-10 14:19:38,786 INFO [com.bco.new._3g._5g.nf.com.mod.NFProfileProducer] (main) createNFProfile(nfApiRoot=http://127.0.0.1:8381, heartBeatTimer=10, nfApiData, nfService)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.mod.NFProfileProducer] (main) getNfApiRootUrl(nfApiRoot=http://127.0.0.1:8381)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFManagementBean] (main) setNFProfile(nfProfile)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFHeartBeatRunnable] (main) NFHeartBeatRunnable()
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFManagementBean] (main) setNfHeartBeatRunnable(nfHeartBeatInstance)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nfp.api.NFProfileServiceDecorator] (main) NFProfileServiceDecorator(decorated)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFManagementConsumerNFProfileServiceDecorator] (main) NFManagementConsumerNFProfileServiceDecorator()
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFManagementBean] (main) setNfProfileService(nfProfileService)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFManagementBean] (main) setNrfApiRoot(nrfApiRoot=http://127.0.0.1:8080)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFManagementBean] (main) onStartup(startupEvent)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFProfileServiceImpl] (main) NFProfileServiceImpl()
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nfp.api.NFProfileServiceDecorator] (main) NFProfileServiceDecorator(decorated)
2020-11-10 14:19:38,787 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFManagementConsumerNFProfileServiceDecorator] (main) NFManagementConsumerNFProfileServiceDecorator(decorated, restClientBuilder)
2020-11-10 14:19:38,787 WARN [org.jbo.res.res.i18n] (main) RESTEASY002160: Provider instance io.smallrye.opentracing.SmallRyeClientTracingFeature is already registered. 2nd registration is being ignored.
2020-11-10 14:19:38,788 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFManagementConsumerNFProfileServiceDecorator] (main) createOrUpdate(nfProfile[nfInstanceId]=83810000-0000-0000-0000-000000000000)
2020-11-10 14:19:38,788 INFO [com.bco.new._3g._5g.nf.com.nfp.api.NFProfileServiceDecorator] (main) update(nfProfile) nfInstanceId=83810000-0000-0000-0000-000000000000
2020-11-10 14:19:38,788 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFProfileServiceImpl] (main) NFProfileServiceImpl()
2020-11-10 14:19:38,788 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFProfileServiceImpl] (main) createOrUpdate(nfProfile[nfInstanceId]=83810000-0000-0000-0000-000000000000)
2020-11-10 14:19:38,788 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFProfileServiceImpl] (main) get()
2020-11-10 14:19:38,801 INFO [com.bco.new._3g._5g.nf.com.jax.fil.log.LoggingWriterInterceptor] (main) LoggingWriterInterceptor()
2020-11-10 14:19:38,803 DEBUG [org.apa.htt.cli.pro.RequestAuthCache] (main) Auth cache not set in the context
2020-11-10 14:19:38,803 DEBUG [org.apa.htt.imp.con.PoolingHttpClientConnectionManager] (main) Connection request: [route: {}->http://127.0.0.1:8080][total available: 0; route allocated: 0 of 50; total allocated: 0 of 50]
2020-11-10 14:19:38,803 DEBUG [org.apa.htt.imp.con.PoolingHttpClientConnectionManager] (main) Connection leased: [id: 0][route: {}->http://127.0.0.1:8080][total available: 0; route allocated: 1 of 50; total allocated: 1 of 50]
2020-11-10 14:19:38,803 DEBUG [org.apa.htt.imp.exe.MainClientExec] (main) Opening connection {}->http://127.0.0.1:8080
2020-11-10 14:19:38,803 DEBUG [org.apa.htt.imp.con.DefaultHttpClientConnectionOperator] (main) Connecting to /127.0.0.1:8080
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.imp.con.DefaultHttpClientConnectionOperator] (main) Connection established 127.0.0.1:64383<->127.0.0.1:8080
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.imp.exe.MainClientExec] (main) Executing request PUT /nnrf-nfm/v1/nf-instances/83810000-0000-0000-0000-000000000000 HTTP/1.1
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.imp.exe.MainClientExec] (main) Target auth state: UNCHALLENGED
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.imp.exe.MainClientExec] (main) Proxy auth state: UNCHALLENGED
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 >> PUT /nnrf-nfm/v1/nf-instances/83810000-0000-0000-0000-000000000000 HTTP/1.1
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 >> Accept: application/json, application/problem+json
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 >> Content-Type: application/json
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 >> uber-trace-id: 5f5b9c7a43650626%3A5f5b9c7a43650626%3A0%3A1
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 >> Content-Length: 744
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 >> Host: 127.0.0.1:8080
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 >> Connection: Keep-Alive
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 >> User-Agent: Apache-HttpClient/UNAVAILABLE (Java/11.0.8)
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "PUT /nnrf-nfm/v1/nf-instances/83810000-0000-0000-0000-000000000000 HTTP/1.1[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "Accept: application/json, application/problem+json[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "Content-Type: application/json[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "uber-trace-id: 5f5b9c7a43650626%3A5f5b9c7a43650626%3A0%3A1[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "Content-Length: 744[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "Host: 127.0.0.1:8080[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "Connection: Keep-Alive[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "User-Agent: Apache-HttpClient/UNAVAILABLE (Java/11.0.8)[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "[\r][\n]"
2020-11-10 14:19:38,804 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 >> "{"capacity":16383,"customInfo":"b<>com","heartBeatTimer":10,"ipv4Addresses":["127.0.0.1"],"load":0,"locality":"France, Brittany, Cesson-S[0xc3][0xa9]vign[0xc3][0xa9], 1219 avenue Champs Blancs","nfInstanceId":"83810000-0000-0000-0000-000000000000","nfServicePersistence":false,"nfServices":[{"ipEndPoints":[{"ipv4Address":"127.0.0.1","port":8381,"transport":"TCP"}],"nfServiceStatus":"REGISTERED","recoveryTime":"2020-11-10T14:19:38.7860736+01:00","scheme":"http","serviceInstanceId":"54bf9457-b2ce-4c4a-9721-3393af39f706","serviceName":"namf-mt","versions":[{"apiFullVersion":"1.0.0","apiVersionInUri":"v1","expiry":"2020-11-10T15:19:38.7860736+01:00"}]}],"nfStatus":"REGISTERED","nfType":"AMF","priority":16383,"recoveryTime":"2020-11-10T14:19:38.7870732+01:00"}"
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 << "HTTP/1.1 201 Created[\r][\n]"
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 << "Content-Type: application/json[\r][\n]"
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 << "Location: http://127.0.0.1:8080/nnrf-nfm/v1/nf-instances/83810000-0000-0000-0000-000000000000[\r][\n]"
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 << "Content-Length: 722[\r][\n]"
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 << "[\r][\n]"
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.wire] (main) http-outgoing-0 << "{"capacity":16383,"heartBeatTimer":10,"ipv4Addresses":["127.0.0.1"],"load":0,"locality":"France, Brittany, Cesson-S[0xc3][0xa9]vign[0xc3][0xa9], 1219 avenue Champs Blancs","nfInstanceId":"83810000-0000-0000-0000-000000000000","nfServicePersistence":false,"nfServices":[{"ipEndPoints":[{"ipv4Address":"127.0.0.1","port":8381,"transport":"TCP"}],"nfServiceStatus":"REGISTERED","recoveryTime":"2020-11-10T14:19:38.7860736+01:00","scheme":"http","serviceInstanceId":"54bf9457-b2ce-4c4a-9721-3393af39f706","serviceName":"namf-mt","versions":[{"apiFullVersion":"1.0.0","apiVersionInUri":"v1","expiry":"2020-11-10T15:19:38.7860736+01:00"}]}],"nfStatus":"REGISTERED","nfType":"AMF","priority":16383,"recoveryTime":"2020-11-10T14:19:38.7870732+01:00"}"
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 << HTTP/1.1 201 Created
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 << Content-Type: application/json
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 << Location: http://127.0.0.1:8080/nnrf-nfm/v1/nf-instances/83810000-0000-0000-0000-000000000000
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.headers] (main) http-outgoing-0 << Content-Length: 722
2020-11-10 14:19:38,810 DEBUG [org.apa.htt.imp.exe.MainClientExec] (main) Connection can be kept alive indefinitely
2020-11-10 14:19:38,811 DEBUG [org.apa.htt.imp.con.PoolingHttpClientConnectionManager] (main) Connection [id: 0][route: {}->http://127.0.0.1:8080] can be kept alive indefinitely
2020-11-10 14:19:38,811 DEBUG [org.apa.htt.imp.con.DefaultManagedHttpClientConnection] (main) http-outgoing-0: set socket timeout to 0
2020-11-10 14:19:38,811 DEBUG [org.apa.htt.imp.con.PoolingHttpClientConnectionManager] (main) Connection released: [id: 0][route: {}->http://127.0.0.1:8080][total available: 1; route allocated: 1 of 50; total allocated: 1 of 50]
2020-11-10 14:19:38,811 INFO [com.bco.new._3g._5g.nf.com.nfp.api.NFProfileServiceDecorator] (main) update(nfProfile) nfInstanceId=83810000-0000-0000-0000-000000000000
2020-11-10 14:19:38,811 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFProfileServiceImpl] (main) createOrUpdate(nfProfile[nfInstanceId]=83810000-0000-0000-0000-000000000000)
2020-11-10 14:19:38,811 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFProfileServiceImpl] (main) get()
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFHeartBeatRunnable] (pool-4-thread-1) NFHeartBeatRunnable(defaultDuration=3600, minDuration=1800, nfProfileService)
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFHeartBeatRunnable] (pool-4-thread-1) setHeartBeatTimer(heartBeatTimer=10)
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFHeartBeatRunnable] (pool-4-thread-1) setNFProfileService(nfProfileService)
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFRuntimeAttributesServiceProducer] (pool-4-thread-1) createNFRuntimeAttributesService()
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.SimulatedNFRuntimeAttributesService] (pool-4-thread-1) SimulatedNFRuntimeAttributesService()
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFHeartBeatRunnable] (pool-4-thread-1) setRuntime(nfRuntimeAttributesService)
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFHeartBeatRunnable] (pool-4-thread-1) run()
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nfp.api.NFProfileServiceDecorator] (pool-4-thread-1) get()
2020-11-10 14:19:38,814 INFO [com.bco.new._3g._5g.nf.com.nfp.imp.NFProfileServiceImpl] (pool-4-thread-1) get()
2020-11-10 14:19:38,818 INFO [com.bco.new._3g._5g.nf.com.nrf.nfm.NFHeartBeatRunnable] (pool-4-thread-1) round 0 - thread is sleeping for 9996ms (heartBeatTimer=10000ms)...
2020-11-10 14:19:38,822 INFO [io.quarkus] (main) 5gc-amf-mt-server 5.5.4-SNAPSHOT native (powered by Quarkus 1.8.3.Final) started in 0.422s. Listening on: http://0.0.0.0:8381 and https://0.0.0.0:8744
2020-11-10 14:19:38,823 INFO [io.quarkus] (main) Profile prod activated.
2020-11-10 14:19:38,823 INFO [io.quarkus] (main) Installed features: [cdi, hibernate-validator, jaeger, rest-client, resteasy, resteasy-jsonb, servlet, smallrye-health, smallrye-metrics, smallrye-opentracing, swagger-ui]
@tnmtechnologies
Copy link
Author

PC hardware is Intel(R) Xeon(R) E-2286M CPU@2.40GHz 32GB RAM with SSD.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment