By type
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.6.9)
2025-06-03 14:39:46,397 - [34mINFO [0;39m [background-preinit ] [36mo.h.validator.internal.util.Version[0;39m - HV000001: Hibernate Validator 6.2.3.Final
2025-06-03 14:39:46,477 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - Starting Application using Java 11.0.9.1 on onap-cps-temporal-6449d9ffd-hjb9z with PID 1 (/app/classes started by ? in /app)
2025-06-03 14:39:46,480 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - The following 1 profile is active: "helm"
2025-06-03 14:39:48,885 - [34mINFO [0;39m [main ] [36mo.s.d.r.c.RepositoryConfigurationDelegate[0;39m - Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2025-06-03 14:39:48,985 - [34mINFO [0;39m [main ] [36mo.s.d.r.c.RepositoryConfigurationDelegate[0;39m - Finished Spring Data repository scanning in 85 ms. Found 1 JPA repository interfaces.
2025-06-03 14:39:49,563 - [34mINFO [0;39m [main ] [36mo.s.cloud.context.scope.GenericScope[0;39m - BeanFactory id=4630c962-e918-31ed-9745-9034b895ad1f
2025-06-03 14:39:50,886 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat initialized with port(s): 8080 (http)
2025-06-03 14:39:50,900 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Initializing ProtocolHandler ["http-nio-8080"]
2025-06-03 14:39:50,901 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardService[0;39m - Starting service [Tomcat]
2025-06-03 14:39:50,901 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardEngine[0;39m - Starting Servlet engine: [Apache Tomcat/9.0.64]
2025-06-03 14:39:51,222 - [34mINFO [0;39m [main ] [36mo.a.c.c.C.[Tomcat].[localhost].[/][0;39m - Initializing Spring embedded WebApplicationContext
2025-06-03 14:39:51,222 - [34mINFO [0;39m [main ] [36mo.s.b.w.s.c.ServletWebServerApplicationContext[0;39m - Root WebApplicationContext: initialization completed in 4243 ms
2025-06-03 14:39:52,891 - [34mINFO [0;39m [main ] [36mcom.zaxxer.hikari.HikariDataSource[0;39m - HikariPool-1 - Starting...
2025-06-03 14:39:53,210 - [34mINFO [0;39m [main ] [36mcom.zaxxer.hikari.HikariDataSource[0;39m - HikariPool-1 - Start completed.
2025-06-03 14:39:53,438 - [34mINFO [0;39m [main ] [36mliquibase.database[0;39m - Set default schema name to cpstemporaldb
2025-06-03 14:39:53,695 - [34mINFO [0;39m [main ] [36mliquibase.lockservice[0;39m - Successfully acquired change log lock
2025-06-03 14:39:54,288 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Creating database history table with name: cpstemporaldb.databasechangelog
2025-06-03 14:39:54,328 - [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-03 14:39:54,651 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Table network_data created
2025-06-03 14:39:54,688 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Custom SQL executed
2025-06-03 14:39:54,696 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.1::cps ran successfully in 109ms
Running Changeset: db/changelog/schema/01-init-schema.xml::1.2::cps
2025-06-03 14:39:54,735 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Index network_data_dataspace_anchor_timestamp_idx created
2025-06-03 14:39:54,751 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Index network_data_dataspace_schema_set_timestamp_idx created
2025-06-03 14:39:54,757 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.2::cps ran successfully in 43ms
Running Changeset: db/changelog/schema/01-init-schema.xml::1.3::cps
2025-06-03 14:39:54,778 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Custom SQL executed
2025-06-03 14:39:54,785 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.3::cps ran successfully in 17ms
Running Changeset: db/changelog/data/02-init-data.xml::2::cps
2025-06-03 14:39:55,031 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Data loaded from 'db/changelog/data/02-init-data.dat' into table 'network_data'
2025-06-03 14:39:55,038 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/data/02-init-data.xml::2::cps ran successfully in 236ms
Running Changeset: db/changelog/schema/03-rename-network-data-timestamp-fields.xml::3::cps
2025-06-03 14:39:55,062 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Column network_data.timestamp renamed to observed_timestamp
2025-06-03 14:39:55,066 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Column network_data.version renamed to created_timestamp
2025-06-03 14:39:55,073 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/03-rename-network-data-timestamp-fields.xml::3::cps ran successfully in 19ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.1::cps
2025-06-03 14:39:55,098 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Columns operation(VARCHAR(20)) added to network_data
2025-06-03 14:39:55,104 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Data updated in network_data
2025-06-03 14:39:55,111 - [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 25ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.2::cps
2025-06-03 14:39:55,130 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Null constraint dropped from network_data.payload
2025-06-03 14:39:55,137 - [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 15ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.3::cps
2025-06-03 14:39:55,160 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - NOT NULL constraint has been added to network_data.operation
2025-06-03 14:39:55,167 - [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 14ms
2025-06-03 14:39:55,192 - [34mINFO [0;39m [main ] [36mliquibase.lockservice[0;39m - Successfully released change log lock
2025-06-03 14:39:55,424 - [34mINFO [0;39m [main ] [36mo.h.jpa.internal.util.LogHelper[0;39m - HHH000204: Processing PersistenceUnitInfo [name: default]
2025-06-03 14:39:55,508 - [34mINFO [0;39m [main ] [36morg.hibernate.Version[0;39m - HHH000412: Hibernate ORM core version 5.6.9.Final
2025-06-03 14:39:55,759 - [34mINFO [0;39m [main ] [36mo.h.annotations.common.Version[0;39m - HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2025-06-03 14:39:55,938 - [34mINFO [0;39m [main ] [36morg.hibernate.dialect.Dialect[0;39m - HHH000400: Using dialect: org.hibernate.dialect.PostgreSQLDialect
2025-06-03 14:39:56,195 - [31mWARN [0;39m [main ] [36mHibernate Types[0;39m - You should use Hypersistence Optimizer to speed up your Hibernate application!
2025-06-03 14:39:56,195 - [31mWARN [0;39m [main ] [36mHibernate Types[0;39m - For more details, go to https://vladmihalcea.com/hypersistence-optimizer/
2025-06-03 14:39:56,196 - [34mINFO [0;39m [main ] [36mHibernate Types[0;39m -
_ _ _ _
| | | | (_) | |
| |__| |_ _ _ __ ___ _ __ ___ _ ___| |_ ___ _ __ ___ ___
| __ | | | | '_ \ / _ \ '__/ __| / __| __/ _ \ '_ \ / __/ _ \
| | | | |_| | |_) | __/ | \__ \ \__ \ || __/ | | | (_| __/
|_| |_|\__, | .__/ \___|_| |___/_|___/\__\___|_| |_|\___\___|
__/ | |
|___/|_|
____ _ _ _
/ __ \ | | (_) (_)
| | | |_ __ | |_ _ _ __ ___ _ _______ _ __
| | | | '_ \| __| | '_ ` _ \| |_ / _ \ '__|
| |__| | |_) | |_| | | | | | | |/ / __/ |
\____/| .__/ \__|_|_| |_| |_|_/___\___|_|
| |
|_|
2025-06-03 14:39:56,196 - [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-03 14:39:57,042 - [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-03 14:39:57,058 - [34mINFO [0;39m [main ] [36mo.s.o.j.LocalContainerEntityManagerFactoryBean[0;39m - Initialized JPA EntityManagerFactory for persistence unit 'default'
2025-06-03 14:40:01,955 - [34mINFO [0;39m [main ] [36mo.s.s.web.DefaultSecurityFilterChain[0;39m - Will secure any request with [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@4cd3e2f3, org.springframework.security.web.context.SecurityContextPersistenceFilter@f64ce35, org.springframework.security.web.header.HeaderWriterFilter@1380a19b, org.springframework.security.web.authentication.logout.LogoutFilter@47fb038d, org.springframework.security.web.authentication.www.BasicAuthenticationFilter@632cfbc5, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@d0c3793, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@33116b8e, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@10e5471d, org.springframework.security.web.session.SessionManagementFilter@42675041, org.springframework.security.web.access.ExceptionTranslationFilter@7c4a44bb, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@76c1ede3]
2025-06-03 14:40:02,563 - [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-03 14:40:02,791 - [34mINFO [0;39m [main ] [36mo.a.k.c.s.a.AbstractLogin[0;39m - Successfully logged in.
2025-06-03 14:40:02,898 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka version: 3.0.1
2025-06-03 14:40:02,898 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka commitId: 8e30984f43e64d8b
2025-06-03 14:40:02,898 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka startTimeMs: 1748961602895
2025-06-03 14:40:02,946 - [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-03 14:40:02,961 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Starting ProtocolHandler ["http-nio-8080"]
2025-06-03 14:40:03,021 - [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-03 14:40:03,149 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat initialized with port(s): 8081 (http)
2025-06-03 14:40:03,151 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Initializing ProtocolHandler ["http-nio-8081"]
2025-06-03 14:40:03,151 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardService[0;39m - Starting service [Tomcat]
2025-06-03 14:40:03,151 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardEngine[0;39m - Starting Servlet engine: [Apache Tomcat/9.0.64]
2025-06-03 14:40:03,199 - [34mINFO [0;39m [main ] [36mo.a.c.c.C.[Tomcat-1].[localhost].[/][0;39m - Initializing Spring embedded WebApplicationContext
2025-06-03 14:40:03,199 - [34mINFO [0;39m [main ] [36mo.s.b.w.s.c.ServletWebServerApplicationContext[0;39m - Root WebApplicationContext: initialization completed in 166 ms
2025-06-03 14:40:03,228 - [34mINFO [0;39m [main ] [36mo.s.b.a.e.web.EndpointLinksResolver[0;39m - Exposing 4 endpoint(s) beneath base path '/manage'
2025-06-03 14:40:03,907 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Starting ProtocolHandler ["http-nio-8081"]
2025-06-03 14:40:03,917 - [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-03 14:40:03,949 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - Started Application in 18.584 seconds (JVM running for 19.492)
2025-06-03 14:40:04,077 - [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-03 14:40:04,079 - [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-03 14:40:04,080 - [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: kcopy2iXQ5yxJVYISIwb-A
2025-06-03 14:40:04,083 - [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-03 14:40:04,084 - [1;31mERROR[0;39m [intContainer#0-0-C-1] [36mo.s.k.l.KafkaMessageListenerContainer[0;39m - Fatal consumer exception; stopping container
2025-06-03 14:40:04,095 - [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-03 14:40:04,095 - [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-03 14:40:04,096 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.kafka.common.metrics.Metrics[0;39m - Metrics scheduler closed
2025-06-03 14:40:04,096 - [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-03 14:40:04,097 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.kafka.common.metrics.Metrics[0;39m - Metrics reporters closed
2025-06-03 14:40:04,106 - [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-03 14:40:04,109 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.s.k.l.KafkaMessageListenerContainer[0;39m - cps-temporal-group: Consumer stopped
2025-06-03 14:40:11,712 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.a.c.c.C.[Tomcat-1].[localhost].[/][0;39m - Initializing Spring DispatcherServlet 'dispatcherServlet'
2025-06-03 14:40:11,712 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.s.web.servlet.DispatcherServlet[0;39m - Initializing Servlet 'dispatcherServlet'
2025-06-03 14:40:11,713 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.s.web.servlet.DispatcherServlet[0;39m - Completed initialization in 1 ms