By type
05:06:22,725 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 05:06:22,727 |-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.4.2.jar!/logback.xml] 05:06:22,728 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs multiple times on the classpath. 05:06:22,728 |-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.4.2.jar!/logback.xml] 05:06:22,728 |-WARN in ch.qos.logback.classic.LoggerContext[default] - Resource [logback.xml] occurs at [file:/opt/app/onap/config/logback.xml] 05:06:22,744 |-INFO in ch.qos.logback.core.joran.spi.ConfigurationWatchList@399c4be1 - URL [jar:file:/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.4.2.jar!/logback.xml] is not of type file 05:06:22,890 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set 05:06:22,900 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 05:06:22,903 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT] 05:06:22,911 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property 05:06:22,993 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to INFO 05:06:22,993 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework.web] to INFO 05:06:22,993 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to ERROR 05:06:22,993 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.hazelcast] to ERROR 05:06:22,993 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.onap.ccsdk.cds] to INFO 05:06:22,993 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to INFO 05:06:22,993 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT] 05:06:22,994 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration. 05:06:22,996 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@291caca8 - Registering current configuration as safe fallback point . ____ _ __ _ _ /\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \ ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \ \\/ ___)| |_)| | | | | || (_| | ) ) ) ) ' |____| .__|_| |_|_| |_\__, | / / / / =========|_|==============|___/=/_/_/_/ :: Spring Boot :: (v2.6.11) 2023-01-03 05:06:24,151|||main||||INFO||||||| Starting BlueprintProcessorApplicationKt v1.4.2 using Java 11.0.9.1 on onap-cds-blueprints-processor-6ccf86f86d-wbb48 with PID 1 (/opt/app/onap/lib/org.onap.ccsdk.cds.blueprintsprocessor-blueprintsprocessor-application-1.4.2.jar started by onap in /app) 2023-01-03 05:06:24,154|||main||||INFO||||||| No active profile set, falling back to 1 default profile: "default" 2023-01-03 05:06:26,955|||main||||INFO||||||| Bootstrapping Spring Data JPA repositories in DEFAULT mode. 2023-01-03 05:06:27,526|||main||||INFO||||||| Finished Spring Data repository scanning in 558 ms. Found 11 JPA repository interfaces. 2023-01-03 05:06:29,686|||main||||INFO||||||| HHH000204: Processing PersistenceUnitInfo [name: default] 2023-01-03 05:06:29,766|||main||||INFO||||||| HHH000412: Hibernate ORM core version 5.6.10.Final 2023-01-03 05:06:30,001|||main||||INFO||||||| HCANN000001: Hibernate Commons Annotations {5.1.2.Final} 2023-01-03 05:06:30,512|||main||||INFO||||||| HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect 2023-01-03 05:06:30,855|||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. 2023-01-03 05:06:30,857|||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. 2023-01-03 05:06:31,246|||main||||INFO||||||| HV000001: Hibernate Validator 6.2.4.Final 2023-01-03 05:06:32,649|||main||||INFO||||||| HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2023-01-03 05:06:32,663|||main||||INFO||||||| Initialized JPA EntityManagerFactory for persistence unit 'default' 2023-01-03 05:06:32,742|||main||||INFO||||||| Application ID: cds > Initializing error catalog message from properties... 2023-01-03 05:06:34,183|||main||||INFO||||||| Audit service is disabled 2023-01-03 05:06:34,320|||main||||INFO||||||| Workflow Audit store is disabled 2023-01-03 05:06:36,144|||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] 2023-01-03 05:06:36,187|||main||||INFO||||||| Dependency Management module created... 2023-01-03 05:06:37,231|||main||||INFO||||||| Exposing 14 endpoint(s) beneath base path '/actuator' 2023-01-03 05:06:37,695|||main||||INFO||||||| Initialised Primary Transaction Manager for url jdbc:mysql://cds-db:3306/sdnctl 2023-01-03 05:06:38,930|||main||||INFO||||||| Netty started on port 8080 2023-01-03 05:06:40,432|||main||||INFO||||||| Starting Blueprint Processor GRPC Starting.. 2023-01-03 05:06:40,986|||main||||INFO||||||| Blueprint Processor GRPC server started and ready to serve on port(9111)... 2023-01-03 05:06:40,992|||main||||INFO||||||| Started BlueprintProcessorApplicationKt in 17.785 seconds (JVM running for 18.825) 2023-01-03 05:06:41,012|||DefaultDispatcher-worker-1||||INFO||||||| Cluster is disabled, to enable cluster set the environment CLUSTER_* properties. 2023-01-03 06:30:06,002|afbc54f4-523d-4118-8e99-ea72b79584d3||reactor-http-epoll-4||/api/v1/blueprint-model/bootstrap||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| Bootstrap request with type load(true), resource dictionary load(true) and cba load(false) 2023-01-03 06:30:06,005|afbc54f4-523d-4118-8e99-ea72b79584d3||reactor-http-epoll-4||/api/v1/blueprint-model/bootstrap||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| model types load from paths(/opt/app/onap/model-catalog/definition-type/starter-type) 2023-01-03 06:30:06,007|afbc54f4-523d-4118-8e99-ea72b79584d3||reactor-http-epoll-4||/api/v1/blueprint-model/bootstrap||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| ****** loadModelType(/opt/app/onap/model-catalog/definition-type/starter-type) ******** 2023-01-03 06:30:06,407|afbc54f4-523d-4118-8e99-ea72b79584d3||DefaultDispatcher-worker-13||/api/v1/blueprint-model/bootstrap||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| resource dictionary load from paths(/opt/app/onap/model-catalog/resource-dictionary/starter-dictionary) 2023-01-03 06:30:06,411|afbc54f4-523d-4118-8e99-ea72b79584d3||DefaultDispatcher-worker-13||/api/v1/blueprint-model/bootstrap||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| ******* loadResourceDictionary(/opt/app/onap/model-catalog/resource-dictionary/starter-dictionary) ******** 2023-01-03 06:30:07,708|e1b04cd9-ce75-4020-930e-bf3b15e698a2||Thread-27||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| Enhancing blueprint(/opt/app/onap/blueprints/working/1e4d5205-a3f4-49b5-8886-53c48844c264) 2023-01-03 06:30:07,729|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| deleting definition types under : /opt/app/onap/blueprints/working/1e4d5205-a3f4-49b5-8886-53c48844c264/Definitions 2023-01-03 06:30:07,856|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| reinitialized all type definitions 2023-01-03 06:30:07,865|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| ***** Enhancing NodeTemplate(helloworld) 2023-01-03 06:30:07,904|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| enhancing ArtifactDefinition(helloworld-template) 2023-01-03 06:30:07,909|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| enhancing ArtifactDefinition(helloworld-mapping) 2023-01-03 06:30:07,919|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| enhancing resource mapping file(Templates/hello-world-mapping.json) already enhanced(false) 2023-01-03 06:30:07,961|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| ##### Enhancing Workflow(helloworld) 2023-01-03 06:30:07,963|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| workflow(helloworld) dependent component NodeTemplates([helloworld]) 2023-01-03 06:30:07,963|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| identified workflow(helloworld) targets(helloworld) 2023-01-03 06:30:07,964|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| resource assignment artifacts(helloworld-mapping) for NodeType(helloworld) 2023-01-03 06:30:07,964|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| workflow(helloworld) resource assignment files([Templates/hello-world-mapping.json] 2023-01-03 06:30:07,965|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| enriching artifacts file(/opt/app/onap/blueprints/working/1e4d5205-a3f4-49b5-8886-53c48844c264/Templates/hello-world-mapping.json 2023-01-03 06:30:07,977|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| enhancing workflow resource mapping file(Templates/hello-world-mapping.json) already enhanced(true) 2023-01-03 06:30:07,977|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| dataType not present for the recipe(dt-helloworld-properties) 2023-01-03 06:30:07,978|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| ##### Enhancing blueprint Resource Definitions 2023-01-03 06:30:07,978|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| resources assignment files ([Templates/hello-world-mapping.json]) 2023-01-03 06:30:07,980|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| processing file (Templates/hello-world-mapping.json) 2023-01-03 06:30:07,999|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| distinct resource keys ([]) 2023-01-03 06:30:08,000|e1b04cd9-ce75-4020-930e-bf3b15e698a2||DefaultDispatcher-worker-13||/api/v1/blueprint-model/enrich||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| Enriching Resource Definition sources Node Template: [] 2023-01-03 06:30:08,333|3e080593-f043-4fc5-9fcc-2a2950fc79dd||Thread-27||/api/v1/blueprint-model/publish||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| Save processing(6305f3e6-2665-4d0b-9127-e28095328785) CBA(/opt/app/onap/blueprints/archive/6305f3e6-2665-4d0b-9127-e28095328785/cba.zip) 2023-01-03 06:30:08,350|3e080593-f043-4fc5-9fcc-2a2950fc79dd||DefaultDispatcher-worker-13||/api/v1/blueprint-model/publish||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| Reading blueprint type(DEFAULT) path(/opt/app/onap/blueprints/working/6305f3e6-2665-4d0b-9127-e28095328785) and entry definition file (Definitions/blueprint.json) 2023-01-03 06:30:08,411|3e080593-f043-4fc5-9fcc-2a2950fc79dd||DefaultDispatcher-worker-13||/api/v1/blueprint-model/publish||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| Validating Workflow(helloworld) 2023-01-03 06:30:08,426|3e080593-f043-4fc5-9fcc-2a2950fc79dd||DefaultDispatcher-worker-13||/api/v1/blueprint-model/publish||INFO|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| Reading blueprint type(DEFAULT) path(/opt/app/onap/blueprints/working/6305f3e6-2665-4d0b-9127-e28095328785) and entry definition file (Definitions/blueprint.json) 2023-01-03 06:30:08,602|3e080593-f043-4fc5-9fcc-2a2950fc79dd||DefaultDispatcher-worker-13||/api/v1/blueprint-model/publish||WARN|||onap-cds-blueprints-processor-6ccf86f86d-wbb48|||| No compiled cache(/opt/app/onap/blueprints/working/6305f3e6-2665-4d0b-9127-e28095328785) present to clean. 2023-01-03 06:30:08,842|654321||reactor-http-epoll-4||||INFO||||||| processing request id 654321 2023-01-03 06:30:08,842|654321||reactor-http-epoll-4||||INFO||||||| storeExecutionInput called not to store the Workflow action input details 2023-01-03 06:30:08,846|654321||reactor-http-epoll-4||||INFO||||||| StoreAuditService ID -1 2023-01-03 06:30:08,853|654321||DefaultDispatcher-worker-13||||INFO||||||| getting cba file name(test101), version(1.0.1) from db 2023-01-03 06:30:08,981|654321||DefaultDispatcher-worker-13||||INFO||||||| cba file name(test101), version(1.0.1) saved in (/opt/app/onap/blueprints/deploy/test101/1.0.1) 2023-01-03 06:30:08,984|654321||DefaultDispatcher-worker-13||||INFO||||||| blueprint base path /opt/app/onap/blueprints/deploy/test101/1.0.1 2023-01-03 06:30:08,986|654321||DefaultDispatcher-worker-13||||INFO||||||| Reading blueprint type(DEFAULT) path(/opt/app/onap/blueprints/deploy/test101/1.0.1) and entry definition file (Definitions/blueprint.json) 2023-01-03 06:30:09,003|654321||DefaultDispatcher-worker-13||||INFO||||||| Deriving input data for workflow: (helloworld) 2023-01-03 06:30:09,004|654321||DefaultDispatcher-worker-13||||INFO||||||| Executing workflow(helloworld) NodeTemplate(helloworld), derived from(tosca.nodes.Component) 2023-01-03 06:30:09,022|654321||DefaultDispatcher-worker-13||||INFO||||||| executing node template(helloworld) component(component-resource-resolution) interface(ResourceResolutionComponent) operation(process) on host (SELF) with timeout(180) sec. 2023-01-03 06:30:09,031|654321||DefaultDispatcher-worker-13||||INFO||||||| preparing request id(654321) for workflow(helloworld) step(helloworld) 2023-01-03 06:30:09,032|654321||DefaultDispatcher-worker-13||||INFO||||||| resolveNodeTemplateInterfaceOperationInputs for node template (helloworld), interface name(ResourceResolutionComponent), operationName(process) 2023-01-03 06:30:09,033|654321||DefaultDispatcher-worker-13||||INFO||||||| input definition for node template (helloworld), values ({artifact-prefix-names=["helloworld"]}) 2023-01-03 06:30:09,056|654321||DefaultDispatcher-worker-13||||INFO||||||| Resolving resource with resource assignment artifact(helloworld-mapping) 2023-01-03 06:30:09,088|654321||DefaultDispatcher-worker-13||||INFO||||||| Batched Sequence : ([]) 2023-01-03 06:30:09,091|654321||DefaultDispatcher-worker-13||||INFO||||||| Generated Resource Param Data ({ }) 2023-01-03 06:30:09,192|654321||DefaultDispatcher-worker-13||||INFO||||||| Preparing Response... 2023-01-03 06:30:09,195|654321||DefaultDispatcher-worker-13||||INFO||||||| resolveNodeTemplateInterfaceOperationOutputs for node template (helloworld),interface name (ResourceResolutionComponent), operationName(process) 2023-01-03 06:30:09,195|654321||DefaultDispatcher-worker-13||||INFO||||||| resolveWorkflowOutputs for workflow(helloworld) 2023-01-03 06:30:09,203|654321||DefaultDispatcher-worker-13||||INFO||||||| storeExecutionOutput called not to store the Workflow action output details correlationUUID bd463d50-d383-403a-9312-7e8173562f79 auditStoreId -1