By type
03:09:59,393 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 03:09:59,394 |-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.5.3.jar!/logback.xml] 03:09:59,395 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath. 03:09:59,395 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/opt/app/onap/config/logback.xml] 03:09:59,395 |-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.5.3.jar!/logback.xml] 03:09:59,424 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@c540f5a - URL [jar:file:/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.5.3.jar!/logback.xml] is not of type file 03:09:59,542 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set 03:09:59,552 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 03:09:59,558 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT] 03:09:59,569 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 03:09:59,668 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to INFO 03:09:59,668 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.web] to INFO 03:09:59,668 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to ERROR 03:09:59,668 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.hazelcast] to ERROR 03:09:59,668 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.onap.ccsdk.cds] to INFO 03:09:59,668 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO 03:09:59,668 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT] 03:09:59,684 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 03:09:59,685 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@770c2e6b - Registering current configuration as safe fallback point . ____ _ __ _ _ /\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \ ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \ \\/ ___)| |_)| | | | | || (_| | ) ) ) ) ' |____| .__|_| |_|_| |_\__, | / / / / =========|_|==============|___/=/_/_/_/ :: Spring Boot :: (v2.6.11) 2024-03-11 03:10:00,326|||background-preinit||||INFO||||||| HV000001: Hibernate Validator 6.2.4.Final 2024-03-11 03:10:00,382|||main||||INFO||||||| Starting BlueprintProcessorApplicationKt v1.5.3 using Java 17.0.6 on onap-cds-blueprints-processor-764966f55f-h7n42 with PID 1 (/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.5.3.jar started by onap in /app) 2024-03-11 03:10:00,384|||main||||INFO||||||| No active profile set, falling back to 1 default profile: "default" 2024-03-11 03:10:02,513|||main||||INFO||||||| Bootstrapping Spring Data JPA repositories in DEFAULT mode. 2024-03-11 03:10:03,015|||main||||INFO||||||| Finished Spring Data repository scanning in 487 ms. Found 11 JPA repository interfaces. 2024-03-11 03:10:04,758|||main||||INFO||||||| HHH000204: Processing PersistenceUnitInfo [name: default] 2024-03-11 03:10:04,813|||main||||INFO||||||| HHH000412: Hibernate ORM core version 5.6.10.Final 2024-03-11 03:10:05,009|||main||||INFO||||||| HCANN000001: Hibernate Commons Annotations {5.1.2.Final} 2024-03-11 03:10:05,406|||main||||INFO||||||| HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect 2024-03-11 03:10:05,678|||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. 2024-03-11 03:10:05,679|||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. 2024-03-11 03:10:08,304|||main||||INFO||||||| HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2024-03-11 03:10:08,316|||main||||INFO||||||| Initialized JPA EntityManagerFactory for persistence unit 'default' 2024-03-11 03:10:08,390|||main||||INFO||||||| Application ID: cds > Initializing error catalog message from properties... 2024-03-11 03:10:08,472|||main||||INFO||||||| Dependency Management module created... 2024-03-11 03:10:09,700|||main||||INFO||||||| Workflow Audit store is disabled 2024-03-11 03:10:10,067|||main||||INFO||||||| Kakfa audit service is enabled 2024-03-11 03:10:10,641|||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] 2024-03-11 03:10:11,268|||main||||INFO||||||| Exposing 14 endpoint(s) beneath base path '/actuator' 2024-03-11 03:10:11,496|||main||||INFO||||||| Initialised Primary Transaction Manager for url jdbc:mysql://cds-db:3306/sdnctl 2024-03-11 03:10:12,349|||main||||INFO||||||| Netty started on port 8080 2024-03-11 03:10:13,404|||main||||INFO||||||| Starting Blueprint Processor GRPC Starting.. 2024-03-11 03:10:13,690|||main||||INFO||||||| Blueprint Processor GRPC server started and ready to serve on port(9111)... 2024-03-11 03:10:13,696|||main||||INFO||||||| Started BlueprintProcessorApplicationKt in 13.874 seconds (JVM running for 14.672) 2024-03-11 03:10:13,717|||DefaultDispatcher-worker-2||||INFO||||||| Setting up message consumer(self-service-api)message producer(self-service-api)... 2024-03-11 03:10:13,720|||DefaultDispatcher-worker-1||||INFO||||||| Cluster is disabled, to enable cluster set the environment CLUSTER_* properties. 2024-03-11 03:10:13,812|||DefaultDispatcher-worker-1||||INFO||||||| 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 = request-receiver-client-id-h7n42 client.rack = connections.max.idle.ms = 540000 default.api.timeout.ms = 60000 enable.auto.commit = 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 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 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.apache.kafka.common.serialization.ByteArrayDeserializer 2024-03-11 03:10:13,930|||DefaultDispatcher-worker-1||||INFO||||||| Successfully logged in. 2024-03-11 03:10:14,000|||DefaultDispatcher-worker-1||||INFO||||||| Kafka version: 3.0.1 2024-03-11 03:10:14,000|||DefaultDispatcher-worker-1||||INFO||||||| Kafka commitId: 8e30984f43e64d8b 2024-03-11 03:10:14,000|||DefaultDispatcher-worker-1||||INFO||||||| Kafka startTimeMs: 1710126613999 2024-03-11 03:10:14,003|||DefaultDispatcher-worker-1||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Subscribed to topic(s): cds.blueprint-processor.self-service-api.request 2024-03-11 03:10:14,004|||DefaultDispatcher-worker-1||||INFO||||||| Successfully consumed topic([cds.blueprint-processor.self-service-api.request]) 2024-03-11 03:10:14,591|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Resetting the last seen epoch of partition cds.blueprint-processor.self-service-api.request-0 to 0 since the associated topicId changed from null to VDLPCTZ6Rz2HVAtAMU6yyg 2024-03-11 03:10:14,592|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Resetting the last seen epoch of partition cds.blueprint-processor.self-service-api.request-5 to 0 since the associated topicId changed from null to VDLPCTZ6Rz2HVAtAMU6yyg 2024-03-11 03:10:14,592|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Resetting the last seen epoch of partition cds.blueprint-processor.self-service-api.request-4 to 0 since the associated topicId changed from null to VDLPCTZ6Rz2HVAtAMU6yyg 2024-03-11 03:10:14,592|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Resetting the last seen epoch of partition cds.blueprint-processor.self-service-api.request-1 to 0 since the associated topicId changed from null to VDLPCTZ6Rz2HVAtAMU6yyg 2024-03-11 03:10:14,592|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Resetting the last seen epoch of partition cds.blueprint-processor.self-service-api.request-2 to 0 since the associated topicId changed from null to VDLPCTZ6Rz2HVAtAMU6yyg 2024-03-11 03:10:14,592|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Resetting the last seen epoch of partition cds.blueprint-processor.self-service-api.request-3 to 0 since the associated topicId changed from null to VDLPCTZ6Rz2HVAtAMU6yyg 2024-03-11 03:10:14,595|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Cluster ID: oLPzOJhqQsuBmRCLjQqfCQ 2024-03-11 03:10:14,597|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Discovered group coordinator onap-strimzi-kafka-2.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 2147483645 rack: null) 2024-03-11 03:10:14,611|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] (Re-)joining group 2024-03-11 03:10:14,686|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Request joining group due to: need to re-join with the given member-id 2024-03-11 03:10:14,687|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] (Re-)joining group 2024-03-11 03:10:17,717|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Successfully joined group with generation Generation{generationId=1, memberId='request-receiver-client-id-h7n42-662358f6-c257-4f70-9903-deb456b92487', protocol='range'} 2024-03-11 03:10:17,721|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Finished assignment for group at generation 1: {request-receiver-client-id-h7n42-662358f6-c257-4f70-9903-deb456b92487=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])} 2024-03-11 03:10:17,783|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Successfully synced group in generation Generation{generationId=1, memberId='request-receiver-client-id-h7n42-662358f6-c257-4f70-9903-deb456b92487', protocol='range'} 2024-03-11 03:10:17,784|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, 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]) 2024-03-11 03:10:17,788|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, 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 2024-03-11 03:10:17,815|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-0 2024-03-11 03:10:17,815|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-1 2024-03-11 03:10:17,816|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-2 2024-03-11 03:10:17,816|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-3 2024-03-11 03:10:17,816|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-4 2024-03-11 03:10:17,816|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, groupId=cds-bp-processor] Found no committed offset for partition cds.blueprint-processor.self-service-api.request-5 2024-03-11 03:10:17,915|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, 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-kafka-2.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 2 rack: null)], epoch=0}}. 2024-03-11 03:10:17,917|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, 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-kafka-2.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 2 rack: null)], epoch=0}}. 2024-03-11 03:10:17,925|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, 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-kafka-0.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 0 rack: null)], epoch=0}}. 2024-03-11 03:10:17,925|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, 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-kafka-0.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 0 rack: null)], epoch=0}}. 2024-03-11 03:10:17,938|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, 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-kafka-1.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 1 rack: null)], epoch=0}}. 2024-03-11 03:10:17,938|||KafkaConsumer-request-receiver-client-id||||INFO||||||| [Consumer clientId=request-receiver-client-id-h7n42, 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-kafka-1.onap-strimzi-kafka-brokers.onap.svc:9092 (id: 1 rack: null)], epoch=0}}.