By type
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.6.9)
2025-03-21 07:35:18,739 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - Starting Application using Java 11.0.9.1 on onap-cps-temporal-6b49f487f6-xp7sn with PID 1 (/app/classes started by ? in /app)
2025-03-21 07:35:18,745 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - The following 1 profile is active: "helm"
2025-03-21 07:35:23,911 - [34mINFO [0;39m [main ] [36mo.s.d.r.c.RepositoryConfigurationDelegate[0;39m - Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2025-03-21 07:35:24,079 - [34mINFO [0;39m [main ] [36mo.s.d.r.c.RepositoryConfigurationDelegate[0;39m - Finished Spring Data repository scanning in 148 ms. Found 1 JPA repository interfaces.
2025-03-21 07:35:25,265 - [34mINFO [0;39m [main ] [36mo.s.cloud.context.scope.GenericScope[0;39m - BeanFactory id=4630c962-e918-31ed-9745-9034b895ad1f
2025-03-21 07:35:27,618 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat initialized with port(s): 8080 (http)
2025-03-21 07:35:27,645 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Initializing ProtocolHandler ["http-nio-8080"]
2025-03-21 07:35:27,647 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardService[0;39m - Starting service [Tomcat]
2025-03-21 07:35:27,647 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardEngine[0;39m - Starting Servlet engine: [Apache Tomcat/9.0.64]
2025-03-21 07:35:28,069 - [34mINFO [0;39m [main ] [36mo.a.c.c.C.[Tomcat].[localhost].[/][0;39m - Initializing Spring embedded WebApplicationContext
2025-03-21 07:35:28,070 - [34mINFO [0;39m [main ] [36mo.s.b.w.s.c.ServletWebServerApplicationContext[0;39m - Root WebApplicationContext: initialization completed in 7899 ms
2025-03-21 07:35:30,642 - [34mINFO [0;39m [main ] [36mcom.zaxxer.hikari.HikariDataSource[0;39m - HikariPool-1 - Starting...
2025-03-21 07:35:31,137 - [34mINFO [0;39m [main ] [36mcom.zaxxer.hikari.HikariDataSource[0;39m - HikariPool-1 - Start completed.
2025-03-21 07:35:31,446 - [34mINFO [0;39m [main ] [36mliquibase.database[0;39m - Set default schema name to cpstemporaldb
2025-03-21 07:35:31,835 - [34mINFO [0;39m [main ] [36mliquibase.lockservice[0;39m - Successfully acquired change log lock
2025-03-21 07:35:32,912 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Creating database history table with name: cpstemporaldb.databasechangelog
2025-03-21 07:35:32,993 - [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-03-21 07:35:33,683 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Table network_data created
2025-03-21 07:35:33,762 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Custom SQL executed
2025-03-21 07:35:33,805 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.1::cps ran successfully in 326ms
Running Changeset: db/changelog/schema/01-init-schema.xml::1.2::cps
2025-03-21 07:35:33,857 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Index network_data_dataspace_anchor_timestamp_idx created
2025-03-21 07:35:33,880 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Index network_data_dataspace_schema_set_timestamp_idx created
2025-03-21 07:35:33,884 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.2::cps ran successfully in 49ms
Running Changeset: db/changelog/schema/01-init-schema.xml::1.3::cps
2025-03-21 07:35:33,907 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Custom SQL executed
2025-03-21 07:35:33,912 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/01-init-schema.xml::1.3::cps ran successfully in 13ms
Running Changeset: db/changelog/data/02-init-data.xml::2::cps
2025-03-21 07:35:34,130 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Data loaded from 'db/changelog/data/02-init-data.dat' into table 'network_data'
2025-03-21 07:35:34,139 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/data/02-init-data.xml::2::cps ran successfully in 209ms
Running Changeset: db/changelog/schema/03-rename-network-data-timestamp-fields.xml::3::cps
2025-03-21 07:35:34,174 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Column network_data.timestamp renamed to observed_timestamp
2025-03-21 07:35:34,198 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Column network_data.version renamed to created_timestamp
2025-03-21 07:35:34,209 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - ChangeSet db/changelog/schema/03-rename-network-data-timestamp-fields.xml::3::cps ran successfully in 48ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.1::cps
2025-03-21 07:35:34,245 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Columns operation(VARCHAR(20)) added to network_data
2025-03-21 07:35:34,251 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Data updated in network_data
2025-03-21 07:35:34,258 - [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 24ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.2::cps
2025-03-21 07:35:34,318 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - Null constraint dropped from network_data.payload
2025-03-21 07:35:34,326 - [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 19ms
Running Changeset: db/changelog/schema/04-added-operation-field-in-network-data.xml::4.3::cps
2025-03-21 07:35:34,357 - [34mINFO [0;39m [main ] [36mliquibase.changelog[0;39m - NOT NULL constraint has been added to network_data.operation
2025-03-21 07:35:34,362 - [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 23ms
2025-03-21 07:35:34,390 - [34mINFO [0;39m [main ] [36mliquibase.lockservice[0;39m - Successfully released change log lock
2025-03-21 07:35:34,967 - [34mINFO [0;39m [main ] [36mo.h.jpa.internal.util.LogHelper[0;39m - HHH000204: Processing PersistenceUnitInfo [name: default]
2025-03-21 07:35:35,087 - [34mINFO [0;39m [main ] [36morg.hibernate.Version[0;39m - HHH000412: Hibernate ORM core version 5.6.9.Final
2025-03-21 07:35:35,710 - [34mINFO [0;39m [main ] [36mo.h.annotations.common.Version[0;39m - HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2025-03-21 07:35:35,991 - [34mINFO [0;39m [main ] [36morg.hibernate.dialect.Dialect[0;39m - HHH000400: Using dialect: org.hibernate.dialect.PostgreSQLDialect
2025-03-21 07:35:36,340 - [31mWARN [0;39m [main ] [36mHibernate Types[0;39m - You should use Hypersistence Optimizer to speed up your Hibernate application!
2025-03-21 07:35:36,341 - [31mWARN [0;39m [main ] [36mHibernate Types[0;39m - For more details, go to https://vladmihalcea.com/hypersistence-optimizer/
2025-03-21 07:35:36,342 - [34mINFO [0;39m [main ] [36mHibernate Types[0;39m -
_ _ _ _
| | | | (_) | |
| |__| |_ _ _ __ ___ _ __ ___ _ ___| |_ ___ _ __ ___ ___
| __ | | | | '_ \ / _ \ '__/ __| / __| __/ _ \ '_ \ / __/ _ \
| | | | |_| | |_) | __/ | \__ \ \__ \ || __/ | | | (_| __/
|_| |_|\__, | .__/ \___|_| |___/_|___/\__\___|_| |_|\___\___|
__/ | |
|___/|_|
____ _ _ _
/ __ \ | | (_) (_)
| | | |_ __ | |_ _ _ __ ___ _ _______ _ __
| | | | '_ \| __| | '_ ` _ \| |_ / _ \ '__|
| |__| | |_) | |_| | | | | | | |/ / __/ |
\____/| .__/ \__|_|_| |_| |_|_/___\___|_|
| |
|_|
2025-03-21 07:35:36,342 - [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-03-21 07:35:36,664 - [34mINFO [0;39m [main ] [36mo.h.validator.internal.util.Version[0;39m - HV000001: Hibernate Validator 6.2.3.Final
2025-03-21 07:35:37,733 - [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-03-21 07:35:37,760 - [34mINFO [0;39m [main ] [36mo.s.o.j.LocalContainerEntityManagerFactoryBean[0;39m - Initialized JPA EntityManagerFactory for persistence unit 'default'
2025-03-21 07:35:45,690 - [34mINFO [0;39m [main ] [36mo.s.s.web.DefaultSecurityFilterChain[0;39m - Will secure any request with [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@76e11cc, org.springframework.security.web.context.SecurityContextPersistenceFilter@6820ab53, org.springframework.security.web.header.HeaderWriterFilter@50f88cea, org.springframework.security.web.authentication.logout.LogoutFilter@2583418c, org.springframework.security.web.authentication.www.BasicAuthenticationFilter@2fe8ec31, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@7ce3c0cc, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@a6321c6, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@4c96b175, org.springframework.security.web.session.SessionManagementFilter@70e966a5, org.springframework.security.web.access.ExceptionTranslationFilter@54d1c9ba, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@2cc224b7]
2025-03-21 07:35:46,860 - [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-03-21 07:35:47,351 - [34mINFO [0;39m [main ] [36mo.a.k.c.s.a.AbstractLogin[0;39m - Successfully logged in.
2025-03-21 07:35:47,557 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka version: 3.0.1
2025-03-21 07:35:47,558 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka commitId: 8e30984f43e64d8b
2025-03-21 07:35:47,558 - [34mINFO [0;39m [main ] [36mo.a.kafka.common.utils.AppInfoParser[0;39m - Kafka startTimeMs: 1742542547552
2025-03-21 07:35:47,633 - [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-03-21 07:35:47,664 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Starting ProtocolHandler ["http-nio-8080"]
2025-03-21 07:35:47,819 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat started on port(s): 8080 (http) with context path ''
2025-03-21 07:35:48,153 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat initialized with port(s): 8081 (http)
2025-03-21 07:35:48,156 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Initializing ProtocolHandler ["http-nio-8081"]
2025-03-21 07:35:48,156 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardService[0;39m - Starting service [Tomcat]
2025-03-21 07:35:48,157 - [34mINFO [0;39m [main ] [36mo.a.catalina.core.StandardEngine[0;39m - Starting Servlet engine: [Apache Tomcat/9.0.64]
2025-03-21 07:35:48,226 - [34mINFO [0;39m [main ] [36mo.a.c.c.C.[Tomcat-1].[localhost].[/][0;39m - Initializing Spring embedded WebApplicationContext
2025-03-21 07:35:48,227 - [34mINFO [0;39m [main ] [36mo.s.b.w.s.c.ServletWebServerApplicationContext[0;39m - Root WebApplicationContext: initialization completed in 392 ms
2025-03-21 07:35:48,927 - [34mINFO [0;39m [main ] [36mo.s.b.a.e.web.EndpointLinksResolver[0;39m - Exposing 4 endpoint(s) beneath base path '/manage'
2025-03-21 07:35:53,411 - [34mINFO [0;39m [main ] [36mo.a.coyote.http11.Http11NioProtocol[0;39m - Starting ProtocolHandler ["http-nio-8081"]
2025-03-21 07:35:53,531 - [34mINFO [0;39m [main ] [36mo.s.b.w.e.tomcat.TomcatWebServer[0;39m - Tomcat started on port(s): 8081 (http) with context path ''
2025-03-21 07:35:53,626 - [34mINFO [0;39m [main ] [36morg.onap.cps.temporal.Application[0;39m - Started Application in 37.611 seconds (JVM running for 43.972)
2025-03-21 07:35:53,666 - [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-03-21 07:35:53,675 - [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-03-21 07:35:53,676 - [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: k7y2_q7PTi25ax_EgYzotg
2025-03-21 07:35:53,681 - [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-03-21 07:35:53,684 - [1;31mERROR[0;39m [intContainer#0-0-C-1] [36mo.s.k.l.KafkaMessageListenerContainer[0;39m - Fatal consumer exception; stopping container
2025-03-21 07:35:53,703 - [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-03-21 07:35:53,703 - [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-03-21 07:35:53,706 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.kafka.common.metrics.Metrics[0;39m - Metrics scheduler closed
2025-03-21 07:35:53,707 - [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-03-21 07:35:53,707 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.a.kafka.common.metrics.Metrics[0;39m - Metrics reporters closed
2025-03-21 07:35:53,736 - [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-03-21 07:35:53,741 - [34mINFO [0;39m [intContainer#0-0-C-1] [36mo.s.k.l.KafkaMessageListenerContainer[0;39m - cps-temporal-group: Consumer stopped
2025-03-21 07:36:03,833 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.a.c.c.C.[Tomcat-1].[localhost].[/][0;39m - Initializing Spring DispatcherServlet 'dispatcherServlet'
2025-03-21 07:36:03,834 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.s.web.servlet.DispatcherServlet[0;39m - Initializing Servlet 'dispatcherServlet'
2025-03-21 07:36:03,836 - [34mINFO [0;39m [http-nio-8081-exec-1] [36mo.s.web.servlet.DispatcherServlet[0;39m - Completed initialization in 2 ms