2023-05-28 23:44:06 
2023-05-28 23:44:06   .   ____          _            __ _ _
2023-05-28 23:44:06  /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
2023-05-28 23:44:06 ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
2023-05-28 23:44:06  \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
2023-05-28 23:44:06   '  |____| .__|_| |_|_| |_\__, | / / / /
2023-05-28 23:44:06  =========|_|==============|___/=/_/_/_/
2023-05-28 23:44:06  :: Spring Boot ::       (v1.5.22.RELEASE)
2023-05-28 23:44:06 
2023-05-28 23:44:06 2023-05-29 03:44:06.581 INFO main org.springframework.boot.StartupInfoLogger - [] - Starting WebApi on 73f8ee00d4bf with PID 1 (/var/lib/ohdsi/webapi/WEB-INF/classes started by ? in /var/lib/ohdsi/webapi)
2023-05-28 23:44:06 2023-05-29 03:44:06.589 INFO main org.springframework.boot.SpringApplication - [] - The following profiles are active: default
2023-05-28 23:44:06 2023-05-29 03:44:06.663 INFO main org.springframework.context.support.AbstractApplicationContext - [] - Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@33a10788: startup date [Mon May 29 03:44:06 UTC 2023]; root of context hierarchy
2023-05-28 23:44:06 2023-05-29 03:44:06.820 INFO background-preinit org.hibernate.validator.internal.util.Version - [] - HV000001: Hibernate Validator 5.4.2.Final
2023-05-28 23:44:07 2023-05-29 03:44:07.973 INFO main org.springframework.beans.factory.support.DefaultListableBeanFactory - [] - Overriding bean definition for bean 'jobBuilders' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.batch.core.configuration.annotation.SimpleBatchConfiguration; factoryMethodName=jobBuilders; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/batch/core/configuration/annotation/SimpleBatchConfiguration.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=true; factoryBeanName=jobConfig; factoryMethodName=jobBuilders; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/ohdsi/webapi/JobConfig.class]]
2023-05-28 23:44:08 2023-05-29 03:44:08.120 INFO main org.springframework.beans.factory.support.DefaultListableBeanFactory - [] - Overriding bean definition for bean 'requestContextFilter' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.boot.autoconfigure.jersey.JerseyAutoConfiguration; factoryMethodName=requestContextFilter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/jersey/JerseyAutoConfiguration.class]] with [Root bean: class [org.springframework.boot.autoconfigure.web.WebMvcAutoConfiguration$WebMvcAutoConfigurationAdapter]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=requestContextFilter; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/boot/autoconfigure/web/WebMvcAutoConfiguration$WebMvcAutoConfigurationAdapter.class]]
2023-05-28 23:44:08 2023-05-29 03:44:08.373 INFO main org.springframework.integration.config.IntegrationRegistrar - [] - No bean named 'integrationHeaderChannelRegistry' has been explicitly defined. Therefore, a default DefaultHeaderChannelRegistry will be created.
2023-05-28 23:44:08 2023-05-29 03:44:08.750 INFO main org.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor - [] - No bean named 'errorChannel' has been explicitly defined. Therefore, a default PublishSubscribeChannel will be created.
2023-05-28 23:44:08 2023-05-29 03:44:08.774 INFO main org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor - [] - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2023-05-28 23:44:08 2023-05-29 03:44:08.844 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'converterConfiguration' of type [org.ohdsi.webapi.ConverterConfiguration$$EnhancerBySpringCGLIB$$6578ac4] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:08 2023-05-29 03:44:08.864 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'conversionService' of type [org.springframework.core.convert.support.DefaultConversionService] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:09 2023-05-29 03:44:09.058 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$9fbc6378] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:09 2023-05-29 03:44:09.309 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'shiroConfiguration' of type [org.ohdsi.webapi.ShiroConfiguration$$EnhancerBySpringCGLIB$$e42ac973] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:09 2023-05-29 03:44:09.319 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'flywayConfig' of type [org.ohdsi.webapi.FlywayConfig$$EnhancerBySpringCGLIB$$a26015cc] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:09 2023-05-29 03:44:09.549 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'secondaryDataSource' of type [org.apache.tomcat.jdbc.pool.DataSource] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:09 2023-05-29 03:44:09.571 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'org.springframework.boot.autoconfigure.jdbc.DataSourceAutoConfiguration' of type [org.springframework.boot.autoconfigure.jdbc.DataSourceAutoConfiguration$$EnhancerBySpringCGLIB$$81b006d8] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:09 2023-05-29 03:44:09.597 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'spring.datasource-org.springframework.boot.autoconfigure.jdbc.DataSourceProperties' of type [org.springframework.boot.autoconfigure.jdbc.DataSourceProperties] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:09 2023-05-29 03:44:09.616 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'dataSourceInitializer' of type [org.springframework.boot.autoconfigure.jdbc.DataSourceInitializer] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:09 2023-05-29 03:44:09.684 INFO main org.flywaydb.core.internal.util.logging.slf4j.Slf4jLog - [] - Flyway 4.2.0 by Boxfuse
2023-05-28 23:44:10 2023-05-29 03:44:10.310 INFO main org.flywaydb.core.internal.util.logging.slf4j.Slf4jLog - [] - Database: jdbc:postgresql://broadsea-atlasdb:5432/postgres (PostgreSQL 15.2)
2023-05-28 23:44:10 2023-05-29 03:44:10.468 INFO main org.flywaydb.core.internal.util.logging.slf4j.Slf4jLog - [] - Current version of schema "webapi": 2.13.0.20221027170000
2023-05-28 23:44:10 2023-05-29 03:44:10.468 WARN main org.flywaydb.core.internal.util.logging.slf4j.Slf4jLog - [] - outOfOrder mode is active. Migration of schema "webapi" may not be reproducible.
2023-05-28 23:44:10 2023-05-29 03:44:10.469 INFO main org.flywaydb.core.internal.util.logging.slf4j.Slf4jLog - [] - Schema "webapi" is up to date. No migration necessary.
2023-05-28 23:44:10 2023-05-29 03:44:10.471 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'flyway' of type [org.flywaydb.core.Flyway] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:10 2023-05-29 03:44:10.483 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'disabledSecurity' of type [org.ohdsi.webapi.shiro.management.DisabledSecurity] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:10 2023-05-29 03:44:10.492 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'noLockoutPolicy' of type [org.ohdsi.webapi.shiro.lockout.NoLockoutPolicy] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:10 2023-05-29 03:44:10.575 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'securityManager' of type [org.ohdsi.webapi.shiro.AtlasWebSecurityManager] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:10 2023-05-29 03:44:10.636 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'integrationGlobalProperties' of type [org.springframework.beans.factory.config.PropertiesFactoryBean] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:10 2023-05-29 03:44:10.637 INFO main org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - [] - Bean 'integrationGlobalProperties' of type [java.util.Properties] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2023-05-28 23:44:10 2023-05-29 03:44:10.925 INFO main org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer - [] - Tomcat initialized with port(s): 8080 (http)
2023-05-28 23:44:10 2023-05-29 03:44:10.947 INFO main org.apache.juli.logging.DirectJDKLog - [] - Initializing ProtocolHandler ["http-nio-8080"]
2023-05-28 23:44:10 2023-05-29 03:44:10.966 INFO main org.apache.juli.logging.DirectJDKLog - [] - Starting service [Tomcat]
2023-05-28 23:44:10 2023-05-29 03:44:10.966 INFO main org.apache.juli.logging.DirectJDKLog - [] - Starting Servlet Engine: Apache Tomcat/8.5.43
2023-05-28 23:44:11 2023-05-29 03:44:11.159 INFO localhost-startStop-1 org.apache.juli.logging.DirectJDKLog - [] - Initializing Spring embedded WebApplicationContext
2023-05-28 23:44:11 2023-05-29 03:44:11.159 INFO localhost-startStop-1 org.springframework.boot.context.embedded.EmbeddedWebApplicationContext - [] - Root WebApplicationContext: initialization completed in 4498 ms
2023-05-28 23:44:11 2023-05-29 03:44:11.839 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - datasource.url is: jdbc:postgresql://broadsea-atlasdb:5432/postgres
2023-05-28 23:44:11 2023-05-29 03:44:11.842 INFO localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - [] - Loaded JDBC driver: org.postgresql.Driver
2023-05-28 23:44:11 2023-05-29 03:44:11.846 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - driver loaded: org.postgresql.Driver
2023-05-28 23:44:11 2023-05-29 03:44:11.846 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - driver loaded: com.microsoft.sqlserver.jdbc.SQLServerDriver
2023-05-28 23:44:11 2023-05-29 03:44:11.847 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - error loading oracle.jdbc.driver.OracleDriver driver. oracle.jdbc.driver.OracleDriver
2023-05-28 23:44:11 2023-05-29 03:44:11.848 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - driver loaded: com.amazon.redshift.jdbc.Driver
2023-05-28 23:44:11 2023-05-29 03:44:11.848 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - error loading com.cloudera.impala.jdbc.Driver driver. com.cloudera.impala.jdbc.Driver
2023-05-28 23:44:11 2023-05-29 03:44:11.849 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - error loading net.starschema.clouddb.jdbc.BQDriver driver. net.starschema.clouddb.jdbc.BQDriver
2023-05-28 23:44:11 2023-05-29 03:44:11.850 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - error loading org.netezza.Driver driver. org.netezza.Driver
2023-05-28 23:44:11 2023-05-29 03:44:11.851 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - error loading com.simba.googlebigquery.jdbc42.Driver driver. com.simba.googlebigquery.jdbc42.Driver
2023-05-28 23:44:11 2023-05-29 03:44:11.852 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - error loading org.apache.hive.jdbc.HiveDriver driver. org.apache.hive.jdbc.HiveDriver
2023-05-28 23:44:11 2023-05-29 03:44:11.852 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - driver loaded: com.simba.spark.jdbc.Driver
2023-05-28 23:44:11 2023-05-29 03:44:11.853 INFO localhost-startStop-1 org.ohdsi.webapi.DataAccessConfig - [] - error loading net.snowflake.client.jdbc.SnowflakeDriver driver. net.snowflake.client.jdbc.SnowflakeDriver
2023-05-28 23:44:12 2023-05-29 03:44:12.011 INFO localhost-startStop-1 org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean - [] - Building JPA container EntityManagerFactory for persistence unit 'default'
2023-05-28 23:44:12 2023-05-29 03:44:12.039 INFO localhost-startStop-1 org.hibernate.jpa.internal.util.LogHelper - [] - HHH000204: Processing PersistenceUnitInfo [name: default]
2023-05-28 23:44:12 2023-05-29 03:44:12.154 INFO localhost-startStop-1 org.hibernate.Version - [] - HHH000412: Hibernate Core {5.4.2.Final}
2023-05-28 23:44:12 2023-05-29 03:44:12.327 INFO localhost-startStop-1 org.hibernate.annotations.common.reflection.java.JavaReflectionManager - [] - HCANN000001: Hibernate Commons Annotations {5.1.0.Final}
2023-05-28 23:44:12 2023-05-29 03:44:12.753 INFO localhost-startStop-1 org.hibernate.dialect.Dialect - [] - HHH000400: Using dialect: org.hibernate.dialect.PostgreSQLDialect
2023-05-28 23:44:13 2023-05-29 03:44:13.363 WARN localhost-startStop-1 org.hibernate.id.SequenceGenerator - [] - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceHiLoGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead.  See Hibernate Domain Model Mapping Guide for details.
2023-05-28 23:44:13 2023-05-29 03:44:13.377 WARN localhost-startStop-1 org.hibernate.id.SequenceGenerator - [] - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceHiLoGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead.  See Hibernate Domain Model Mapping Guide for details.
2023-05-28 23:44:13 2023-05-29 03:44:13.425 WARN localhost-startStop-1 org.hibernate.mapping.RootClass - [] - HHH000038: Composite-id class does not override equals(): org.ohdsi.webapi.conceptset.ConceptSetGenerationInfoKey
2023-05-28 23:44:13 2023-05-29 03:44:13.426 WARN localhost-startStop-1 org.hibernate.mapping.RootClass - [] - HHH000039: Composite-id class does not override hashCode(): org.ohdsi.webapi.conceptset.ConceptSetGenerationInfoKey
2023-05-28 23:44:14 2023-05-29 03:44:14.387 INFO localhost-startStop-1 org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator - [] - HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2023-05-28 23:44:14 2023-05-29 03:44:14.390 INFO localhost-startStop-1 org.springframework.orm.jpa.AbstractEntityManagerFactoryBean - [] - Initialized JPA EntityManagerFactory for persistence unit 'default'
2023-05-28 23:44:17 2023-05-29 03:44:17.282 INFO localhost-startStop-1 org.springframework.batch.core.repository.support.JobRepositoryFactoryBean - [] - No database type set, using meta data indicating: POSTGRES
2023-05-28 23:44:17 2023-05-29 03:44:17.619 INFO localhost-startStop-1 org.springframework.scheduling.concurrent.ExecutorConfigurationSupport - [] - Initializing ExecutorService
2023-05-28 23:44:17 2023-05-29 03:44:17.668 INFO localhost-startStop-1 org.springframework.scheduling.concurrent.ExecutorConfigurationSupport - [] - Initializing ExecutorService 'taskExecutor'
2023-05-28 23:44:18 2023-05-29 03:44:18.360 INFO localhost-startStop-1 org.springframework.boot.web.servlet.ServletRegistrationBean - [] - Mapping servlet: 'org.ohdsi.webapi.JerseyConfig' to [/*]
2023-05-28 23:44:18 2023-05-29 03:44:18.364 INFO localhost-startStop-1 org.springframework.boot.web.servlet.ServletRegistrationBean - [] - Mapping servlet: 'dispatcherServlet' to [/]
2023-05-28 23:44:18 2023-05-29 03:44:18.369 INFO localhost-startStop-1 org.springframework.boot.web.servlet.AbstractFilterRegistrationBean - [] - Mapping filter: 'characterEncodingFilter' to: [/*]
2023-05-28 23:44:18 2023-05-29 03:44:18.370 INFO localhost-startStop-1 org.springframework.boot.web.servlet.AbstractFilterRegistrationBean - [] - Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
2023-05-28 23:44:18 2023-05-29 03:44:18.371 INFO localhost-startStop-1 org.springframework.boot.web.servlet.AbstractFilterRegistrationBean - [] - Mapping filter: 'httpPutFormContentFilter' to: [/*]
2023-05-28 23:44:18 2023-05-29 03:44:18.371 INFO localhost-startStop-1 org.springframework.boot.web.servlet.AbstractFilterRegistrationBean - [] - Mapping filter: 'requestContextFilter' to: [/*]
2023-05-28 23:44:18 2023-05-29 03:44:18.371 INFO localhost-startStop-1 org.springframework.boot.web.servlet.AbstractFilterRegistrationBean - [] - Mapping filter: 'exceptionHandlerFilter' to: [/*]
2023-05-28 23:44:18 2023-05-29 03:44:18.371 INFO localhost-startStop-1 org.springframework.boot.web.servlet.AbstractFilterRegistrationBean - [] - Mapping filter: 'cacheFilter' to: [/*]
2023-05-28 23:44:18 2023-05-29 03:44:18.371 INFO localhost-startStop-1 org.springframework.boot.web.servlet.AbstractFilterRegistrationBean - [] - Mapping filter: 'corsFilter' to: [/*]
2023-05-28 23:44:18 2023-05-29 03:44:18.371 INFO localhost-startStop-1 org.springframework.boot.web.servlet.AbstractFilterRegistrationBean - [] - Mapping filter: 'shiroFilter' to: [/*]
2023-05-28 23:44:19 2023-05-29 03:44:19.549 INFO main org.ohdsi.webapi.executionengine.service.ScriptExecutionServiceImpl - [] - Invalidating execution engine based analyses
2023-05-28 23:44:21 2023-05-29 03:44:21.058 INFO main org.springframework.scheduling.concurrent.ExecutorConfigurationSupport - [] - Initializing ExecutorService
2023-05-28 23:44:21 2023-05-29 03:44:21.068 INFO main org.springframework.scheduling.concurrent.ExecutorConfigurationSupport - [] - Initializing ExecutorService 'taskScheduler'
2023-05-28 23:44:21 2023-05-29 03:44:21.152 WARN main org.springframework.aop.framework.CglibAopProxy - [] - Unable to proxy interface-implementing method [public final void com.odysseusinc.scheduler.service.BaseJobServiceImpl.setApplicationContext(org.springframework.context.ApplicationContext) throws org.springframework.beans.BeansException] because it is marked as final: Consider using interface-based JDK proxies instead!
2023-05-28 23:44:21 2023-05-29 03:44:21.152 INFO main org.springframework.aop.framework.CglibAopProxy - [] - Final method [public final void com.odysseusinc.scheduler.service.BaseJobServiceImpl.setApplicationContext(org.springframework.context.ApplicationContext) throws org.springframework.beans.BeansException] cannot get proxied via CGLIB: Calls to this method will NOT be routed to the target instance and might lead to NPEs against uninitialized fields in the proxy instance.
2023-05-28 23:44:21 2023-05-29 03:44:21.152 INFO main org.springframework.aop.framework.CglibAopProxy - [] - Final method [protected final com.cronutils.model.time.ExecutionTime com.odysseusinc.scheduler.service.BaseJobServiceImpl.getExecutionTime(com.odysseusinc.scheduler.model.ArachneJob)] cannot get proxied via CGLIB: Calls to this method will NOT be routed to the target instance and might lead to NPEs against uninitialized fields in the proxy instance.
2023-05-28 23:44:21 2023-05-29 03:44:21.267 INFO main org.flywaydb.core.internal.util.logging.slf4j.Slf4jLog - [] - Current version of schema "webapi": 2.13.0.20221027170000
2023-05-28 23:44:21 2023-05-29 03:44:21.267 WARN main org.flywaydb.core.internal.util.logging.slf4j.Slf4jLog - [] - outOfOrder mode is active. Migration of schema "webapi" may not be reproducible.
2023-05-28 23:44:21 2023-05-29 03:44:21.268 INFO main org.flywaydb.core.internal.util.logging.slf4j.Slf4jLog - [] - Schema "webapi" is up to date. No migration necessary.
2023-05-28 23:44:21 2023-05-29 03:44:21.843 INFO main org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter - [] - Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@33a10788: startup date [Mon May 29 03:44:06 UTC 2023]; root of context hierarchy
2023-05-28 23:44:22 2023-05-29 03:44:22.013 INFO main org.springframework.web.servlet.handler.AbstractUrlHandlerMapping - [] - Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2023-05-28 23:44:22 2023-05-29 03:44:22.013 INFO main org.springframework.web.servlet.handler.AbstractUrlHandlerMapping - [] - Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2023-05-28 23:44:22 2023-05-29 03:44:22.094 INFO main org.springframework.web.servlet.handler.AbstractUrlHandlerMapping - [] - Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2023-05-28 23:44:22 2023-05-29 03:44:22.336 INFO main org.springframework.ldap.core.support.AbstractContextSource - [] - Property 'userDn' not set - anonymous context will be used for read-write operations
2023-05-28 23:44:22 2023-05-29 03:44:22.424 INFO main org.springframework.ui.freemarker.SpringTemplateLoader - [] - SpringTemplateLoader for FreeMarker: using resource loader [org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@33a10788: startup date [Mon May 29 03:44:06 UTC 2023]; root of context hierarchy] and template loader path [classpath:/templates/]
2023-05-28 23:44:22 2023-05-29 03:44:22.424 INFO main org.springframework.web.servlet.view.freemarker.FreeMarkerConfigurer - [] - ClassTemplateLoader for Spring macros added to FreeMarker configuration
2023-05-28 23:44:23 2023-05-29 03:44:23.004 INFO main org.springframework.jmx.export.MBeanExporter - [] - Registering beans for JMX exposure on startup
2023-05-28 23:44:23 2023-05-29 03:44:23.018 INFO main org.springframework.jmx.export.MBeanExporter - [] - Bean with name 'taskExecutor' has been autodetected for JMX exposure
2023-05-28 23:44:23 2023-05-29 03:44:23.020 INFO main org.springframework.jmx.export.MBeanExporter - [] - Bean with name 'integrationMbeanExporter' has been autodetected for JMX exposure
2023-05-28 23:44:23 2023-05-29 03:44:23.026 INFO main org.springframework.jmx.export.MBeanExporter - [] - Located managed bean 'taskExecutor': registering with JMX server as MBean [org.ohdsi.webapi.util:name=taskExecutor,type=ManagedThreadPoolTaskExecutor]
2023-05-28 23:44:23 2023-05-29 03:44:23.060 INFO main org.springframework.jmx.export.MBeanExporter - [] - Located managed bean 'integrationMbeanExporter': registering with JMX server as MBean [org.springframework.integration.monitor:name=integrationMbeanExporter,type=IntegrationMBeanExporter]
2023-05-28 23:44:23 2023-05-29 03:44:23.086 INFO main org.springframework.jmx.export.MBeanExporter - [] - Registering beans for JMX exposure on startup
2023-05-28 23:44:23 2023-05-29 03:44:23.086 INFO main org.springframework.integration.monitor.IntegrationMBeanExporter - [] - Registering MessageChannel nullChannel
2023-05-28 23:44:23 2023-05-29 03:44:23.092 INFO main org.springframework.jmx.export.MBeanExporter - [] - Located managed bean 'org.springframework.integration:type=MessageChannel,name=nullChannel': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=nullChannel]
2023-05-28 23:44:23 2023-05-29 03:44:23.101 INFO main org.springframework.integration.monitor.IntegrationMBeanExporter - [] - Registering MessageChannel errorChannel
2023-05-28 23:44:23 2023-05-29 03:44:23.103 INFO main org.springframework.jmx.export.MBeanExporter - [] - Located managed bean 'org.springframework.integration:type=MessageChannel,name=errorChannel': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=errorChannel]
2023-05-28 23:44:23 2023-05-29 03:44:23.151 INFO main org.springframework.integration.monitor.IntegrationMBeanExporter - [] - Registering MessageHandler errorLogger
2023-05-28 23:44:23 2023-05-29 03:44:23.153 INFO main org.springframework.jmx.export.MBeanExporter - [] - Located managed bean 'org.springframework.integration:type=MessageHandler,name=errorLogger,bean=internal': registering with JMX server as MBean [org.springframework.integration:type=MessageHandler,name=errorLogger,bean=internal]
2023-05-28 23:44:23 2023-05-29 03:44:23.185 INFO main org.springframework.context.support.DefaultLifecycleProcessor$LifecycleGroup - [] - Starting beans in phase 0
2023-05-28 23:44:23 2023-05-29 03:44:23.186 INFO main org.springframework.integration.endpoint.EventDrivenConsumer - [] - Adding {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
2023-05-28 23:44:23 2023-05-29 03:44:23.186 INFO main org.springframework.integration.channel.AbstractSubscribableChannel - [] - Channel 'application:default:8080.errorChannel' has 1 subscriber(s).
2023-05-28 23:44:23 2023-05-29 03:44:23.187 INFO main org.springframework.integration.endpoint.AbstractEndpoint - [] - started _org.springframework.integration.errorLogger
2023-05-28 23:44:23 2023-05-29 03:44:23.187 INFO main org.springframework.context.support.DefaultLifecycleProcessor$LifecycleGroup - [] - Starting beans in phase 2147483647
2023-05-28 23:44:23 2023-05-29 03:44:23.206 INFO taskScheduler-1 org.ohdsi.webapi.tag.TagService - [] - Starting tags statistics refreshing
2023-05-28 23:44:23 2023-05-29 03:44:23.212 INFO main org.apache.juli.logging.DirectJDKLog - [] - Starting ProtocolHandler ["http-nio-8080"]
2023-05-28 23:44:23 2023-05-29 03:44:23.223 INFO main org.apache.juli.logging.DirectJDKLog - [] - Using a shared selector for servlet write/read
2023-05-28 23:44:23 2023-05-29 03:44:23.248 INFO main org.springframework.boot.context.embedded.tomcat.TomcatEmbeddedServletContainer - [] - Tomcat started on port(s): 8080 (http)
2023-05-28 23:44:23 2023-05-29 03:44:23.257 INFO main org.springframework.boot.StartupInfoLogger - [] - Started WebApi in 17.166 seconds (JVM running for 18.756)
2023-05-28 23:44:23 2023-05-29 03:44:23.269 INFO taskScheduler-3 org.ohdsi.webapi.executionengine.service.ScriptExecutionServiceImpl - [] - Invalidating execution engine based analyses
2023-05-28 23:44:23 2023-05-29 03:44:23.422 INFO taskScheduler-1 org.ohdsi.webapi.tag.TagService - [] - Finishing tags statistics refreshing
2023-05-28 23:44:42 2023-05-29 03:44:42.650 WARN http-nio-8080-exec-1 org.glassfish.jersey.server.ApplicationHandler - [] - Component of class interface org.ohdsi.webapi.service.IRAnalysisResource cannot be instantiated and will be ignored.
2023-05-28 23:44:43 2023-05-29 03:44:43.032 WARN http-nio-8080-exec-1 org.glassfish.jersey.internal.Errors - [] - The following warnings have been detected: WARNING: The (sub)resource method createAnalysis in org.ohdsi.webapi.service.IRAnalysisResource contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getIRAnalysisList in org.ohdsi.webapi.service.IRAnalysisResource contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method list in org.ohdsi.webapi.feanalysis.FeAnalysisController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method createAnalysis in org.ohdsi.webapi.feanalysis.FeAnalysisController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method createConceptSet in org.ohdsi.webapi.service.ConceptSetService contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getConceptSets in org.ohdsi.webapi.service.ConceptSetService contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method create in org.ohdsi.webapi.pathway.PathwayController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method list in org.ohdsi.webapi.pathway.PathwayController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method create in org.ohdsi.webapi.reusable.ReusableController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method page in org.ohdsi.webapi.reusable.ReusableController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getResources in org.ohdsi.webapi.i18n.I18nController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method list in org.ohdsi.webapi.job.NotificationController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getInfo in org.ohdsi.webapi.info.InfoService contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method create in org.ohdsi.webapi.cohortcharacterization.CcController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method list in org.ohdsi.webapi.cohortcharacterization.CcController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getCohortDefinitionList in org.ohdsi.webapi.service.CohortDefinitionService contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method createCohortDefinition in org.ohdsi.webapi.service.CohortDefinitionService contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getPermissions in org.ohdsi.webapi.security.PermissionController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method create in org.ohdsi.webapi.tag.TagController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method list in org.ohdsi.webapi.tag.TagController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method createJob in org.ohdsi.webapi.user.importer.UserImportJobController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method listJobs in org.ohdsi.webapi.user.importer.UserImportJobController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method createEstimation in org.ohdsi.webapi.estimation.EstimationController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getAnalysisList in org.ohdsi.webapi.estimation.EstimationController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method createStudy in org.ohdsi.webapi.service.FeasibilityService contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getFeasibilityStudyList in org.ohdsi.webapi.service.FeasibilityService contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method createSource in org.ohdsi.webapi.source.SourceController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method createAnalysis in org.ohdsi.webapi.prediction.PredictionController contains empty path annotation.
2023-05-28 23:44:43 WARNING: The (sub)resource method getAnalysisList in org.ohdsi.webapi.prediction.PredictionController contains empty path annotation.
2023-05-28 23:44:43 
2023-05-28 23:54:23 2023-05-29 03:54:23.317 INFO taskScheduler-4 org.ohdsi.webapi.executionengine.service.ScriptExecutionServiceImpl - [] - Invalidating execution engine based analyses
2023-05-28 23:54:23 2023-05-29 03:54:23.423 INFO taskScheduler-20 org.ohdsi.webapi.tag.TagService - [] - Starting tags statistics refreshing
2023-05-28 23:54:23 2023-05-29 03:54:23.591 INFO taskScheduler-20 org.ohdsi.webapi.tag.TagService - [] - Finishing tags statistics refreshing
2023-05-28 23:58:02 2023-05-29 03:58:02.790 INFO http-nio-8080-exec-11 org.ohdsi.webapi.service.CohortGenerationService - [] - Beginning generate cohort for cohort definition id: 1
2023-05-28 23:58:02 2023-05-29 03:58:02.901 INFO taskExecutor-1 org.springframework.batch.core.launch.support.SimpleJobLauncher$1 - [] - Job: [SimpleJob: [name=generateCohort]] launched with the following parameters: [{jobName=Generating cohort 1 : OHDSI Eunomia Demo Database (EUNOMIA), target_database_schema=demo_cdm_results, sessionId=gx3tg36w, cohort_definition_id=1, source_id=1, generate_stats=true, time=1685332682812, jobAuthor=anonymous}]
2023-05-28 23:58:03 2023-05-29 03:58:03.044 INFO taskExecutor-1 org.springframework.batch.core.job.SimpleStepHandler - [] - Executing step: [cohortDefinition.generateCohort]
2023-05-28 23:58:06 2023-05-29 03:58:06.014 INFO taskExecutor-1 org.ohdsi.webapi.generationcache.GenerationCacheHelper - [] - Computes cache if absent for type = COHORT, design = -61503765, source id = 1
2023-05-28 23:58:06 2023-05-29 03:58:06.039 INFO taskExecutor-1 org.ohdsi.webapi.generationcache.GenerationCacheHelper - [] - Retrieves or invalidates cache for cohort id = 1
2023-05-28 23:58:06 2023-05-29 03:58:06.065 INFO taskExecutor-1 org.ohdsi.webapi.generationcache.GenerationCacheHelper - [] - Cache is absent for cohort id = 1. Calculating with design hash = -61503765
2023-05-28 23:58:06 2023-05-29 03:58:06.314 INFO taskExecutor-1 org.ohdsi.webapi.generationcache.CohortGenerationCacheProvider - [] - Checksum of Generation cache for designHash = -61503765 has been calculated in 20 milliseconds
2023-05-28 23:58:06 2023-05-29 03:58:06.320 INFO taskExecutor-1 org.ohdsi.webapi.generationcache.GenerationCacheServiceImpl - [] - Cached results of COHORT with design hash = -61503765
2023-05-28 23:58:06 2023-05-29 03:58:06.320 INFO taskExecutor-1 org.ohdsi.webapi.generationcache.GenerationCacheHelper - [] - Finished computation cache if absent for cohort id = 1
2023-05-28 23:58:07 2023-05-29 03:58:07.497 INFO taskExecutor-1 org.springframework.batch.core.launch.support.SimpleJobLauncher$1 - [] - Job: [SimpleJob: [name=generateCohort]] completed with the following parameters: [{jobName=Generating cohort 1 : OHDSI Eunomia Demo Database (EUNOMIA), target_database_schema=demo_cdm_results, sessionId=gx3tg36w, cohort_definition_id=1, source_id=1, generate_stats=true, time=1685332682812, jobAuthor=anonymous}] and the following status: [COMPLETED]
2023-05-28 23:58:22 2023-05-29 03:58:22.430 INFO http-nio-8080-exec-2 org.ohdsi.webapi.service.CohortGenerationService - [] - Beginning generate cohort for cohort definition id: 1
2023-05-28 23:58:22 2023-05-29 03:58:22.484 INFO taskExecutor-2 org.springframework.batch.core.launch.support.SimpleJobLauncher$1 - [] - Job: [SimpleJob: [name=generateCohort]] launched with the following parameters: [{jobName=Generating cohort 1 : Databricks Demo (DATABRICKS_DEMO), target_database_schema=demo_cdm_ach_res, sessionId=r5au0dha, cohort_definition_id=1, source_id=2, generate_stats=true, time=1685332702431, jobAuthor=anonymous}]
2023-05-28 23:58:22 2023-05-29 03:58:22.599 INFO taskExecutor-2 org.springframework.batch.core.job.SimpleStepHandler - [] - Executing step: [cohortDefinition.generateCohort]
2023-05-29 00:04:23 2023-05-29 04:04:23.361 INFO taskScheduler-4 org.ohdsi.webapi.executionengine.service.ScriptExecutionServiceImpl - [] - Invalidating execution engine based analyses
2023-05-29 00:04:23 2023-05-29 04:04:23.591 INFO taskScheduler-7 org.ohdsi.webapi.tag.TagService - [] - Starting tags statistics refreshing
2023-05-29 00:04:23 2023-05-29 04:04:23.753 INFO taskScheduler-7 org.ohdsi.webapi.tag.TagService - [] - Finishing tags statistics refreshing