By type
14:51:03,693 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
14:51:03,694 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [jar:file:/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.7.1.jar!/logback.xml]
14:51:03,696 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath.
14:51:03,696 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/opt/app/onap/config/logback.xml]
14:51:03,696 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [jar:file:/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.7.1.jar!/logback.xml]
14:51:03,725 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@400cff1a - URL [jar:file:/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.7.1.jar!/logback.xml] is not of type file
14:51:03,811 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
14:51:03,821 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
14:51:03,826 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
14:51:03,838 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
14:51:03,921 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to INFO
14:51:03,921 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.web] to INFO
14:51:03,921 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to ERROR
14:51:03,921 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.hazelcast] to ERROR
14:51:03,921 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.onap.ccsdk.cds] to INFO
14:51:03,921 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO
14:51:03,921 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
14:51:03,922 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
14:51:03,923 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@275710fc - Registering current configuration as safe fallback point
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.7.18)
2025-06-30 14:51:04,552|||background-preinit||||INFO||||||| HV000001: Hibernate Validator 6.2.5.Final
2025-06-30 14:51:04,607|||main||||INFO||||||| Starting BlueprintProcessorApplicationKt v1.7.1 using Java 17.0.6 on onap-cds-blueprints-processor-8cfd76c77-tqfxz with PID 1 (/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.7.1.jar started by onap in /app)
2025-06-30 14:51:04,609|||main||||INFO||||||| No active profile set, falling back to 1 default profile: "default"
2025-06-30 14:51:06,769|||main||||INFO||||||| Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2025-06-30 14:51:07,223|||main||||INFO||||||| Finished Spring Data repository scanning in 431 ms. Found 11 JPA repository interfaces.
2025-06-30 14:51:09,517|||main||||INFO||||||| HHH000204: Processing PersistenceUnitInfo [name: default]
2025-06-30 14:51:09,587|||main||||INFO||||||| HHH000412: Hibernate ORM core version 5.6.15.Final
2025-06-30 14:51:09,803|||main||||INFO||||||| HCANN000001: Hibernate Commons Annotations {5.1.2.Final}
2025-06-30 14:51:10,271|||main||||INFO||||||| HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
2025-06-30 14:51:10,897|||main||||WARN||||||| HHH000481: Encountered Java type [class com.fasterxml.jackson.databind.JsonNode] for which we could not locate a JavaTypeDescriptor and which does not appear to implement equals and/or hashCode. This can lead to significant performance problems when performing equality/dirty checking involving this Java type. Consider registering a custom JavaTypeDescriptor or at least implementing equals/hashCode.
2025-06-30 14:51:10,902|||main||||WARN||||||| HHH000481: Encountered Java type [class org.onap.ccsdk.cds.controllerblueprints.resource.dict.ResourceDefinition] for which we could not locate a JavaTypeDescriptor and which does not appear to implement equals and/or hashCode. This can lead to significant performance problems when performing equality/dirty checking involving this Java type. Consider registering a custom JavaTypeDescriptor or at least implementing equals/hashCode.
2025-06-30 14:51:12,579|||main||||WARN||||||| Error: 1091-42000: Can't DROP INDEX `UKhqyorwtr4murr712k6c4kpjql`; check that it exists
2025-06-30 14:51:12,677|||main||||WARN||||||| Error: 1091-42000: Can't DROP INDEX `UKg1fqnr40qdvhcwh0worep1n2i`; check that it exists
2025-06-30 14:51:12,762|||main||||WARN||||||| Error: 1091-42000: Can't DROP INDEX `UKd73ymcu616qfl51b38cyma2vp`; check that it exists
2025-06-30 14:51:14,106|||main||||INFO||||||| HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2025-06-30 14:51:14,120|||main||||INFO||||||| Initialized JPA EntityManagerFactory for persistence unit 'default'
2025-06-30 14:51:14,212|||main||||INFO||||||| Application ID: cds > Initializing error catalog message from properties...
2025-06-30 14:51:16,245|||main||||INFO||||||| Registering Dictionary Sources : [processor-db=source-db, input=source-input, default=source-default, sdnc=source-rest, aai-data=source-rest, capability=source-capability, rest=source-rest, vault-data=source-rest, script=source-capability, cps-data=source-rest]
2025-06-30 14:51:16,308|||main||||INFO||||||| Workflow Audit store is disabled
2025-06-30 14:51:16,424|||main||||INFO||||||| Kakfa audit service is enabled
2025-06-30 14:51:16,615|||main||||INFO||||||| Dependency Management module created...
2025-06-30 14:51:17,655|||main||||INFO||||||| Exposing 14 endpoint(s) beneath base path '/actuator'
2025-06-30 14:51:17,898|||main||||INFO||||||| Initialised Primary Transaction Manager for url jdbc:mariadb://cds-db:3306/sdnctl
2025-06-30 14:51:18,899|||main||||INFO||||||| Netty started on port 8080
2025-06-30 14:51:20,163|||main||||INFO||||||| Starting Blueprint Processor GRPC Starting..
2025-06-30 14:51:20,420|||main||||INFO||||||| Blueprint Processor GRPC server started and ready to serve on port(9111)...
2025-06-30 14:51:20,425|||main||||INFO||||||| Started BlueprintProcessorApplicationKt in 16.363 seconds (JVM running for 17.171)
2025-06-30 14:51:20,439|||DefaultDispatcher-worker-2||||INFO||||||| Setting up message consumer(self-service-api)message producer(self-service-api)...
2025-06-30 14:51:20,440|||DefaultDispatcher-worker-1||||INFO||||||| Cluster is disabled, to enable cluster set the environment CLUSTER_* properties.
2025-06-30 14:51:20,586|||DefaultDispatcher-worker-1||||INFO||||||| ConsumerConfig values:
allow.auto.create.topics = true
auto.commit.interval.ms = 5000
auto.include.jmx.reporter = true
auto.offset.reset = latest
bootstrap.servers = [onap-strimzi-kafka-bootstrap:9092]
check.crcs = true
client.dns.lookup = use_all_dns_ips
client.id = request-receiver-client-id-tqfxz
client.rack =
connections.max.idle.ms = 540000
default.api.timeout.ms = 60000
enable.auto.commit = true
enable.metrics.push = true
exclude.internal.topics = true
fetch.max.bytes = 52428800
fetch.max.wait.ms = 500
fetch.min.bytes = 1
group.id = cds-bp-processor
group.instance.id = null
group.protocol = classic
group.remote.assignor = 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.apache.kafka.common.serialization.StringDeserializer
max.partition.fetch.bytes = 1048576
max.poll.interval.ms = 300000
max.poll.records = 500
metadata.max.age.ms = 300000
metadata.recovery.strategy = none
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.max.ms = 1000
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.connect.timeout.ms = null
sasl.login.read.timeout.ms = 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.login.retry.backoff.max.ms = 10000
sasl.login.retry.backoff.ms = 100
sasl.mechanism = SCRAM-SHA-512
sasl.oauthbearer.clock.skew.seconds = 30
sasl.oauthbearer.expected.audience = null
sasl.oauthbearer.expected.issuer = null
sasl.oauthbearer.jwks.endpoint.refresh.ms = 3600000
sasl.oauthbearer.jwks.endpoint.retry.backoff.max.ms = 10000
sasl.oauthbearer.jwks.endpoint.retry.backoff.ms = 100
sasl.oauthbearer.jwks.endpoint.url = null
sasl.oauthbearer.scope.claim.name = scope
sasl.oauthbearer.sub.claim.name = sub
sasl.oauthbearer.token.endpoint.url = null
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.apache.kafka.common.serialization.ByteArrayDeserializer
2025-06-30 14:51:20,665|||DefaultDispatcher-worker-1||||INFO||||||| initializing Kafka metrics collector
2025-06-30 14:51:20,801|||DefaultDispatcher-worker-1||||INFO||||||| Successfully logged in.
2025-06-30 14:51:20,899|||DefaultDispatcher-worker-1||||INFO||||||| Kafka version: 3.1.2
2025-06-30 14:51:20,899|||DefaultDispatcher-worker-1||||INFO||||||| Kafka commitId: f8c67dc3ae0a3265
2025-06-30 14:51:20,900|||DefaultDispatcher-worker-1||||INFO||||||| Kafka startTimeMs: 1751295080896
2025-06-30 14:51:20,904|||DefaultDispatcher-worker-1||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Subscribed to topic(s): cds.blueprint-processor.self-service-api.request
2025-06-30 14:51:20,905|||DefaultDispatcher-worker-1||||INFO||||||| Successfully consumed topic([cds.blueprint-processor.self-service-api.request])
2025-06-30 14:51:21,476|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Cluster ID: gZOIa6GHTzygC-dDxK7w0g
2025-06-30 14:51:21,478|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Discovered group coordinator onap-strimzi-onap-strimzi-broker-2.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 2147483645 rack: null)
2025-06-30 14:51:21,494|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] (Re-)joining group
2025-06-30 14:51:21,552|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Request joining group due to: need to re-join with the given member-id: request-receiver-client-id-tqfxz-88ad79d2-1067-4179-b10e-f1fd6621a84f
2025-06-30 14:51:21,552|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] (Re-)joining group
2025-06-30 14:51:24,556|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Successfully joined group with generation Generation{generationId=1, memberId='request-receiver-client-id-tqfxz-88ad79d2-1067-4179-b10e-f1fd6621a84f', protocol='range'}
2025-06-30 14:51:24,567|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Finished assignment for group at generation 1: {request-receiver-client-id-tqfxz-88ad79d2-1067-4179-b10e-f1fd6621a84f=Assignment(partitions=[cds.blueprint-processor.self-service-api.request-0, cds.blueprint-processor.self-service-api.request-1, cds.blueprint-processor.self-service-api.request-2, cds.blueprint-processor.self-service-api.request-3, cds.blueprint-processor.self-service-api.request-4, cds.blueprint-processor.self-service-api.request-5])}
2025-06-30 14:51:24,661|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Successfully synced group in generation Generation{generationId=1, memberId='request-receiver-client-id-tqfxz-88ad79d2-1067-4179-b10e-f1fd6621a84f', protocol='range'}
2025-06-30 14:51:24,662|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Notifying assignor about the new Assignment(partitions=[cds.blueprint-processor.self-service-api.request-0, cds.blueprint-processor.self-service-api.request-1, cds.blueprint-processor.self-service-api.request-2, cds.blueprint-processor.self-service-api.request-3, cds.blueprint-processor.self-service-api.request-4, cds.blueprint-processor.self-service-api.request-5])
2025-06-30 14:51:24,666|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Adding newly assigned partitions: cds.blueprint-processor.self-service-api.request-0, cds.blueprint-processor.self-service-api.request-1, cds.blueprint-processor.self-service-api.request-2, cds.blueprint-processor.self-service-api.request-3, cds.blueprint-processor.self-service-api.request-4, cds.blueprint-processor.self-service-api.request-5
2025-06-30 14:51:24,674|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-0
2025-06-30 14:51:24,675|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-1
2025-06-30 14:51:24,675|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-2
2025-06-30 14:51:24,675|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-3
2025-06-30 14:51:24,675|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-4
2025-06-30 14:51:24,675|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-5
2025-06-30 14:51:24,741|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Resetting offset for partition cds.blueprint-processor.self-service-api.request-1 to position FetchPosition{offset=0, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[onap-strimzi-onap-strimzi-broker-2.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 2 rack: null)], epoch=0}}.
2025-06-30 14:51:24,742|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Resetting offset for partition cds.blueprint-processor.self-service-api.request-5 to position FetchPosition{offset=0, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[onap-strimzi-onap-strimzi-broker-2.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 2 rack: null)], epoch=0}}.
2025-06-30 14:51:24,755|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Resetting offset for partition cds.blueprint-processor.self-service-api.request-2 to position FetchPosition{offset=0, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[onap-strimzi-onap-strimzi-broker-0.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 0 rack: null)], epoch=0}}.
2025-06-30 14:51:24,755|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Resetting offset for partition cds.blueprint-processor.self-service-api.request-3 to position FetchPosition{offset=0, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[onap-strimzi-onap-strimzi-broker-0.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 0 rack: null)], epoch=0}}.
2025-06-30 14:51:24,756|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Resetting offset for partition cds.blueprint-processor.self-service-api.request-0 to position FetchPosition{offset=0, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[onap-strimzi-onap-strimzi-broker-1.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 1 rack: null)], epoch=0}}.
2025-06-30 14:51:24,756|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Resetting offset for partition cds.blueprint-processor.self-service-api.request-4 to position FetchPosition{offset=0, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[onap-strimzi-onap-strimzi-broker-1.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 1 rack: null)], epoch=0}}.
2025-06-30 15:00:21,482|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Node -1 disconnected.
2025-06-30 15:10:21,524|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-tqfxz, groupId=cds-bp-processor] Node -1 disconnected.