By type
Project Build Version: 1.0.0 2021-07-28 10:15:54.523 INFO --- [ main] org.onap.aai.util.AAIConfig : Initializing AAIConfig 2021-07-28 10:15:54.537 DEBUG --- [ main] org.onap.aai.util.AAIConfig : Reloading config from /opt/app/aai-graphadmin/./resources/etc/appprops/aaiconfig.properties 2021-07-28 10:15:54.537 INFO --- [ main] org.onap.aai.util.AAIConfig : Found the aaiconfig.properties in the following location: /opt/app/aai-graphadmin/./resources/etc/appprops/aaiconfig.properties 2021-07-28 10:15:54.544 INFO --- [ main] org.onap.aai.util.AAIConfig : A&AI Server Node Name = aai.config.nodename 2021-07-28 10:15:57.234 WARN 1 --- [ main] o.s.b.l.logback.LogbackLoggingSystem : Ignoring 'logback.configurationFile' system property. Please use 'logging.config' instead. . ____ _ __ _ _ /\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \ ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \ \\/ ___)| |_)| | | | | || (_| | ) ) ) ) ' |____| .__|_| |_|_| |_\__, | / / / / =========|_|==============|___/=/_/_/_/ :: Spring Boot :: (v2.1.12.RELEASE) 2021-07-28 10:15:58.096 INFO 1 --- [ main] org.onap.aai.GraphAdminApp : Starting GraphAdminApp on aai-graphadmin with PID 1 (/opt/app/aai-graphadmin/lib/aai-graphadmin-1.8.0.jar started by aaiadmin in /opt/app/aai-graphadmin) 2021-07-28 10:15:58.096 DEBUG 1 --- [ main] org.onap.aai.GraphAdminApp : Running with Spring Boot v2.1.12.RELEASE, Spring v5.1.13.RELEASE 2021-07-28 10:15:58.097 INFO 1 --- [ main] org.onap.aai.GraphAdminApp : The following profiles are active: dmaap,one-way-ssl 10:16:05,557 |-INFO in LogbackRequestLog - Will use configuration resource [/localhost-access-logback.xml] 10:16:05,568 |-INFO in ch.qos.logback.access.joran.action.ConfigurationAction - debug attribute not set 10:16:05,568 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender] 10:16:05,568 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [ACCESS] 10:16:05,569 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@9797126 - No compression will be used 10:16:05,569 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@9797126 - Will use the pattern /opt/app/aai-graphadmin/logs/ajsc-jetty/localhost_access.log.%d{yyyy-MM-dd} for the active file 10:16:05,569 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern '/opt/app/aai-graphadmin/logs/ajsc-jetty/localhost_access.log.%d{yyyy-MM-dd}'. 10:16:05,569 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight. 10:16:05,570 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Wed Jul 28 10:16:05 GMT 2021 10:16:05,606 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[ACCESS] - Active log file name: /opt/app/aai-graphadmin/logs/ajsc-jetty/localhost_access.log 10:16:05,606 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[ACCESS] - File property is set to [/opt/app/aai-graphadmin/logs/ajsc-jetty/localhost_access.log] 10:16:05,606 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [ACCESS] to null 10:16:05,606 |-INFO in ch.qos.logback.access.joran.action.ConfigurationAction - End of configuration. 10:16:05,606 |-INFO in ch.qos.logback.access.joran.JoranConfigurator@32502377 - Registering current configuration as safe fallback point 2021-07-28 10:16:05.965 DEBUG 1 --- [ main] org.reflections.Reflections : going to scan these urls: jar:file:/opt/app/aai-graphadmin/lib/aai-graphadmin-1.8.0.jar!/BOOT-INF/lib/aai-els-onap-logging-1.8.1.jar!/ 2021-07-28 10:16:06.057 INFO 1 --- [ main] org.reflections.Reflections : Reflections took 92 ms to scan 1 urls, producing 6 keys and 6 values 2021-07-28 10:16:06.068 DEBUG 1 --- [ main] org.reflections.Reflections : going to scan these urls: jar:file:/opt/app/aai-graphadmin/lib/aai-graphadmin-1.8.0.jar!/BOOT-INF/classes!/ 2021-07-28 10:16:06.098 INFO 1 --- [ main] org.reflections.Reflections : Reflections took 30 ms to scan 1 urls, producing 7 keys and 53 values 2021-07-28 10:16:06.165 DEBUG 1 --- [ main] org.reflections.Reflections : going to scan these urls: jar:file:/opt/app/aai-graphadmin/lib/aai-graphadmin-1.8.0.jar!/BOOT-INF/lib/aai-els-onap-logging-1.8.1.jar!/ 2021-07-28 10:16:06.177 INFO 1 --- [ main] org.reflections.Reflections : Reflections took 9 ms to scan 1 urls, producing 6 keys and 6 values 2021-07-28 10:16:06.192 DEBUG 1 --- [ main] org.reflections.Reflections : going to scan these urls: jar:file:/opt/app/aai-graphadmin/lib/aai-graphadmin-1.8.0.jar!/BOOT-INF/classes!/ 2021-07-28 10:16:06.216 INFO 1 --- [ main] org.reflections.Reflections : Reflections took 20 ms to scan 1 urls, producing 7 keys and 53 values 2021-07-28 10:16:06.227 DEBUG 1 --- [ main] org.reflections.Reflections : going to scan these urls: jar:file:/opt/app/aai-graphadmin/lib/aai-graphadmin-1.8.0.jar!/BOOT-INF/lib/aai-els-onap-logging-1.8.1.jar!/ 2021-07-28 10:16:06.232 INFO 1 --- [ main] org.reflections.Reflections : Reflections took 5 ms to scan 1 urls, producing 6 keys and 6 values 2021-07-28 10:16:06.239 DEBUG 1 --- [ main] org.reflections.Reflections : going to scan these urls: jar:file:/opt/app/aai-graphadmin/lib/aai-graphadmin-1.8.0.jar!/BOOT-INF/classes!/ 2021-07-28 10:16:06.255 INFO 1 --- [ main] org.reflections.Reflections : Reflections took 16 ms to scan 1 urls, producing 7 keys and 53 values 2021-07-28 10:16:07.005 INFO 1 --- [ main] org.onap.aai.config.NodesConfiguration : Translator is SchemaServiceTranslator 2021-07-28 10:16:07.737 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:07.738 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:07.738 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:07.740 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:07.752 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/versions, BODY= 2021-07-28 10:16:07.938 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:07.938 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:07.938 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:07.939 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:08.392 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:08.548 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Processing the translator 2021-07-28 10:16:08.548 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv11 2021-07-28 10:16:08.549 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:08.713 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:08.713 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:08.713 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:08.714 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:08.749 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:08.856 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:08.857 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:08.858 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:14.499 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 222 2021-07-28 10:16:14.592 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv12 2021-07-28 10:16:14.592 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:14.595 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:14.595 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:14.595 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:14.595 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:14.618 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:14.702 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:14.703 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:14.703 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:16.929 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 235 2021-07-28 10:16:16.995 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv13 2021-07-28 10:16:16.996 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:16.998 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:16.998 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:16.998 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:16.998 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:17.028 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:17.107 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:17.108 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:17.108 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:19.030 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 237 2021-07-28 10:16:19.101 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv14 2021-07-28 10:16:19.102 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:19.104 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:19.105 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:19.105 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:19.105 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:19.141 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:19.164 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:19.165 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:19.166 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:22.118 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 272 2021-07-28 10:16:22.152 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv15 2021-07-28 10:16:22.152 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:22.156 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:22.156 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:22.156 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:22.156 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:22.251 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:22.313 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:22.314 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:22.315 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:24.501 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 279 2021-07-28 10:16:24.521 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv16 2021-07-28 10:16:24.522 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:24.527 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:24.527 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:24.527 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:24.527 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:24.565 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:24.588 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:24.589 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:24.589 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:26.461 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 281 2021-07-28 10:16:26.513 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv17 2021-07-28 10:16:26.514 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:26.517 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:26.517 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:26.517 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:26.517 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:26.548 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:26.625 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:26.630 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:26.632 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:28.731 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 281 2021-07-28 10:16:28.745 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv18 2021-07-28 10:16:28.746 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:28.748 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:28.748 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:28.749 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:28.749 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:28.871 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:28.907 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:28.907 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:28.908 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:31.636 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 281 2021-07-28 10:16:31.650 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv19 2021-07-28 10:16:31.651 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:31.653 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:31.653 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:31.654 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:31.654 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:31.698 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:31.720 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:31.721 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:31.722 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:34.044 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 301 2021-07-28 10:16:34.058 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv20 2021-07-28 10:16:34.059 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:34.062 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:34.062 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:34.062 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:34.062 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:34.103 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:34.151 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:34.152 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:34.154 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:36.057 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 305 2021-07-28 10:16:36.071 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv21 2021-07-28 10:16:36.072 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:36.075 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:36.075 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:36.075 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:36.075 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:36.152 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:36.192 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:36.193 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:36.193 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:38.565 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 371 2021-07-28 10:16:38.579 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv22 2021-07-28 10:16:38.580 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:38.582 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:38.583 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:38.583 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:38.583 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:38.629 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:38.675 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:38.676 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:38.677 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:41.267 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 378 2021-07-28 10:16:41.287 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Version being processedv23 2021-07-28 10:16:41.287 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Headers: {Accept=application/xml, Content-Type=application/xml} 2021-07-28 10:16:41.290 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:41.290 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:41.290 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:41.290 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:41.389 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:41.439 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:41.440 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Retrieved OXMs from SchemaService 2021-07-28 10:16:41.441 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Ingested the InputStream 2021-07-28 10:16:43.809 DEBUG 1 --- [ main] org.onap.aai.nodes.NodeIngestor : Types size 378 2021-07-28 10:16:44.042 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.042 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.042 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.045 INFO 1 --- [ main] o.onap.aai.rest.client.ApertureService : Successfully initialized the aperture service 2021-07-28 10:16:44.291 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Processing the translator 2021-07-28 10:16:44.293 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv11 2021-07-28 10:16:44.293 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.294 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v11, BODY= 2021-07-28 10:16:44.296 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.296 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.296 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.296 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.312 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.314 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.314 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.315 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv12 2021-07-28 10:16:44.315 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.316 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v12, BODY= 2021-07-28 10:16:44.318 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.318 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.318 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.318 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.403 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.406 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.407 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.407 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv13 2021-07-28 10:16:44.408 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.409 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v13, BODY= 2021-07-28 10:16:44.411 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.411 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.411 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.411 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.425 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.429 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.430 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.430 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv14 2021-07-28 10:16:44.431 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.432 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v14, BODY= 2021-07-28 10:16:44.434 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.434 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.435 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.435 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.448 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.452 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.452 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.452 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv15 2021-07-28 10:16:44.453 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.454 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v15, BODY= 2021-07-28 10:16:44.455 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.456 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.456 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.456 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.482 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.486 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.486 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.487 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv16 2021-07-28 10:16:44.487 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.488 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v16, BODY= 2021-07-28 10:16:44.489 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.489 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.490 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.490 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.503 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.507 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.508 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.508 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv17 2021-07-28 10:16:44.508 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.509 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v17, BODY= 2021-07-28 10:16:44.511 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.511 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.511 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.511 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.530 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.534 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.536 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.536 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv18 2021-07-28 10:16:44.536 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.537 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v18, BODY= 2021-07-28 10:16:44.539 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.539 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.539 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.539 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.552 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.557 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.558 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.558 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv19 2021-07-28 10:16:44.560 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.561 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v19, BODY= 2021-07-28 10:16:44.562 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.562 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.563 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.563 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.582 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.585 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.586 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.586 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv20 2021-07-28 10:16:44.586 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.588 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v20, BODY= 2021-07-28 10:16:44.589 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.589 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.589 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.589 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.606 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.611 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.611 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.612 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv21 2021-07-28 10:16:44.612 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.613 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v21, BODY= 2021-07-28 10:16:44.614 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.614 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.614 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.614 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.637 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.645 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.646 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.647 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv22 2021-07-28 10:16:44.647 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.648 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v22, BODY= 2021-07-28 10:16:44.650 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.650 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.650 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.650 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.669 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.674 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.675 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:44.675 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Version being processedv23 2021-07-28 10:16:44.676 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : Request Headers: {} 2021-07-28 10:16:44.676 DEBUG 1 --- [ main] org.onap.aai.restclient.RestClient : METHOD=GET, URL=https://aai-schema-service.onap:8452/aai/schema-service/v1/edgerules?version=v23, BODY= 2021-07-28 10:16:44.678 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Checking for system property [partnerName] 2021-07-28 10:16:44.678 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : System property was null or empty. Checking environment variable for: partnerName 2021-07-28 10:16:44.678 INFO 1 --- [ main] org.onap.logging.filter.base.MDCSetup : Environment variable: partnerName was null or empty 2021-07-28 10:16:44.678 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : Invoke 2021-07-28 10:16:44.702 INFO 1 --- [ main] o.o.l.f.base.AbstractMetricLogFilter : InvokeReturn 2021-07-28 10:16:44.709 DEBUG 1 --- [ main] o.o.aai.setup.SchemaServiceTranslator : SchemaResponse Status code200 OK 2021-07-28 10:16:44.709 DEBUG 1 --- [ main] org.onap.aai.edges.EdgeIngestor : Retrieved json from SchemaService 2021-07-28 10:16:45.270 WARN 1 --- [ main] o.apache.activemq.broker.BrokerService : Memory Usage for the Broker (1024mb) is more than the maximum available for the JVM: 972 mb - resetting to 70% of maximum available: 680 mb 2021-07-28 10:16:46.918 INFO 1 --- [ main] com.datastax.driver.core : DataStax Java driver 3.6.0 for Apache Cassandra 2021-07-28 10:16:46.938 INFO 1 --- [ main] c.d.driver.core.GuavaCompatibility : Detected Guava >= 19 in the classpath, using modern compatibility layer 2021-07-28 10:16:46.941 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.NEW_NODE_DELAY_SECONDS is undefined, using default value 1 2021-07-28 10:16:46.944 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.NOTIF_LOCK_TIMEOUT_SECONDS is undefined, using default value 60 2021-07-28 10:16:47.044 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.USE_NATIVE_CLOCK is undefined, using default value true 2021-07-28 10:16:47.496 INFO 1 --- [ main] com.datastax.driver.core.ClockFactory : Using native clock to generate timestamps. 2021-07-28 10:16:47.500 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.NON_BLOCKING_EXECUTOR_SIZE is undefined, using default value 2 2021-07-28 10:16:47.632 WARN 1 --- [ main] .b.a.g.t.GroovyTemplateAutoConfiguration : Cannot find template location: classpath:/templates/ (please add some templates, check your Groovy configuration, or set spring.groovy.template.check-template-location=false) 2021-07-28 10:16:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:16:49.061 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:16:52.003 WARN 1 --- [ main] org.glassfish.jersey.internal.Errors : The following warnings have been detected: WARNING: A HTTP GET method, public javax.ws.rs.core.Response org.onap.aai.rest.AuditSqlDbConsumer.executeAudit(java.lang.String,java.lang.String,javax.ws.rs.core.HttpHeaders,javax.ws.rs.core.UriInfo), should not consume any entity. 2021-07-28 10:16:52.143 INFO 1 --- [ main] org.onap.aai.GraphAdminApp : Started GraphAdminApp in 57.077 seconds (JVM running for 60.17) 2021-07-28 10:16:52.146 INFO 1 --- [ main] org.onap.aai.GraphAdminApp : Application 'aai-graphadmin' is running on 8449! 2021-07-28 10:16:52.608 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.USE_NATIVE_CLOCK is undefined, using default value true 2021-07-28 10:16:52.608 INFO 1 --- [ main] com.datastax.driver.core.ClockFactory : Using native clock to generate timestamps. 2021-07-28 10:16:52.608 DEBUG 1 --- [ main] com.datastax.driver.core.Cluster : Starting new cluster with contact points [cassandra/10.242.123.89:9042] 2021-07-28 10:16:52.646 DEBUG 1 --- [ main] i.n.u.i.logging.InternalLoggerFactory : Using SLF4J as the default logging framework 2021-07-28 10:16:52.718 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : -Dio.netty.noUnsafe: false 2021-07-28 10:16:52.719 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : Java version: 8 2021-07-28 10:16:52.721 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : sun.misc.Unsafe.theUnsafe: available 2021-07-28 10:16:52.722 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : sun.misc.Unsafe.copyMemory: available 2021-07-28 10:16:52.723 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : java.nio.Buffer.address: available 2021-07-28 10:16:52.724 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : direct buffer constructor: available 2021-07-28 10:16:52.725 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : java.nio.Bits.unaligned: available, true 2021-07-28 10:16:52.726 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : jdk.internal.misc.Unsafe.allocateUninitializedArray(int): unavailable prior to Java9 2021-07-28 10:16:52.726 DEBUG 1 --- [ main] i.n.util.internal.PlatformDependent0 : java.nio.DirectByteBuffer.
(long, int): available 2021-07-28 10:16:52.726 DEBUG 1 --- [ main] i.netty.util.internal.PlatformDependent : sun.misc.Unsafe: available 2021-07-28 10:16:52.726 DEBUG 1 --- [ main] i.netty.util.internal.PlatformDependent : -Dio.netty.tmpdir: /tmp (java.io.tmpdir) 2021-07-28 10:16:52.726 DEBUG 1 --- [ main] i.netty.util.internal.PlatformDependent : -Dio.netty.bitMode: 64 (sun.arch.data.model) 2021-07-28 10:16:52.728 DEBUG 1 --- [ main] i.netty.util.internal.PlatformDependent : -Dio.netty.noPreferDirect: false 2021-07-28 10:16:52.728 DEBUG 1 --- [ main] i.netty.util.internal.PlatformDependent : -Dio.netty.maxDirectMemory: 1020067840 bytes 2021-07-28 10:16:52.728 DEBUG 1 --- [ main] i.netty.util.internal.PlatformDependent : -Dio.netty.uninitializedArrayAllocationThreshold: -1 2021-07-28 10:16:52.730 DEBUG 1 --- [ main] io.netty.util.internal.CleanerJava6 : java.nio.ByteBuffer.cleaner(): available 2021-07-28 10:16:52.743 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.FORCE_NIO is undefined, using default value false 2021-07-28 10:16:52.807 DEBUG 1 --- [ main] i.n.util.internal.NativeLibraryLoader : -Dio.netty.native.workdir: /tmp (io.netty.tmpdir) 2021-07-28 10:16:52.814 DEBUG 1 --- [ main] i.n.util.internal.NativeLibraryLoader : Unable to load the library 'netty_transport_native_epoll_x86_64', trying other loading mechanism. java.lang.UnsatisfiedLinkError: no netty_transport_native_epoll_x86_64 in java.library.path at java.lang.ClassLoader.loadLibrary(ClassLoader.java:1867) at java.lang.Runtime.loadLibrary0(Runtime.java:870) at java.lang.System.loadLibrary(System.java:1122) at io.netty.util.internal.NativeLibraryUtil.loadLibrary(NativeLibraryUtil.java:38) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at io.netty.util.internal.NativeLibraryLoader$1.run(NativeLibraryLoader.java:263) at java.security.AccessController.doPrivileged(Native Method) at io.netty.util.internal.NativeLibraryLoader.loadLibraryByHelper(NativeLibraryLoader.java:255) at io.netty.util.internal.NativeLibraryLoader.loadLibrary(NativeLibraryLoader.java:233) at io.netty.util.internal.NativeLibraryLoader.load(NativeLibraryLoader.java:124) at io.netty.channel.epoll.Native.loadNativeLibrary(Native.java:287) at io.netty.channel.epoll.Native. (Native.java:69) at io.netty.channel.epoll.Epoll. (Epoll.java:33) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Class.java:264) at com.datastax.driver.core.NettyUtil. (NettyUtil.java:57) at com.datastax.driver.core.NettyOptions.eventLoopGroup(NettyOptions.java:95) at com.datastax.driver.core.Connection$Factory. (Connection.java:926) at com.datastax.driver.core.Cluster$Manager.init(Cluster.java:1499) at com.datastax.driver.core.Cluster.init(Cluster.java:208) at com.datastax.driver.core.Cluster.connectAsync(Cluster.java:376) at com.datastax.driver.core.Cluster.connectAsync(Cluster.java:355) at com.datastax.driver.core.Cluster.connect(Cluster.java:305) at org.janusgraph.diskstorage.cql.CQLStoreManager.initializeSession(CQLStoreManager.java:272) at org.janusgraph.diskstorage.cql.CQLStoreManager. (CQLStoreManager.java:165) at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:423) at org.janusgraph.util.system.ConfigurationUtil.instantiate(ConfigurationUtil.java:58) at org.janusgraph.diskstorage.Backend.getImplementationClass(Backend.java:477) at org.janusgraph.diskstorage.Backend.getStorageManager(Backend.java:409) at org.janusgraph.graphdb.configuration.GraphDatabaseConfiguration. (GraphDatabaseConfiguration.java:1382) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:164) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:133) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:113) at org.onap.aai.dbmap.AAIGraph.loadGraph(AAIGraph.java:107) at org.onap.aai.dbmap.AAIGraph. (AAIGraph.java:73) at org.onap.aai.dbmap.AAIGraph. (AAIGraph.java:54) at org.onap.aai.dbmap.AAIGraph$Helper. (AAIGraph.java:80) at org.onap.aai.dbmap.AAIGraph.getInstance(AAIGraph.java:94) at org.onap.aai.GraphAdminApp.main(GraphAdminApp.java:135) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48) at org.springframework.boot.loader.Launcher.launch(Launcher.java:87) at org.springframework.boot.loader.Launcher.launch(Launcher.java:50) at org.springframework.boot.loader.PropertiesLauncher.main(PropertiesLauncher.java:595) 2021-07-28 10:16:52.816 DEBUG 1 --- [ main] i.n.util.internal.NativeLibraryLoader : netty_transport_native_epoll_x86_64 cannot be loaded from java.libary.path, now trying export to -Dio.netty.native.workdir: /tmp java.lang.UnsatisfiedLinkError: no netty_transport_native_epoll_x86_64 in java.library.path at java.lang.ClassLoader.loadLibrary(ClassLoader.java:1867) at java.lang.Runtime.loadLibrary0(Runtime.java:870) at java.lang.System.loadLibrary(System.java:1122) at io.netty.util.internal.NativeLibraryUtil.loadLibrary(NativeLibraryUtil.java:38) at io.netty.util.internal.NativeLibraryLoader.loadLibrary(NativeLibraryLoader.java:243) at io.netty.util.internal.NativeLibraryLoader.load(NativeLibraryLoader.java:124) at io.netty.channel.epoll.Native.loadNativeLibrary(Native.java:287) at io.netty.channel.epoll.Native. (Native.java:69) at io.netty.channel.epoll.Epoll. (Epoll.java:33) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Class.java:264) at com.datastax.driver.core.NettyUtil. (NettyUtil.java:57) at com.datastax.driver.core.NettyOptions.eventLoopGroup(NettyOptions.java:95) at com.datastax.driver.core.Connection$Factory. (Connection.java:926) at com.datastax.driver.core.Cluster$Manager.init(Cluster.java:1499) at com.datastax.driver.core.Cluster.init(Cluster.java:208) at com.datastax.driver.core.Cluster.connectAsync(Cluster.java:376) at com.datastax.driver.core.Cluster.connectAsync(Cluster.java:355) at com.datastax.driver.core.Cluster.connect(Cluster.java:305) at org.janusgraph.diskstorage.cql.CQLStoreManager.initializeSession(CQLStoreManager.java:272) at org.janusgraph.diskstorage.cql.CQLStoreManager. (CQLStoreManager.java:165) at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:423) at org.janusgraph.util.system.ConfigurationUtil.instantiate(ConfigurationUtil.java:58) at org.janusgraph.diskstorage.Backend.getImplementationClass(Backend.java:477) at org.janusgraph.diskstorage.Backend.getStorageManager(Backend.java:409) at org.janusgraph.graphdb.configuration.GraphDatabaseConfiguration. (GraphDatabaseConfiguration.java:1382) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:164) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:133) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:113) at org.onap.aai.dbmap.AAIGraph.loadGraph(AAIGraph.java:107) at org.onap.aai.dbmap.AAIGraph. (AAIGraph.java:73) at org.onap.aai.dbmap.AAIGraph. (AAIGraph.java:54) at org.onap.aai.dbmap.AAIGraph$Helper. (AAIGraph.java:80) at org.onap.aai.dbmap.AAIGraph.getInstance(AAIGraph.java:94) at org.onap.aai.GraphAdminApp.main(GraphAdminApp.java:135) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48) at org.springframework.boot.loader.Launcher.launch(Launcher.java:87) at org.springframework.boot.loader.Launcher.launch(Launcher.java:50) at org.springframework.boot.loader.PropertiesLauncher.main(PropertiesLauncher.java:595) Suppressed: java.lang.UnsatisfiedLinkError: no netty_transport_native_epoll_x86_64 in java.library.path at java.lang.ClassLoader.loadLibrary(ClassLoader.java:1867) at java.lang.Runtime.loadLibrary0(Runtime.java:870) at java.lang.System.loadLibrary(System.java:1122) at io.netty.util.internal.NativeLibraryUtil.loadLibrary(NativeLibraryUtil.java:38) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at io.netty.util.internal.NativeLibraryLoader$1.run(NativeLibraryLoader.java:263) at java.security.AccessController.doPrivileged(Native Method) at io.netty.util.internal.NativeLibraryLoader.loadLibraryByHelper(NativeLibraryLoader.java:255) at io.netty.util.internal.NativeLibraryLoader.loadLibrary(NativeLibraryLoader.java:233) ... 41 common frames omitted 2021-07-28 10:16:52.843 DEBUG 1 --- [ main] i.n.util.internal.NativeLibraryLoader : Successfully loaded the library /tmp/libnetty_transport_native_epoll_x86_647582499628886788185.so 2021-07-28 10:16:52.848 DEBUG 1 --- [ main] io.netty.util.NetUtil : Loopback interface: lo (lo, 0:0:0:0:0:0:0:1%lo) 2021-07-28 10:16:52.850 DEBUG 1 --- [ main] io.netty.util.NetUtil : /proc/sys/net/core/somaxconn: 128 2021-07-28 10:16:52.850 INFO 1 --- [ main] com.datastax.driver.core.NettyUtil : Found Netty's native epoll transport in the classpath, using it 2021-07-28 10:16:52.893 DEBUG 1 --- [ main] i.n.channel.MultithreadEventLoopGroup : -Dio.netty.eventLoopThreads: 4 2021-07-28 10:16:52.957 DEBUG 1 --- [ main] i.netty.util.internal.PlatformDependent : org.jctools-core.MpscChunkedArrayQueue: available 2021-07-28 10:16:52.984 DEBUG 1 --- [ main] io.netty.util.ResourceLeakDetector : -Dio.netty.leakDetection.level: simple 2021-07-28 10:16:52.984 DEBUG 1 --- [ main] io.netty.util.ResourceLeakDetector : -Dio.netty.leakDetection.targetRecords: 4 2021-07-28 10:16:52.984 DEBUG 1 --- [ main] i.n.util.ResourceLeakDetectorFactory : Loaded default ResourceLeakDetector: io.netty.util.ResourceLeakDetector@539953af 2021-07-28 10:16:53.010 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.EXTENDED_PEER_CHECK is undefined, using default value true 2021-07-28 10:16:53.130 DEBUG 1 --- [ main] com.datastax.driver.core.Host.STATES : [cassandra/10.242.123.89:9042] preparing to open 1 new connections, total = 1 2021-07-28 10:16:53.137 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.DISABLE_COALESCING is undefined, using default value false 2021-07-28 10:16:53.138 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.FLUSHER_SCHEDULE_PERIOD_NS is undefined, using default value 10000 2021-07-28 10:16:53.138 DEBUG 1 --- [ main] c.datastax.driver.core.SystemProperties : com.datastax.driver.FLUSHER_RUN_WITHOUT_WORK_TIMES is undefined, using default value 5 2021-07-28 10:16:53.152 DEBUG 1 --- [ main] i.n.u.internal.InternalThreadLocalMap : -Dio.netty.threadLocalMap.stringBuilder.initialSize: 1024 2021-07-28 10:16:53.152 DEBUG 1 --- [ main] i.n.u.internal.InternalThreadLocalMap : -Dio.netty.threadLocalMap.stringBuilder.maxSize: 4096 2021-07-28 10:16:53.205 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.numHeapArenas: 4 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.numDirectArenas: 4 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.pageSize: 8192 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.maxOrder: 11 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.chunkSize: 16777216 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.tinyCacheSize: 512 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.smallCacheSize: 256 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.normalCacheSize: 64 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.maxCachedBufferCapacity: 32768 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.cacheTrimInterval: 8192 2021-07-28 10:16:53.206 DEBUG 1 --- [ main] io.netty.buffer.PooledByteBufAllocator : -Dio.netty.allocator.useCacheForAllThreads: true 2021-07-28 10:16:53.316 DEBUG 1 --- [ main] io.netty.buffer.ByteBufUtil : -Dio.netty.allocator.type: unpooled 2021-07-28 10:16:53.316 DEBUG 1 --- [ main] io.netty.buffer.ByteBufUtil : -Dio.netty.threadLocalDirectBufferSize: 65536 2021-07-28 10:16:53.317 DEBUG 1 --- [ main] io.netty.buffer.ByteBufUtil : -Dio.netty.maxThreadLocalCharBufferSize: 16384 2021-07-28 10:16:53.400 DEBUG 1 --- [er-nio-worker-0] com.datastax.driver.core.Connection : Connection[cassandra/10.242.123.89:9042-1, inFlight=0, closed=false] Error connecting to cassandra/10.242.123.89:9042 (syscall:getsockopt(..) failed: Connection refused: cassandra/10.242.123.89:9042) 2021-07-28 10:16:53.410 DEBUG 1 --- [er-nio-worker-0] com.datastax.driver.core.Host.STATES : Defuncting Connection[cassandra/10.242.123.89:9042-1, inFlight=0, closed=false] because: [cassandra/10.242.123.89:9042] Cannot connect 2021-07-28 10:16:53.413 DEBUG 1 --- [er-nio-worker-0] com.datastax.driver.core.Host.STATES : [cassandra/10.242.123.89:9042] preventing new connections for the next 1000 ms 2021-07-28 10:16:53.415 DEBUG 1 --- [er-nio-worker-0] com.datastax.driver.core.Host.STATES : [cassandra/10.242.123.89:9042] Connection[cassandra/10.242.123.89:9042-1, inFlight=0, closed=false] failed, remaining = 0 2021-07-28 10:16:53.416 DEBUG 1 --- [er-nio-worker-0] com.datastax.driver.core.Connection : Connection[cassandra/10.242.123.89:9042-1, inFlight=0, closed=true] closing connection 2021-07-28 10:16:53.421 DEBUG 1 --- [ main] c.d.driver.core.ControlConnection : [Control connection] error on cassandra/10.242.123.89:9042 connection, no more host to try com.datastax.driver.core.exceptions.TransportException: [cassandra/10.242.123.89:9042] Cannot connect at com.datastax.driver.core.Connection$1.operationComplete(Connection.java:224) at com.datastax.driver.core.Connection$1.operationComplete(Connection.java:194) at io.netty.util.concurrent.DefaultPromise.notifyListener0(DefaultPromise.java:512) at io.netty.util.concurrent.DefaultPromise.notifyListeners0(DefaultPromise.java:505) at io.netty.util.concurrent.DefaultPromise.notifyListenersNow(DefaultPromise.java:484) at io.netty.util.concurrent.DefaultPromise.notifyListeners(DefaultPromise.java:425) at io.netty.util.concurrent.DefaultPromise.tryFailure(DefaultPromise.java:122) at io.netty.channel.epoll.AbstractEpollChannel$AbstractEpollUnsafe.fulfillConnectPromise(AbstractEpollChannel.java:574) at io.netty.channel.epoll.AbstractEpollChannel$AbstractEpollUnsafe.finishConnect(AbstractEpollChannel.java:593) at io.netty.channel.epoll.AbstractEpollChannel$AbstractEpollUnsafe.epollOutReady(AbstractEpollChannel.java:468) at io.netty.channel.epoll.EpollEventLoop.processReady(EpollEventLoop.java:390) at io.netty.channel.epoll.EpollEventLoop.run(EpollEventLoop.java:300) at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:131) at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) at java.lang.Thread.run(Thread.java:748) Caused by: io.netty.channel.AbstractChannel$AnnotatedConnectException: syscall:getsockopt(..) failed: Connection refused: cassandra/10.242.123.89:9042 at io.netty.channel.unix.Socket.finishConnect(..)(Unknown Source) Caused by: io.netty.channel.unix.Errors$NativeConnectException: syscall:getsockopt(..) failed: Connection refused ... 1 common frames omitted 2021-07-28 10:16:53.422 DEBUG 1 --- [ main] com.datastax.driver.core.Cluster : Shutting down Exception in thread "main" java.lang.reflect.InvocationTargetException at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48) at org.springframework.boot.loader.Launcher.launch(Launcher.java:87) at org.springframework.boot.loader.Launcher.launch(Launcher.java:50) at org.springframework.boot.loader.PropertiesLauncher.main(PropertiesLauncher.java:595) Caused by: java.lang.ExceptionInInitializerError at org.onap.aai.dbmap.AAIGraph.getInstance(AAIGraph.java:94) at org.onap.aai.GraphAdminApp.main(GraphAdminApp.java:135) ... 8 more Caused by: java.lang.RuntimeException: Failed to instantiate graphs at org.onap.aai.dbmap.AAIGraph. (AAIGraph.java:75) at org.onap.aai.dbmap.AAIGraph. (AAIGraph.java:54) at org.onap.aai.dbmap.AAIGraph$Helper. (AAIGraph.java:80) ... 10 more Caused by: java.lang.IllegalArgumentException: Could not instantiate implementation: org.janusgraph.diskstorage.cql.CQLStoreManager at org.janusgraph.util.system.ConfigurationUtil.instantiate(ConfigurationUtil.java:69) at org.janusgraph.diskstorage.Backend.getImplementationClass(Backend.java:477) at org.janusgraph.diskstorage.Backend.getStorageManager(Backend.java:409) at org.janusgraph.graphdb.configuration.GraphDatabaseConfiguration. (GraphDatabaseConfiguration.java:1382) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:164) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:133) at org.janusgraph.core.JanusGraphFactory.open(JanusGraphFactory.java:113) at org.onap.aai.dbmap.AAIGraph.loadGraph(AAIGraph.java:107) at org.onap.aai.dbmap.AAIGraph. (AAIGraph.java:73) ... 12 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.lang.reflect.Constructor.newInstance(Constructor.java:423) at org.janusgraph.util.system.ConfigurationUtil.instantiate(ConfigurationUtil.java:58) ... 20 more Caused by: com.datastax.driver.core.exceptions.NoHostAvailableException: All host(s) tried for query failed (tried: cassandra/10.242.123.89:9042 (com.datastax.driver.core.exceptions.TransportException: [cassandra/10.242.123.89:9042] Cannot connect)) at com.datastax.driver.core.ControlConnection.reconnectInternal(ControlConnection.java:268) at com.datastax.driver.core.ControlConnection.connect(ControlConnection.java:107) at com.datastax.driver.core.Cluster$Manager.negotiateProtocolVersionAndConnect(Cluster.java:1652) at com.datastax.driver.core.Cluster$Manager.init(Cluster.java:1571) at com.datastax.driver.core.Cluster.init(Cluster.java:208) at com.datastax.driver.core.Cluster.connectAsync(Cluster.java:376) at com.datastax.driver.core.Cluster.connectAsync(Cluster.java:355) at com.datastax.driver.core.Cluster.connect(Cluster.java:305) at org.janusgraph.diskstorage.cql.CQLStoreManager.initializeSession(CQLStoreManager.java:272) at org.janusgraph.diskstorage.cql.CQLStoreManager. (CQLStoreManager.java:165) ... 25 more 2021-07-28 10:17:49.062 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:17:49.066 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:18:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:18:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:19:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:19:49.060 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:20:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:20:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:21:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:21:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:22:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:22:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:23:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:23:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:24:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:24:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:25:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:25:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:26:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:26:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:27:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:27:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:28:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:28:49.059 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:29:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:29:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:30:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:30:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:31:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:31:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:32:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:32:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:33:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:33:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:34:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:34:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:35:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:35:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:36:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:36:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:37:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:37:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:38:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:38:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:39:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:39:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:40:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:40:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:41:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:41:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:42:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:42:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:43:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:43:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:44:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:44:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:45:00.002 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:45:00.043 DEBUG 1 --- [ scheduling-1] o.o.aai.datasnapshot.DataSnapshotTasks : Exit value of the dataSnapshot check process: 1 2021-07-28 10:45:00.043 DEBUG 1 --- [ scheduling-1] o.o.aai.datasnapshot.DataSnapshotTasks : Started cron job dataSnapshot @ 10:45:00 2021-07-28 10:45:00.044 DEBUG 1 --- [ scheduling-1] o.o.aai.datasnapshot.DataSnapshotTasks : DataSnapshot Params [-c, JUST_TAKE_SNAPSHOT] 2021-07-28 10:45:00.066 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.threads.for.create] value from aaiconfig.properties file. Property key aai.datasnapshot.threads.for.create cannot be found 2021-07-28 10:45:00.066 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.max.nodes.per.file.for.create] value from aaiconfig.properties file. Property key aai.datasnapshot.max.nodes.per.file.for.create cannot be found 2021-07-28 10:45:00.066 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.vertex.add.delay.ms] value from aaiconfig.properties file. Property key aai.datasnapshot.vertex.add.delay.ms cannot be found 2021-07-28 10:45:00.066 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.edge.add.delay.ms] value from aaiconfig.properties file. Property key aai.datasnapshot.edge.add.delay.ms cannot be found 2021-07-28 10:45:00.067 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.failure.delay.ms] value from aaiconfig.properties file. Property key aai.datasnapshot.failure.delay.ms cannot be found 2021-07-28 10:45:00.067 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.retry.delay.ms] value from aaiconfig.properties file. Property key aai.datasnapshot.retry.delay.ms cannot be found 2021-07-28 10:45:00.067 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.max.errors.per.thread] value from aaiconfig.properties file. Property key aai.datasnapshot.max.errors.per.thread cannot be found 2021-07-28 10:45:00.067 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.vertex.to.edge.proc.delay.ms] value from aaiconfig.properties file. Property key aai.datasnapshot.vertex.to.edge.proc.delay.ms cannot be found 2021-07-28 10:45:00.067 WARN 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : WARNING - could not get [aai.datasnapshot.stagger.thread.delay.ms] value from aaiconfig.properties file. Property key aai.datasnapshot.stagger.thread.delay.ms cannot be found 2021-07-28 10:45:00.131 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : DataSnapshot command is [JUST_TAKE_SNAPSHOT] 2021-07-28 10:45:00.132 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : File name to reload snapshot [] 2021-07-28 10:45:00.132 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : snapshotType is [graphson] 2021-07-28 10:45:00.132 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : Thread count is [15] 2021-07-28 10:45:00.132 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : Max Nodes Per File is [120000] 2021-07-28 10:45:00.132 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : Debug Flag is [] 2021-07-28 10:45:00.133 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : DebugAddDelayTimer is [1] 2021-07-28 10:45:00.133 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : VertAddDelayMs is [1] 2021-07-28 10:45:00.133 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : FailureDelayMs is [50] 2021-07-28 10:45:00.133 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : RetryDelayMs is [50] 2021-07-28 10:45:00.133 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : MaxErrorsPerThread is [25] 2021-07-28 10:45:00.134 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : VertToEdgeProcDelay is [9000] 2021-07-28 10:45:00.134 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : StaggerThreadDelay is [5000] 2021-07-28 10:45:00.134 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : Caller process is [] 2021-07-28 10:45:00.134 INFO 1 --- [ scheduling-1] org.onap.aai.util.AAIConfig : Initializing AAIConfig 2021-07-28 10:45:00.135 DEBUG 1 --- [ scheduling-1] org.onap.aai.util.AAIConfig : Reloading config from /opt/app/aai-graphadmin/./resources/etc/appprops/aaiconfig.properties 2021-07-28 10:45:00.135 INFO 1 --- [ scheduling-1] org.onap.aai.util.AAIConfig : Found the aaiconfig.properties in the following location: /opt/app/aai-graphadmin/./resources/etc/appprops/aaiconfig.properties 2021-07-28 10:45:00.136 INFO 1 --- [ scheduling-1] org.onap.aai.util.AAIConfig : A&AI Server Node Name = aai.config.nodename 2021-07-28 10:45:00.142 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : Command = JUST_TAKE_SNAPSHOT, oldSnapshotFileName = []. 2021-07-28 10:45:00.143 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : ---- NOTE --- about to open graph (takes a little while) 2021-07-28 10:45:00.144 DEBUG 1 --- [ scheduling-1] org.onap.aai.datasnapshot.DataSnapshot : Command = JUST_TAKE_SNAPSHOT 2021-07-28 10:45:00.146 DEBUG 1 --- [ scheduling-1] o.o.aai.datasnapshot.DataSnapshotTasks : Ended cron job dataSnapshot @ 10:45:00 2021-07-28 10:45:00.147 ERROR 1 --- [ scheduling-1] o.s.s.s.TaskUtils$LoggingErrorHandler : Unexpected error occurred in scheduled task. java.lang.NoClassDefFoundError: Could not initialize class org.onap.aai.dbmap.AAIGraph$Helper at org.onap.aai.dbmap.AAIGraph.getInstance(AAIGraph.java:94) at org.onap.aai.datasnapshot.DataSnapshot.executeCommand(DataSnapshot.java:448) at org.onap.aai.datasnapshot.DataSnapshot.main(DataSnapshot.java:102) at org.onap.aai.datasnapshot.DataSnapshotTasks.snapshotScheduleTask(DataSnapshotTasks.java:70) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.springframework.scheduling.support.ScheduledMethodRunnable.run(ScheduledMethodRunnable.java:84) at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) at org.springframework.scheduling.concurrent.ReschedulingRunnable.run(ReschedulingRunnable.java:93) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 2021-07-28 10:45:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:45:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:46:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:46:49.068 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:47:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:47:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:48:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:48:49.058 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:49:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:49:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:50:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:50:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:51:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:51:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:52:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:52:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:53:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:53:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:54:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:54:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:55:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:55:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:56:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:56:49.064 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:57:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:57:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:58:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:58:49.058 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 10:59:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 10:59:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:00:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:00:49.063 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:01:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:01:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:02:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:02:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:03:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:03:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:04:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:04:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:05:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:05:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:06:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:06:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:07:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:07:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:08:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:08:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:09:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:09:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:10:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:10:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:11:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:11:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:12:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:12:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:13:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:13:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:14:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:14:49.055 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:15:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:15:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:16:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:16:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:17:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:17:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:18:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:18:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:19:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:19:49.057 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:20:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:20:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:21:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:21:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:22:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:22:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:23:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:23:49.058 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:24:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:24:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:25:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:25:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:26:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:26:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:27:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:27:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:28:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:28:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:29:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:29:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:30:49.051 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:30:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:31:49.053 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:31:49.056 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting. 2021-07-28 11:32:49.052 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Entering 2021-07-28 11:32:49.054 INFO 1 --- [ scheduling-1] o.o.a.a.logs.AaiScheduledTaskAuditLog : Exiting.