By type
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.6.9)
2025-06-24 10:49:11,347 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - Starting Application using Java 11.0.9.1 on onap-cps-temporal-7b4c5bfd6b-89lq6 with PID 1 (/app/classes started by ? in /app)
2025-06-24 10:49:11,354 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - The following 1 profile is active: "helm"
2025-06-24 10:49:18,596 - [34mINFO [0;39m [main ] [36mo.s.d.r.c.RepositoryConfigurationDelegate[0;39m - Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2025-06-24 10:49:18,796 - [34mINFO [0;39m [main ] [36mo.s.d.r.c.RepositoryConfigurationDelegate[0;39m - Finished Spring Data repository scanning in 179 ms. Found 1 JPA repository interfaces.
2025-06-24 10:49:20,115 - [34mINFO [0;39m [main ] [36mo.s.cloud.context.scope.GenericScope[0;39m - BeanFactory id=4630c962-e918-31ed-9745-9034b895ad1f
2025-06-24 10:49:22,199 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat initialized with port(s): 8080 (http)
2025-06-24 10:49:22,251 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Initializing ProtocolHandler ["http-nio-8080"]
2025-06-24 10:49:22,253 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardService[0;39m - Starting service [Tomcat]
2025-06-24 10:49:22,254 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardEngine[0;39m - Starting Servlet engine: [Apache Tomcat/9.0.64]
2025-06-24 10:49:22,798 - [34mINFO [0;39m [main ] [36mo.a.c.c.C.[Tomcat].[localhost].[/][0;39m - Initializing Spring embedded WebApplicationContext
2025-06-24 10:49:22,798 - [34mINFO [0;39m [main ] [36mo.s.b.w.s.c.ServletWebServerApplicationContext[0;39m - Root WebApplicationContext: initialization completed in 10261 ms
2025-06-24 10:49:27,460 - [34mINFO [0;39m [main ] [36mcom.zaxxer.hikari.HikariDataSource[0;39m - HikariPool-1 - Starting...
2025-06-24 10:49:28,127 - [34mINFO [0;39m [main ] [36mcom.zaxxer.hikari.HikariDataSource[0;39m - HikariPool-1 - Start completed.
2025-06-24 10:49:28,460 - [34mINFO [0;39m [main ] [36mliquibase.database[0;39m - Set default schema name to cpstemporaldb
2025-06-24 10:49:28,899 - [34mINFO [0;39m [main ] [36mliquibase.lockservice[0;39m - Successfully acquired change log lock
2025-06-24 10:49:30,292 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Creating database history table with name: cpstemporaldb.databasechangelog
2025-06-24 10:49:30,365 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Reading from cpstemporaldb.databasechangelog
Running Changeset: db/changelog/schema/01-init-schema.xml::1.1::cps
2025-06-24 10:49:31,017 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Table network_data created
2025-06-24 10:49:31,092 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Custom SQL executed
2025-06-24 10:49:31,102 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.1::cps ran successfully in 254ms
Running Changeset: db/changelog/schema/01-init-schema.xml::1.2::cps
2025-06-24 10:49:31,162 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Index network_data_dataspace_anchor_timestamp_idx created
2025-06-24 10:49:31,177 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Index network_data_dataspace_schema_set_timestamp_idx created
2025-06-24 10:49:31,184 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.2::cps ran successfully in 60ms
Running Changeset: db/changelog/schema/01-init-schema.xml::1.3::cps
2025-06-24 10:49:31,219 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Custom SQL executed
2025-06-24 10:49:31,229 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.3::cps ran successfully in 23ms
Running Changeset: db/changelog/data/02-init-data.xml::2::cps
2025-06-24 10:49:31,485 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Data loaded from 'db/changelog/data/02-init-data.dat' into table 'network_data'
2025-06-24 10:49:31,495 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/data/02-init-data.xml::2::cps ran successfully in 248ms
Running Changeset: db/changelog/schema/03-rename-network-data-timestamp-fields.xml::3::cps
2025-06-24 10:49:31,538 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Column network_data.timestamp renamed to observed_timestamp
2025-06-24 10:49:31,549 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Column network_data.version renamed to created_timestamp
2025-06-24 10:49:31,557 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/03-rename-network-data-timestamp-fields.xml::3::cps ran successfully in 41ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.1::cps
2025-06-24 10:49:31,603 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Columns operation(VARCHAR(20)) added to network_data
2025-06-24 10:49:31,619 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Data updated in network_data
2025-06-24 10:49:31,666 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/04-added-operation-field-in-network-data.xml::4.1::cps ran successfully in 76ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.2::cps
2025-06-24 10:49:31,771 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Null constraint dropped from network_data.payload
2025-06-24 10:49:31,781 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/04-added-operation-field-in-network-data.xml::4.2::cps ran successfully in 16ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.3::cps
2025-06-24 10:49:31,814 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - NOT NULL constraint has been added to network_data.operation
2025-06-24 10:49:31,821 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/04-added-operation-field-in-network-data.xml::4.3::cps ran successfully in 18ms
2025-06-24 10:49:31,869 - [34mINFO [0;39m [main ] [36mliquibase.lockservice[0;39m - Successfully released change log lock
2025-06-24 10:49:32,395 - [34mINFO [0;39m [main ] [36mo.h.jpa.internal.util.LogHelper[0;39m - HHH000204: Processing PersistenceUnitInfo [name: default]
2025-06-24 10:49:32,515 - [34mINFO [0;39m [main ] [36morg.hibernate.Version[0;39m - HHH000412: Hibernate ORM core version 5.6.9.Final
2025-06-24 10:49:33,015 - [34mINFO [0;39m [main ] [36mo.h.annotations.common.Version[0;39m - HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2025-06-24 10:49:33,349 - [34mINFO [0;39m [main ] [36morg.hibernate.dialect.Dialect[0;39m - HHH000400: Using dialect: org.hibernate.dialect.PostgreSQLDialect
2025-06-24 10:49:33,692 - [31mWARN [0;39m [main ] [36mHibernate Types[0;39m - You should use Hypersistence Optimizer to speed up your Hibernate application!
2025-06-24 10:49:33,692 - [31mWARN [0;39m [main ] [36mHibernate Types[0;39m - For more details, go to https://vladmihalcea.com/hypersistence-optimizer/
2025-06-24 10:49:33,693 - [34mINFO [0;39m [main ] [36mHibernate Types[0;39m -
_ _ _ _
| | | | (_) | |
| |__| |_ _ _ __ ___ _ __ ___ _ ___| |_ ___ _ __ ___ ___
| __ | | | | '_ \ / _ \ '__/ __| / __| __/ _ \ '_ \ / __/ _ \
| | | | |_| | |_) | __/ | \__ \ \__ \ || __/ | | | (_| __/
|_| |_|\__, | .__/ \___|_| |___/_|___/\__\___|_| |_|\___\___|
__/ | |
|___/|_|
____ _ _ _
/ __ \ | | (_) (_)
| | | |_ __ | |_ _ _ __ ___ _ _______ _ __
| | | | '_ \| __| | '_ ` _ \| |_ / _ \ '__|
| |__| | |_) | |_| | | | | | | |/ / __/ |
\____/| .__/ \__|_|_| |_| |_|_/___\___|_|
| |
|_|
2025-06-24 10:49:33,693 - [34mINFO [0;39m [main ] [36mHibernate Types[0;39m - Check out the README page for more info about the Hypersistence Optimizer banner https://github.com/vladmihalcea/hibernate-types#how-to-remove-the-hypersistence-optimizer-banner-from-the-log
2025-06-24 10:49:34,084 - [34mINFO [0;39m [main ] [36mo.h.validator.internal.util.Version[0;39m - HV000001: Hibernate Validator 6.2.3.Final
2025-06-24 10:49:35,245 - [34mINFO [0;39m [main ] [36mo.h.e.t.j.p.i.JtaPlatformInitiator[0;39m - HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2025-06-24 10:49:35,267 - [34mINFO [0;39m [main ] [36mo.s.o.j.LocalContainerEntityManagerFactoryBean[0;39m - Initialized JPA EntityManagerFactory for persistence unit 'default'
2025-06-24 10:49:49,333 - [34mINFO [0;39m [main ] [36mo.s.s.web.DefaultSecurityFilterChain[0;39m - Will secure any request with [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@4c96b175, org.springframework.security.web.context.SecurityContextPersistenceFilter@6d2d04ef, org.springframework.security.web.header.HeaderWriterFilter@2654f4aa, org.springframework.security.web.authentication.logout.LogoutFilter@11fe6743, org.springframework.security.web.authentication.www.BasicAuthenticationFilter@46bcdf2e, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@3a9c5f14, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@762078dc, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@4bf9f44b, org.springframework.security.web.session.SessionManagementFilter@2ac9b75c, org.springframework.security.web.access.ExceptionTranslationFilter@626ed5f0, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@76f58d0]
2025-06-24 10:49:50,291 - [34mINFO [0;39m [main ] [36mo.a.k.c.consumer.ConsumerConfig[0;39m - ConsumerConfig values:
allow.auto.create.topics = true
auto.commit.interval.ms = 5000
auto.offset.reset = latest
bootstrap.servers = [onap-strimzi-kafka-bootstrap:9092]
check.crcs = true
client.dns.lookup = use_all_dns_ips
client.id = consumer-cps-temporal-group-1
client.rack =
connections.max.idle.ms = 540000
default.api.timeout.ms = 60000
enable.auto.commit = false
exclude.internal.topics = true
fetch.max.bytes = 52428800
fetch.max.wait.ms = 500
fetch.min.bytes = 1
group.id = cps-temporal-group
group.instance.id = null
heartbeat.interval.ms = 3000
interceptor.classes = []
internal.leave.group.on.close = true
internal.throw.on.fetch.stable.offset.unsupported = false
isolation.level = read_uncommitted
key.deserializer = class org.springframework.kafka.support.serializer.ErrorHandlingDeserializer
max.partition.fetch.bytes = 1048576
max.poll.interval.ms = 300000
max.poll.records = 500
metadata.max.age.ms = 300000
metric.reporters = []
metrics.num.samples = 2
metrics.recording.level = INFO
metrics.sample.window.ms = 30000
partition.assignment.strategy = [class org.apache.kafka.clients.consumer.RangeAssignor, class org.apache.kafka.clients.consumer.CooperativeStickyAssignor]
receive.buffer.bytes = 65536
reconnect.backoff.max.ms = 1000
reconnect.backoff.ms = 50
request.timeout.ms = 30000
retry.backoff.ms = 100
sasl.client.callback.handler.class = null
sasl.jaas.config = [hidden]
sasl.kerberos.kinit.cmd = /usr/bin/kinit
sasl.kerberos.min.time.before.relogin = 60000
sasl.kerberos.service.name = null
sasl.kerberos.ticket.renew.jitter = 0.05
sasl.kerberos.ticket.renew.window.factor = 0.8
sasl.login.callback.handler.class = null
sasl.login.class = null
sasl.login.refresh.buffer.seconds = 300
sasl.login.refresh.min.period.seconds = 60
sasl.login.refresh.window.factor = 0.8
sasl.login.refresh.window.jitter = 0.05
sasl.mechanism = SCRAM-SHA-512
security.protocol = SASL_PLAINTEXT
security.providers = null
send.buffer.bytes = 131072
session.timeout.ms = 45000
socket.connection.setup.timeout.max.ms = 30000
socket.connection.setup.timeout.ms = 10000
ssl.cipher.suites = null
ssl.enabled.protocols = [TLSv1.2, TLSv1.3]
ssl.endpoint.identification.algorithm = https
ssl.engine.factory.class = null
ssl.key.password = null
ssl.keymanager.algorithm = SunX509
ssl.keystore.certificate.chain = null
ssl.keystore.key = null
ssl.keystore.location = null
ssl.keystore.password = null
ssl.keystore.type = JKS
ssl.protocol = TLSv1.3
ssl.provider = null
ssl.secure.random.implementation = null
ssl.trustmanager.algorithm = PKIX
ssl.truststore.certificates = null
ssl.truststore.location = null
ssl.truststore.password = null
ssl.truststore.type = JKS
value.deserializer = class org.springframework.kafka.support.serializer.ErrorHandlingDeserializer
2025-06-24 10:49:50,640 - [34mINFO [0;39m [main ] [36mo.a.k.c.s.a.AbstractLogin[0;39m - Successfully logged in.
2025-06-24 10:49:50,828 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka version: 3.0.1
2025-06-24 10:49:50,828 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka commitId: 8e30984f43e64d8b
2025-06-24 10:49:50,829 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka startTimeMs: 1750762190823
2025-06-24 10:49:50,899 - [34mINFO [0;39m [main ] [36mo.a.k.clients.consumer.KafkaConsumer[0;39m - [Consumer clientId=consumer-cps-temporal-group-1, groupId=cps-temporal-group] Subscribed to topic(s): cps.data-updated-events
2025-06-24 10:49:50,938 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Starting ProtocolHandler ["http-nio-8080"]
2025-06-24 10:49:50,986 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat started on port(s): 8080 (http) with context path ''
2025-06-24 10:49:51,207 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat initialized with port(s): 8081 (http)
2025-06-24 10:49:51,208 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Initializing ProtocolHandler ["http-nio-8081"]
2025-06-24 10:49:51,209 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardService[0;39m - Starting service [Tomcat]
2025-06-24 10:49:51,209 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardEngine[0;39m - Starting Servlet engine: [Apache Tomcat/9.0.64]
2025-06-24 10:49:51,330 - [34mINFO [0;39m [main ] [36mo.a.c.c.C.[Tomcat-1].[localhost].[/][0;39m - Initializing Spring embedded WebApplicationContext
2025-06-24 10:49:51,330 - [34mINFO [0;39m [main ] [36mo.s.b.w.s.c.ServletWebServerApplicationContext[0;39m - Root WebApplicationContext: initialization completed in 329 ms
2025-06-24 10:49:51,786 - [34mINFO [0;39m [main ] [36mo.s.b.a.e.web.EndpointLinksResolver[0;39m - Exposing 4 endpoint(s) beneath base path '/manage'
2025-06-24 10:49:52,613 - [31mWARN [0;39m [intContainer#0-0-C-1] [36mo.apache.kafka.clients.NetworkClient[0;39m - [Consumer clientId=consumer-cps-temporal-group-1, groupId=cps-temporal-group] Error while fetching metadata with correlation id 2 : {cps.data-updated-events=TOPIC_AUTHORIZATION_FAILED}
2025-06-24 10:49:52,617 - [1;31mERROR[0;39m [intContainer#0-0-C-1] [36morg.apache.kafka.clients.Metadata[0;39m - [Consumer clientId=consumer-cps-temporal-group-1, groupId=cps-temporal-group] Topic authorization failed for topics [cps.data-updated-events]
2025-06-24 10:49:52,618 - [34mINFO [0;39m [intContainer#0-0-C-1] [36morg.apache.kafka.clients.Metadata[0;39m - [Consumer clientId=consumer-cps-temporal-group-1, groupId=cps-temporal-group] Cluster ID: UIwnm2Y7QTuZu655YZTs4Q
2025-06-24 10:49:52,624 - [1;31mERROR[0;39m [intContainer#0-0-C-1] [36mo.s.k.l.KafkaMessageListenerContainer[0;39m - Authentication/Authorization Exception and no authExceptionRetryInterval set
org.apache.kafka.common.errors.TopicAuthorizationException: Not authorized to access topics: [cps.data-updated-events]
2025-06-24 10:49:52,627 - [1;31mERROR[0;39m [intContainer#0-0-C-1] [36mo.s.k.l.KafkaMessageListenerContainer[0;39m - Fatal consumer exception; stopping container
2025-06-24 10:49:52,642 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.k.c.c.i.ConsumerCoordinator[0;39m - [Consumer clientId=consumer-cps-temporal-group-1, groupId=cps-temporal-group] Resetting generation due to: consumer pro-actively leaving the group
2025-06-24 10:49:52,642 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.k.c.c.i.ConsumerCoordinator[0;39m - [Consumer clientId=consumer-cps-temporal-group-1, groupId=cps-temporal-group] Request joining group due to: consumer pro-actively leaving the group
2025-06-24 10:49:52,643 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.kafka.common.metrics.Metrics[0;39m - Metrics scheduler closed
2025-06-24 10:49:52,643 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.kafka.common.metrics.Metrics[0;39m - Closing reporter org.apache.kafka.common.metrics.JmxReporter
2025-06-24 10:49:52,646 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.kafka.common.metrics.Metrics[0;39m - Metrics reporters closed
2025-06-24 10:49:52,667 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - App info kafka.consumer for consumer-cps-temporal-group-1 unregistered
2025-06-24 10:49:52,672 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.s.k.l.KafkaMessageListenerContainer[0;39m - cps-temporal-group: Consumer stopped
2025-06-24 10:49:52,689 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Starting ProtocolHandler ["http-nio-8081"]
2025-06-24 10:49:52,698 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat started on port(s): 8081 (http) with context path ''
2025-06-24 10:49:52,760 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - Started Application in 44.354 seconds (JVM running for 47.065)
2025-06-24 10:49:59,945 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.a.c.c.C.[Tomcat-1].[localhost].[/][0;39m - Initializing Spring DispatcherServlet 'dispatcherServlet'
2025-06-24 10:49:59,946 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.s.web.servlet.DispatcherServlet[0;39m - Initializing Servlet 'dispatcherServlet'
2025-06-24 10:49:59,947 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.s.web.servlet.DispatcherServlet[0;39m - Completed initialization in 1 ms