root@7f305c2df235:/var/log/supervisor# head -2000 *stdout*

. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v1.5.2.RELEASE)

2019-08-07 11:26:25.665 INFO localhost-startStop-1 org.ohdsi.webapi.WebApi - - Starting WebApi on 7f305c2df235 with PID 11 (/usr/local/tomcat/webapps/WebAPI/WEB-INF/classes started by root in /usr/local/tomcat/webapps)
2019-08-07 11:26:25.676 DEBUG localhost-startStop-1 org.ohdsi.webapi.WebApi - - Running with Spring Boot v1.5.2.RELEASE, Spring v4.3.7.RELEASE
2019-08-07 11:26:25.677 INFO localhost-startStop-1 org.ohdsi.webapi.WebApi - - The following profiles are active: default
2019-08-07 11:26:25.789 INFO localhost-startStop-1 org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext - - Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@31aad3ca: startup date [Wed Aug 07 11:26:25 UTC 2019]; root of context hierarchy
2019-08-07 11:26:26.049 INFO background-preinit org.hibernate.validator.internal.util.Version - - HV000001: Hibernate Validator 5.3.4.Final
2019-08-07 11:26:31.501 INFO localhost-startStop-1 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]]
2019-08-07 11:26:31.948 INFO localhost-startStop-1 org.springframework.integration.config.IntegrationRegistrar - - No bean named 'integrationHeaderChannelRegistry' has been explicitly defined. Therefore, a default DefaultHeaderChannelRegistry will be created.
2019-08-07 11:26:32.703 WARN localhost-startStop-1 org.springframework.context.annotation.ConfigurationClassEnhancer - - @Bean method ScopeConfiguration.stepScope is non-static and returns an object assignable to Spring's BeanFactoryPostProcessor interface. This will result in a failure to process annotations such as @Autowired, @Resource and @PostConstruct within the method's declaring @Configuration class. Add the 'static' modifier to this method to avoid these container lifecycle issues; see @Bean javadoc for complete details.
2019-08-07 11:26:32.735 WARN localhost-startStop-1 org.springframework.context.annotation.ConfigurationClassEnhancer - - @Bean method ScopeConfiguration.jobScope is non-static and returns an object assignable to Spring's BeanFactoryPostProcessor interface. This will result in a failure to process annotations such as @Autowired, @Resource and @PostConstruct within the method's declaring @Configuration class. Add the 'static' modifier to this method to avoid these container lifecycle issues; see @Bean javadoc for complete details.
2019-08-07 11:26:32.780 INFO localhost-startStop-1 org.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor - - No bean named 'errorChannel' has been explicitly defined. Therefore, a default PublishSubscribeChannel will be created.
2019-08-07 11:26:32.790 INFO localhost-startStop-1 org.springframework.integration.config.DefaultConfiguringBeanFactoryPostProcessor - - No bean named 'taskScheduler' has been explicitly defined. Therefore, a default ThreadPoolTaskScheduler will be created.
2019-08-07 11:26:32.847 INFO localhost-startStop-1 org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor - - JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2019-08-07 11:26:32.990 INFO localhost-startStop-1 org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - - Bean 'converterConfiguration' of type [org.ohdsi.webapi.ConverterConfiguration$$EnhancerBySpringCGLIB$$6d2b82fe] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2019-08-07 11:26:33.008 INFO localhost-startStop-1 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)
2019-08-07 11:26:33.654 INFO localhost-startStop-1 org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - - Bean 'org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration' of type [org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration$$EnhancerBySpringCGLIB$$6905bb2] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2019-08-07 11:26:33.783 INFO localhost-startStop-1 org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - - Bean 'shiroConfiguration' of type [org.ohdsi.webapi.ShiroConfiguration$$EnhancerBySpringCGLIB$$4afec1ad] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2019-08-07 11:26:33.817 INFO localhost-startStop-1 org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - - Bean 'flywayConfig' of type [org.ohdsi.webapi.FlywayConfig$$EnhancerBySpringCGLIB$$9340e06] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2019-08-07 11:26:34.835 INFO localhost-startStop-1 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)
2019-08-07 11:26:34.855 INFO localhost-startStop-1 org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - - Bean 'org.springframework.boot.autoconfigure.jdbc.DataSourceAutoConfiguration' of type [org.springframework.boot.autoconfigure.jdbc.DataSourceAutoConfiguration$$EnhancerBySpringCGLIB$$e883ff12] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2019-08-07 11:26:35.032 INFO localhost-startStop-1 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)
2019-08-07 11:26:35.062 INFO localhost-startStop-1 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)
2019-08-07 11:26:35.263 INFO localhost-startStop-1 org.flywaydb.core.internal.util.VersionPrinter - - Flyway 4.2.0 by Boxfuse
2019-08-07 11:26:35.525 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.DbSupportFactory - - Database: jdbc:postgresql://10.65.4.5:5432/ohdsi (PostgreSQL 10.8)
2019-08-07 11:26:35.640 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.021 ms parse <unnamed>: BEGIN
2019-08-07 11:26:35.644 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: BEGIN
2019-08-07 11:26:35.645 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.013 ms execute <unnamed>: BEGIN
2019-08-07 11:26:35.645 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.025 ms parse <unnamed>: RESET ROLE
2019-08-07 11:26:35.645 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: RESET ROLE
2019-08-07 11:26:35.645 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.008 ms execute <unnamed>: RESET ROLE
2019-08-07 11:26:35.850 INFO localhost-startStop-1 org.flywaydb.core.internal.command.DbMigrate - - Current version of schema "ohdsi": 2.6.0.20181009115500
2019-08-07 11:26:35.851 WARN localhost-startStop-1 org.flywaydb.core.internal.command.DbMigrate - - outOfOrder mode is active. Migration of schema "ohdsi" may not be reproducible.
2019-08-07 11:26:35.853 INFO localhost-startStop-1 org.flywaydb.core.internal.command.DbMigrate - - Schema "ohdsi" is up to date. No migration necessary.
2019-08-07 11:26:35.854 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.012 ms parse <unnamed>: BEGIN
2019-08-07 11:26:35.854 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.005 ms bind <unnamed>: BEGIN
2019-08-07 11:26:35.859 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.013 ms execute <unnamed>: BEGIN
2019-08-07 11:26:35.859 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.010 ms parse <unnamed>: RESET ROLE
2019-08-07 11:26:35.859 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: RESET ROLE
2019-08-07 11:26:35.860 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.010 ms execute <unnamed>: RESET ROLE
2019-08-07 11:26:35.865 INFO localhost-startStop-1 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)
2019-08-07 11:26:35.874 INFO localhost-startStop-1 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)
2019-08-07 11:26:35.896 INFO localhost-startStop-1 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)
2019-08-07 11:26:35.987 INFO localhost-startStop-1 org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker - - Bean 'securityManager' of type [org.ohdsi.webapi.shiro.lockout.LockoutWebSecurityManager] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2019-08-07 11:26:36.061 INFO localhost-startStop-1 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)
2019-08-07 11:26:36.065 INFO localhost-startStop-1 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)
2019-08-07 11:26:36.112 INFO localhost-startStop-1 org.springframework.web.context.ContextLoader - - Root WebApplicationContext: initialization completed in 10323 ms
2019-08-07 11:26:37.565 INFO localhost-startStop-1 org.springframework.boot.web.servlet.ServletRegistrationBean - - Mapping servlet: 'org.ohdsi.webapi.JerseyConfig' to [/*]
2019-08-07 11:26:37.567 INFO localhost-startStop-1 org.springframework.boot.web.servlet.ServletRegistrationBean - - Mapping servlet: 'dispatcherServlet' to [/]
2019-08-07 11:26:37.568 INFO localhost-startStop-1 org.springframework.boot.web.servlet.FilterRegistrationBean - - Mapping filter: 'errorPageFilter' to: [/*]
2019-08-07 11:26:37.569 INFO localhost-startStop-1 org.springframework.boot.web.servlet.FilterRegistrationBean - - Mapping filter: 'characterEncodingFilter' to: [/*]
2019-08-07 11:26:37.569 INFO localhost-startStop-1 org.springframework.boot.web.servlet.FilterRegistrationBean - - Mapping filter: 'hiddenHttpMethodFilter' to: [/*]
2019-08-07 11:26:37.569 INFO localhost-startStop-1 org.springframework.boot.web.servlet.FilterRegistrationBean - - Mapping filter: 'httpPutFormContentFilter' to: [/*]
2019-08-07 11:26:37.569 INFO localhost-startStop-1 org.springframework.boot.web.servlet.FilterRegistrationBean - - Mapping filter: 'requestContextFilter' to: [/*]
2019-08-07 11:26:37.569 INFO localhost-startStop-1 org.springframework.boot.web.servlet.FilterRegistrationBean - - Mapping filter: 'corsFilter' to: [/*]
2019-08-07 11:26:37.573 INFO localhost-startStop-1 org.springframework.boot.web.servlet.FilterRegistrationBean - - Mapping filter: 'shiroFilter' to: [/*]
2019-08-07 11:26:37.675 INFO localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Loaded JDBC driver: org.postgresql.Driver
driver loaded: org.postgresql.Driver
driver loaded: com.microsoft.sqlserver.jdbc.SQLServerDriver
error loading oracle.jdbc.driver.OracleDriver driver.
driver loaded: com.amazon.redshift.jdbc.Driver
error loading com.cloudera.impala.jdbc41.Driver driver.
error loading net.starschema.clouddb.jdbc.BQDriver driver.
error loading org.netezza.Driver driver.
2019-08-07 11:26:38.279 INFO localhost-startStop-1 org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean - - Building JPA container EntityManagerFactory for persistence unit 'default'
2019-08-07 11:26:38.312 INFO localhost-startStop-1 org.hibernate.jpa.internal.util.LogHelper - - HHH000204: Processing PersistenceUnitInfo [
name: default
...]
2019-08-07 11:26:38.482 INFO localhost-startStop-1 org.hibernate.Version - - HHH000412: Hibernate Core {5.0.12.Final}
2019-08-07 11:26:38.486 INFO localhost-startStop-1 org.hibernate.cfg.Environment - - HHH000206: hibernate.properties not found
2019-08-07 11:26:38.489 INFO localhost-startStop-1 org.hibernate.cfg.Environment - - HHH000021: Bytecode provider name : javassist
2019-08-07 11:26:38.631 INFO localhost-startStop-1 org.hibernate.annotations.common.Version - - HCANN000001: Hibernate Commons Annotations {5.0.1.Final}
2019-08-07 11:26:39.234 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:39.274 INFO localhost-startStop-1 org.hibernate.dialect.Dialect - - HHH000400: Using dialect: org.hibernate.dialect.PostgreSQLDialect
2019-08-07 11:26:39.877 INFO localhost-startStop-1 org.hibernate.engine.jdbc.env.internal.LobCreatorBuilderImpl - - HHH000424: Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException
2019-08-07 11:26:39.884 INFO localhost-startStop-1 org.hibernate.type.BasicTypeRegistry - - HHH000270: Type registration [java.util.UUID] overrides previous : org.hibernate.type.UUIDBinaryType@12c8b963
2019-08-07 11:26:40.476 WARN localhost-startStop-1 org.hibernate.boot.internal.IdGeneratorInterpreterImpl - - HHH000392: Hibernate does not support SequenceGenerator.initialValue() unless 'hibernate.id.new_generator_mappings' set
2019-08-07 11:26:40.493 WARN localhost-startStop-1 org.hibernate.boot.internal.IdGeneratorInterpreterImpl - - HHH000392: Hibernate does not support SequenceGenerator.initialValue() unless 'hibernate.id.new_generator_mappings' set
2019-08-07 11:26:40.501 WARN localhost-startStop-1 org.hibernate.boot.internal.IdGeneratorInterpreterImpl - - HHH000392: Hibernate does not support SequenceGenerator.initialValue() unless 'hibernate.id.new_generator_mappings' set
2019-08-07 11:26:40.513 WARN localhost-startStop-1 org.hibernate.boot.internal.IdGeneratorInterpreterImpl - - HHH000392: Hibernate does not support SequenceGenerator.initialValue() unless 'hibernate.id.new_generator_mappings' set
2019-08-07 11:26:40.557 WARN localhost-startStop-1 org.hibernate.boot.internal.IdGeneratorInterpreterImpl - - HHH000392: Hibernate does not support SequenceGenerator.initialValue() unless 'hibernate.id.new_generator_mappings' set
2019-08-07 11:26:40.748 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.755 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead. See Hibernate Domain Model Mapping Guide for details.
2019-08-07 11:26:40.755 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.755 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead. See Hibernate Domain Model Mapping Guide for details.
2019-08-07 11:26:40.756 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.756 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.756 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.756 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.761 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.761 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.762 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.762 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.762 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.763 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.763 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.763 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.763 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.764 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead. See Hibernate Domain Model Mapping Guide for details.
2019-08-07 11:26:40.764 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.764 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.765 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead. See Hibernate Domain Model Mapping Guide for details.
2019-08-07 11:26:40.765 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.765 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead. See Hibernate Domain Model Mapping Guide for details.
2019-08-07 11:26:40.766 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.766 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.766 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.767 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.767 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead. See Hibernate Domain Model Mapping Guide for details.
2019-08-07 11:26:40.767 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - HHH90000014: Found use of deprecated [org.hibernate.id.SequenceGenerator] sequence-based id generator; use org.hibernate.id.enhanced.SequenceStyleGenerator instead. See Hibernate Domain Model Mapping Guide for details.
2019-08-07 11:26:40.772 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.773 WARN localhost-startStop-1 org.hibernate.orm.deprecation - - 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.
2019-08-07 11:26:40.842 WARN localhost-startStop-1 org.hibernate.mapping.RootClass - - HHH000038: Composite-id class does not override equals(): org.ohdsi.webapi.conceptset.ConceptSetGenerationInfoKey
2019-08-07 11:26:40.845 WARN localhost-startStop-1 org.hibernate.mapping.RootClass - - HHH000039: Composite-id class does not override hashCode(): org.ohdsi.webapi.conceptset.ConceptSetGenerationInfoKey
2019-08-07 11:26:42.646 INFO localhost-startStop-1 org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean - - Initialized JPA EntityManagerFactory for persistence unit 'default'
2019-08-07 11:26:43.531 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.676 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.677 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.678 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.678 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.678 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.701 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.705 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.715 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.715 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.757 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.757 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.916 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.917 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.921 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.921 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.922 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.923 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.923 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.924 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.971 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.977 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.982 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.983 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.989 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.989 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:43.990 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:43.991 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.052 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.053 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.054 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.054 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.055 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.056 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.057 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.057 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.115 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.116 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.122 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.123 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.123 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.123 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.125 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.126 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.198 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.199 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.200 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.200 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.204 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.205 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.206 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.207 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.247 INFO localhost-startStop-1 org.hibernate.hql.internal.QueryTranslatorFactoryInitiator - - HHH000397: Using ASTQueryTranslatorFactory
2019-08-07 11:26:44.626 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.627 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.629 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.629 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.632 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.633 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.634 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.635 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.800 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.801 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.801 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.802 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.808 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.809 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.810 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.810 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.845 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.846 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.849 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.849 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.849 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.849 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.850 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.850 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.905 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.905 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.906 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.906 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.907 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.907 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.908 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.908 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.965 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.965 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.969 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.969 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.969 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.970 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:44.971 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:44.971 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.027 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.027 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.028 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.032 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.033 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.033 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.042 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.043 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.093 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.096 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.096 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.096 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.097 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.097 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.098 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.098 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.215 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.216 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.217 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.217 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.217 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.221 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.221 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.222 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.272 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.272 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.273 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.273 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.274 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.274 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.281 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.281 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.333 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [null]: PROPAGATION_REQUIRES_NEW,ISOLATION_DEFAULT
2019-08-07 11:26:45.333 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@6e346026] for JPA transaction
2019-08-07 11:26:45.340 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:45.350 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@55e21a05]
Hibernate: select cohortgene0_.id as id1_16_, cohortgene0_.source_id as source_i2_16_, cohortgene0_.execution_duration as executio3_16_, cohortgene0_.fail_message as fail_mes4_16_, cohortgene0_.include_features as include_5_16_, cohortgene0_.is_valid as is_valid6_16_, cohortgene0_.person_count as person_c7_16_, cohortgene0_.record_count as record_c8_16_, cohortgene0_.start_time as start_ti9_16_, cohortgene0_.status as status10_16_ from ohdsi.cohort_generation_info cohortgene0_ where cohortgene0_.status in (? , ?)
2019-08-07 11:26:45.456 INFO localhost-startStop-1 org.springframework.batch.core.repository.support.JobRepositoryFactoryBean - - No database type set, using meta data indicating: POSTGRES
2019-08-07 11:26:45.749 INFO localhost-startStop-1 org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor - - Initializing ExecutorService
2019-08-07 11:26:45.769 INFO localhost-startStop-1 org.springframework.scheduling.concurrent.ThreadPoolTaskExecutor - - Initializing ExecutorService 'taskExecutor'
2019-08-07 11:26:45.782 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Found thread-bound EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@6e346026] for JPA transaction
2019-08-07 11:26:45.783 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Participating in existing transaction
2019-08-07 11:26:45.783 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:45.784 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@6e346026]
2019-08-07 11:26:45.785 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@6e346026] after transaction
2019-08-07 11:26:45.785 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.786 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:45.786 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.063 ms parse S_1: COMMIT
2019-08-07 11:26:45.786 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:45.786 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.018 ms bind S_1: COMMIT
2019-08-07 11:26:45.786 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:45.786 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.017 ms execute S_1: COMMIT
2019-08-07 11:26:45.819 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.824 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.826 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.827 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.828 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.833 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.834 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.834 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.932 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.932 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.937 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.937 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.938 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.938 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:45.938 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:45.939 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.146 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [null]: PROPAGATION_REQUIRES_NEW,ISOLATION_DEFAULT
2019-08-07 11:26:46.147 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@33f30ed1] for JPA transaction
2019-08-07 11:26:46.147 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:46.157 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@549fdc01]
Hibernate: select ccgenerati0_.id as id1_4_, ccgenerati0_.end_time as end_time2_4_, ccgenerati0_.exit_message as exit_mes3_4_, ccgenerati0_.created_by_id as created_8_4_, ccgenerati0_.design as design4_4_, ccgenerati0_.hash_code as hash_cod5_4_, ccgenerati0_.source_id as source_i9_4_, ccgenerati0_.start_time as start_ti6_4_, ccgenerati0_.status as status7_4_, ccgenerati0_.cc_id as cc_id10_4_ from ohdsi.cc_generation ccgenerati0_ where ccgenerati0_.status in (? , ? , ? , ?)
2019-08-07 11:26:46.177 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:46.177 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@33f30ed1]
2019-08-07 11:26:46.178 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@33f30ed1] after transaction
2019-08-07 11:26:46.178 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.178 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:46.178 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.011 ms parse S_1: COMMIT
2019-08-07 11:26:46.178 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:46.178 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.011 ms bind S_1: COMMIT
2019-08-07 11:26:46.178 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:46.179 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.010 ms execute S_1: COMMIT
2019-08-07 11:26:46.370 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.372 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.381 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.381 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.382 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.382 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.383 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.384 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.448 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.452 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.453 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.453 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.453 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.454 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.454 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.454 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.719 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.724 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.729 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.730 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.730 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.730 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.731 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.731 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.799 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.804 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.806 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.806 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.806 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.807 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.811 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.816 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.980 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.984 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.992 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.993 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.993 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.993 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:46.994 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:46.996 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.066 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:47.067 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.069 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:47.069 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.069 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:47.070 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.070 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:47.070 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.358 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.ohdsi.webapi.service.SourceService.getSources]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT; ''
2019-08-07 11:26:47.359 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@4cdf9aef] for JPA transaction
2019-08-07 11:26:47.359 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.370 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@6b2e2667]
2019-08-07 11:26:47.384 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Found thread-bound EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@4cdf9aef] for JPA transaction
2019-08-07 11:26:47.388 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Participating in existing transaction
Hibernate: select source0_.SOURCE_ID as SOURCE_I1_49_, source0_.keytab_name as keytab_n2_49_, source0_.krb_admin_server as krb_admi3_49_, source0_.krb_auth_method as krb_auth4_49_, source0_.krb_keytab as krb_keyt5_49_, source0_.password as password6_49_, source0_.SOURCE_CONNECTION as SOURCE_C7_49_, source0_.SOURCE_DIALECT as SOURCE_D8_49_, source0_.SOURCE_KEY as SOURCE_K9_49_, source0_.SOURCE_NAME as SOURCE_10_49_, source0_.username as usernam11_49_ from ohdsi.source source0_
Hibernate: select daimons0_.SOURCE_ID as SOURCE_I5_50_0_, daimons0_.SOURCE_DAIMON_ID as SOURCE_D1_50_0_, daimons0_.SOURCE_DAIMON_ID as SOURCE_D1_50_1_, daimons0_.DAIMON_TYPE as DAIMON_T2_50_1_, daimons0_.PRIORITY as PRIORITY3_50_1_, daimons0_.SOURCE_ID as SOURCE_I5_50_1_, daimons0_.TABLE_QUALIFIER as TABLE_QU4_50_1_ from ohdsi.source_daimon daimons0_ where daimons0_.SOURCE_ID=?
2019-08-07 11:26:47.449 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.452 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@4cdf9aef]
2019-08-07 11:26:47.470 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@4cdf9aef] after transaction
2019-08-07 11:26:47.471 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.471 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.471 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.043 ms parse S_1: COMMIT
2019-08-07 11:26:47.471 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.471 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.025 ms bind S_1: COMMIT
2019-08-07 11:26:47.471 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.471 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.037 ms execute S_1: COMMIT
2019-08-07 11:26:47.480 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
Hibernate: select source0_.SOURCE_ID as SOURCE_I1_49_, source0_.keytab_name as keytab_n2_49_, source0_.krb_admin_server as krb_admi3_49_, source0_.krb_auth_method as krb_auth4_49_, source0_.krb_keytab as krb_keyt5_49_, source0_.password as password6_49_, source0_.SOURCE_CONNECTION as SOURCE_C7_49_, source0_.SOURCE_DIALECT as SOURCE_D8_49_, source0_.SOURCE_KEY as SOURCE_K9_49_, source0_.SOURCE_NAME as SOURCE_10_49_, source0_.username as usernam11_49_ from ohdsi.source source0_ where source0_.SOURCE_KEY=?
2019-08-07 11:26:47.494 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
Hibernate: select daimons0_.SOURCE_ID as SOURCE_I5_50_0_, daimons0_.SOURCE_DAIMON_ID as SOURCE_D1_50_0_, daimons0_.SOURCE_DAIMON_ID as SOURCE_D1_50_1_, daimons0_.DAIMON_TYPE as DAIMON_T2_50_1_, daimons0_.PRIORITY as PRIORITY3_50_1_, daimons0_.SOURCE_ID as SOURCE_I5_50_1_, daimons0_.TABLE_QUALIFIER as TABLE_QU4_50_1_ from ohdsi.source_daimon daimons0_ where daimons0_.SOURCE_ID=?
2019-08-07 11:26:47.507 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.569 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.getLastJobExecution]: PROPAGATION_REQUIRES_NEW,ISOLATION_READ_COMMITTED
2019-08-07 11:26:47.570 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@5c6bd485] for JPA transaction
2019-08-07 11:26:47.570 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.577 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DataSourceUtils - - Changing isolation level of JDBC Connection [org.postgresql.jdbc4.Jdbc4Connection@17a0f34a] to 2
2019-08-07 11:26:47.578 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@12cd9e21]
2019-08-07 11:26:47.585 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.586 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = ? and JOB_KEY = ?]
2019-08-07 11:26:47.594 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.012 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:47.594 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:47.594 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:47.594 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.513 ms parse <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.595 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.332 ms bind <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.595 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.028 ms execute <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.595 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.595 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@5c6bd485]
2019-08-07 11:26:47.595 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@5c6bd485] after transaction
2019-08-07 11:26:47.596 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.596 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.596 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.059 ms parse <unnamed>: SHOW TRANSACTION ISOLATION LEVEL
2019-08-07 11:26:47.596 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.596 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.007 ms bind <unnamed>: SHOW TRANSACTION ISOLATION LEVEL
2019-08-07 11:26:47.597 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.597 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.032 ms execute <unnamed>: SHOW TRANSACTION ISOLATION LEVEL
2019-08-07 11:26:47.597 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.597 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.017 ms parse S_1: COMMIT
2019-08-07 11:26:47.597 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.597 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.009 ms bind S_1: COMMIT
2019-08-07 11:26:47.598 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.598 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.008 ms execute S_1: COMMIT
2019-08-07 11:26:47.600 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.createJobExecution]: PROPAGATION_REQUIRES_NEW,ISOLATION_READ_COMMITTED
2019-08-07 11:26:47.601 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@a5f1829] for JPA transaction
2019-08-07 11:26:47.604 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.610 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DataSourceUtils - - Changing isolation level of JDBC Connection [org.postgresql.jdbc4.Jdbc4Connection@6171108e] to 2
2019-08-07 11:26:47.612 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@2754eb58]
2019-08-07 11:26:47.613 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.613 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = ? and JOB_KEY = ?]
2019-08-07 11:26:47.614 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.010 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:47.614 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:47.617 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.002 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:47.617 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.416 ms parse <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.618 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.294 ms bind <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.618 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.016 ms execute <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.618 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.618 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = ? and JOB_KEY = ?]
2019-08-07 11:26:47.619 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.046 ms parse <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.619 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.068 ms bind <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.619 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.009 ms execute <unnamed>: SELECT JOB_INSTANCE_ID, JOB_NAME from ohdsi.BATCH_JOB_INSTANCE where JOB_NAME = $1 and JOB_KEY = $2]
2019-08-07 11:26:47.624 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.625 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [INSERT into ohdsi.BATCH_JOB_INSTANCE(JOB_INSTANCE_ID, JOB_NAME, JOB_KEY, VERSION) values (?, ?, ?, ?)]
2019-08-07 11:26:47.628 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.628 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.091 ms parse <unnamed>: INSERT into ohdsi.BATCH_JOB_INSTANCE(JOB_INSTANCE_ID, JOB_NAME, JOB_KEY, VERSION) values ($1, $2, $3, $4)]
2019-08-07 11:26:47.628 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.033 ms bind <unnamed>: INSERT into ohdsi.BATCH_JOB_INSTANCE(JOB_INSTANCE_ID, JOB_NAME, JOB_KEY, VERSION) values ($1, $2, $3, $4)]
2019-08-07 11:26:47.628 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.081 ms execute <unnamed>: INSERT into ohdsi.BATCH_JOB_INSTANCE(JOB_INSTANCE_ID, JOB_NAME, JOB_KEY, VERSION) values ($1, $2, $3, $4)]
2019-08-07 11:26:47.629 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.630 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [INSERT into ohdsi.BATCH_JOB_EXECUTION(JOB_EXECUTION_ID, JOB_INSTANCE_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, VERSION, CREATE_TIME, LAST_UPDATED, JOB_CONFIGURATION_LOCATION) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)]
2019-08-07 11:26:47.637 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.637 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.097 ms parse <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION(JOB_EXECUTION_ID, JOB_INSTANCE_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, VERSION, CREATE_TIME, LAST_UPDATED, JOB_CONFIGURATION_LOCATION) values ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11)]
2019-08-07 11:26:47.637 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.071 ms bind <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION(JOB_EXECUTION_ID, JOB_INSTANCE_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, VERSION, CREATE_TIME, LAST_UPDATED, JOB_CONFIGURATION_LOCATION) values ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11)]
2019-08-07 11:26:47.637 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.396 ms execute <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION(JOB_EXECUTION_ID, JOB_INSTANCE_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, VERSION, CREATE_TIME, LAST_UPDATED, JOB_CONFIGURATION_LOCATION) values ($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11)]
2019-08-07 11:26:47.638 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.638 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values (?, ?, ?, ?, ?, ?, ?, ?)]
2019-08-07 11:26:47.641 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.641 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.115 ms parse <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.642 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.046 ms bind <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.642 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.192 ms execute <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.642 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.643 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values (?, ?, ?, ?, ?, ?, ?, ?)]
2019-08-07 11:26:47.644 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.644 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.041 ms parse <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.644 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.038 ms bind <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.644 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.104 ms execute <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.644 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.644 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values (?, ?, ?, ?, ?, ?, ?, ?)]
2019-08-07 11:26:47.646 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.648 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.037 ms parse <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.648 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.034 ms bind <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.648 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.072 ms execute <unnamed>: INSERT into ohdsi.BATCH_JOB_EXECUTION_PARAMS(JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING) values ($1, $2, $3, $4, $5, $6, $7, $8)]
2019-08-07 11:26:47.686 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.686 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [INSERT INTO ohdsi.BATCH_JOB_EXECUTION_CONTEXT (SHORT_CONTEXT, SERIALIZED_CONTEXT, JOB_EXECUTION_ID) VALUES(?, ?, ?)]
2019-08-07 11:26:47.687 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.687 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.195 ms parse <unnamed>: INSERT INTO ohdsi.BATCH_JOB_EXECUTION_CONTEXT (SHORT_CONTEXT, SERIALIZED_CONTEXT, JOB_EXECUTION_ID) VALUES($1, $2, $3)]
2019-08-07 11:26:47.688 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.080 ms bind <unnamed>: INSERT INTO ohdsi.BATCH_JOB_EXECUTION_CONTEXT (SHORT_CONTEXT, SERIALIZED_CONTEXT, JOB_EXECUTION_ID) VALUES($1, $2, $3)]
2019-08-07 11:26:47.688 DEBUG localhost-startStop-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.315 ms execute <unnamed>: INSERT INTO ohdsi.BATCH_JOB_EXECUTION_CONTEXT (SHORT_CONTEXT, SERIALIZED_CONTEXT, JOB_EXECUTION_ID) VALUES($1, $2, $3)]
2019-08-07 11:26:47.688 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.688 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@a5f1829]
2019-08-07 11:26:47.693 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@a5f1829] after transaction
2019-08-07 11:26:47.693 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.061 ms parse <unnamed>: SHOW TRANSACTION ISOLATION LEVEL
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.007 ms bind <unnamed>: SHOW TRANSACTION ISOLATION LEVEL
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.017 ms execute <unnamed>: SHOW TRANSACTION ISOLATION LEVEL
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.011 ms parse S_1: COMMIT
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.017 ms bind S_1: COMMIT
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.693 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 4.395 ms execute S_1: COMMIT
2019-08-07 11:26:47.704 INFO taskExecutor-1 org.springframework.batch.core.launch.support.SimpleJobLauncher - - Job: [SimpleJob: [name=warmCache]] launched with the following parameters: [{jobName=warming OHDSI-CDMV5 cache , time=1565177207532, jobAuthor=system}]
2019-08-07 11:26:47.717 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.update]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:47.717 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@3edea472] for JPA transaction
2019-08-07 11:26:47.718 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.724 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@5945691e]
2019-08-07 11:26:47.726 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.742 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=?]
2019-08-07 11:26:47.745 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.012 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:47.745 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.005 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:47.745 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:47.745 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.274 ms parse <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:47.746 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.333 ms bind <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:47.746 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.041 ms execute <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:47.746 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.752 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT COUNT(*) FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID = ?]
2019-08-07 11:26:47.755 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.093 ms parse <unnamed>: SELECT COUNT(*) FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.755 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.090 ms bind <unnamed>: SELECT COUNT(*) FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.755 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.034 ms execute <unnamed>: SELECT COUNT(*) FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.755 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.755 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_JOB_EXECUTION set START_TIME = ?, END_TIME = ?, STATUS = ?, EXIT_CODE = ?, EXIT_MESSAGE = ?, VERSION = ?, CREATE_TIME = ?, LAST_UPDATED = ? where JOB_EXECUTION_ID = ? and VERSION = ?]
2019-08-07 11:26:47.760 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.760 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.146 ms parse <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, EXIT_CODE = $4, EXIT_MESSAGE = $5, VERSION = $6, CREATE_TIME = $7, LAST_UPDATED = $8 where JOB_EXECUTION_ID = $9 and VERSION = $10]
2019-08-07 11:26:47.761 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.092 ms bind <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, EXIT_CODE = $4, EXIT_MESSAGE = $5, VERSION = $6, CREATE_TIME = $7, LAST_UPDATED = $8 where JOB_EXECUTION_ID = $9 and VERSION = $10]
2019-08-07 11:26:47.761 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.135 ms execute <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, EXIT_CODE = $4, EXIT_MESSAGE = $5, VERSION = $6, CREATE_TIME = $7, LAST_UPDATED = $8 where JOB_EXECUTION_ID = $9 and VERSION = $10]
2019-08-07 11:26:47.761 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.761 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@3edea472]
2019-08-07 11:26:47.765 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@3edea472] after transaction
2019-08-07 11:26:47.765 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.765 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.766 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.011 ms parse S_1: COMMIT
2019-08-07 11:26:47.766 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.766 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.007 ms bind S_1: COMMIT
2019-08-07 11:26:47.766 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.766 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 3.535 ms execute S_1: COMMIT
2019-08-07 11:26:47.768 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.getLastStepExecution]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:47.768 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@71540bdd] for JPA transaction
2019-08-07 11:26:47.768 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.772 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@4aae823f]
2019-08-07 11:26:47.777 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.777 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME, LAST_UPDATED, VERSION, JOB_CONFIGURATION_LOCATION from ohdsi.BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = ? order by JOB_EXECUTION_ID desc]
2019-08-07 11:26:47.779 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.780 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING from ohdsi.BATCH_JOB_EXECUTION_PARAMS where JOB_EXECUTION_ID = ?]
2019-08-07 11:26:47.784 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.101 ms parse <unnamed>: SELECT JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING from ohdsi.BATCH_JOB_EXECUTION_PARAMS where JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.788 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.085 ms bind <unnamed>: SELECT JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING from ohdsi.BATCH_JOB_EXECUTION_PARAMS where JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.788 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.026 ms execute <unnamed>: SELECT JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING from ohdsi.BATCH_JOB_EXECUTION_PARAMS where JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.792 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.010 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:47.792 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:47.792 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.003 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:47.792 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.365 ms parse <unnamed>: SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME, LAST_UPDATED, VERSION, JOB_CONFIGURATION_LOCATION from ohdsi.BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = $1 order by JOB_EXECUTION_ID desc]
2019-08-07 11:26:47.792 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.286 ms bind <unnamed>: SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME, LAST_UPDATED, VERSION, JOB_CONFIGURATION_LOCATION from ohdsi.BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = $1 order by JOB_EXECUTION_ID desc]
2019-08-07 11:26:47.792 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.073 ms execute <unnamed>: SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME, LAST_UPDATED, VERSION, JOB_CONFIGURATION_LOCATION from ohdsi.BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = $1 order by JOB_EXECUTION_ID desc]
2019-08-07 11:26:47.793 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.793 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED, VERSION from ohdsi.BATCH_STEP_EXECUTION where JOB_EXECUTION_ID = ? order by STEP_EXECUTION_ID]
2019-08-07 11:26:47.794 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.168 ms parse <unnamed>: SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED, VERSION from ohdsi.BATCH_STEP_EXECUTION where JOB_EXECUTION_ID = $1 order by STEP_EXECUTION_ID]
2019-08-07 11:26:47.794 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.172 ms bind <unnamed>: SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED, VERSION from ohdsi.BATCH_STEP_EXECUTION where JOB_EXECUTION_ID = $1 order by STEP_EXECUTION_ID]
2019-08-07 11:26:47.794 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.027 ms execute <unnamed>: SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED, VERSION from ohdsi.BATCH_STEP_EXECUTION where JOB_EXECUTION_ID = $1 order by STEP_EXECUTION_ID]
2019-08-07 11:26:47.794 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.794 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@71540bdd]
2019-08-07 11:26:47.794 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@71540bdd] after transaction
2019-08-07 11:26:47.795 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.795 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.795 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.016 ms parse S_1: COMMIT
2019-08-07 11:26:47.795 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.795 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.009 ms bind S_1: COMMIT
2019-08-07 11:26:47.795 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.795 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.009 ms execute S_1: COMMIT
2019-08-07 11:26:47.797 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.getStepExecutionCount]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:47.799 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@576bd424] for JPA transaction
2019-08-07 11:26:47.799 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.802 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@1d2acfaa]
2019-08-07 11:26:47.806 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.806 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME, LAST_UPDATED, VERSION, JOB_CONFIGURATION_LOCATION from ohdsi.BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = ? order by JOB_EXECUTION_ID desc]
2019-08-07 11:26:47.808 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.808 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING from ohdsi.BATCH_JOB_EXECUTION_PARAMS where JOB_EXECUTION_ID = ?]
2019-08-07 11:26:47.809 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.128 ms parse <unnamed>: SELECT JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING from ohdsi.BATCH_JOB_EXECUTION_PARAMS where JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.810 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.155 ms bind <unnamed>: SELECT JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING from ohdsi.BATCH_JOB_EXECUTION_PARAMS where JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.810 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.045 ms execute <unnamed>: SELECT JOB_EXECUTION_ID, KEY_NAME, TYPE_CD, STRING_VAL, DATE_VAL, LONG_VAL, DOUBLE_VAL, IDENTIFYING from ohdsi.BATCH_JOB_EXECUTION_PARAMS where JOB_EXECUTION_ID = $1]
2019-08-07 11:26:47.810 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.011 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:47.810 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:47.810 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.003 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:47.812 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.502 ms parse <unnamed>: SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME, LAST_UPDATED, VERSION, JOB_CONFIGURATION_LOCATION from ohdsi.BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = $1 order by JOB_EXECUTION_ID desc]
2019-08-07 11:26:47.812 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.229 ms bind <unnamed>: SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME, LAST_UPDATED, VERSION, JOB_CONFIGURATION_LOCATION from ohdsi.BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = $1 order by JOB_EXECUTION_ID desc]
2019-08-07 11:26:47.812 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.038 ms execute <unnamed>: SELECT JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, EXIT_CODE, EXIT_MESSAGE, CREATE_TIME, LAST_UPDATED, VERSION, JOB_CONFIGURATION_LOCATION from ohdsi.BATCH_JOB_EXECUTION where JOB_INSTANCE_ID = $1 order by JOB_EXECUTION_ID desc]
2019-08-07 11:26:47.812 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.812 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED, VERSION from ohdsi.BATCH_STEP_EXECUTION where JOB_EXECUTION_ID = ? order by STEP_EXECUTION_ID]
2019-08-07 11:26:47.814 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.140 ms parse <unnamed>: SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED, VERSION from ohdsi.BATCH_STEP_EXECUTION where JOB_EXECUTION_ID = $1 order by STEP_EXECUTION_ID]
2019-08-07 11:26:47.815 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.158 ms bind <unnamed>: SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED, VERSION from ohdsi.BATCH_STEP_EXECUTION where JOB_EXECUTION_ID = $1 order by STEP_EXECUTION_ID]
2019-08-07 11:26:47.815 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.013 ms execute <unnamed>: SELECT STEP_EXECUTION_ID, STEP_NAME, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED, VERSION from ohdsi.BATCH_STEP_EXECUTION where JOB_EXECUTION_ID = $1 order by STEP_EXECUTION_ID]
2019-08-07 11:26:47.815 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.815 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@576bd424]
2019-08-07 11:26:47.817 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@576bd424] after transaction
2019-08-07 11:26:47.817 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.817 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.820 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.017 ms parse S_1: COMMIT
2019-08-07 11:26:47.820 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.820 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.019 ms bind S_1: COMMIT
2019-08-07 11:26:47.821 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.821 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.009 ms execute S_1: COMMIT
2019-08-07 11:26:47.825 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.add]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:47.825 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@31350534] for JPA transaction
2019-08-07 11:26:47.825 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.830 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@38762dc2]
2019-08-07 11:26:47.831 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.831 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [INSERT into ohdsi.BATCH_STEP_EXECUTION(STEP_EXECUTION_ID, VERSION, STEP_NAME, JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED) values(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)]
2019-08-07 11:26:47.837 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.837 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.176 ms parse <unnamed>: INSERT into ohdsi.BATCH_STEP_EXECUTION(STEP_EXECUTION_ID, VERSION, STEP_NAME, JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED) values($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14, $15, $16, $17, $18)]
2019-08-07 11:26:47.837 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.075 ms bind <unnamed>: INSERT into ohdsi.BATCH_STEP_EXECUTION(STEP_EXECUTION_ID, VERSION, STEP_NAME, JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED) values($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14, $15, $16, $17, $18)]
2019-08-07 11:26:47.837 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.578 ms execute <unnamed>: INSERT into ohdsi.BATCH_STEP_EXECUTION(STEP_EXECUTION_ID, VERSION, STEP_NAME, JOB_EXECUTION_ID, START_TIME, END_TIME, STATUS, COMMIT_COUNT, READ_COUNT, FILTER_COUNT, WRITE_COUNT, EXIT_CODE, EXIT_MESSAGE, READ_SKIP_COUNT, WRITE_SKIP_COUNT, PROCESS_SKIP_COUNT, ROLLBACK_COUNT, LAST_UPDATED) values($1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14, $15, $16, $17, $18)]
2019-08-07 11:26:47.839 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.839 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [INSERT INTO ohdsi.BATCH_STEP_EXECUTION_CONTEXT (SHORT_CONTEXT, SERIALIZED_CONTEXT, STEP_EXECUTION_ID) VALUES(?, ?, ?)]
2019-08-07 11:26:47.843 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.843 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.075 ms parse <unnamed>: INSERT INTO ohdsi.BATCH_STEP_EXECUTION_CONTEXT (SHORT_CONTEXT, SERIALIZED_CONTEXT, STEP_EXECUTION_ID) VALUES($1, $2, $3)]
2019-08-07 11:26:47.844 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.052 ms bind <unnamed>: INSERT INTO ohdsi.BATCH_STEP_EXECUTION_CONTEXT (SHORT_CONTEXT, SERIALIZED_CONTEXT, STEP_EXECUTION_ID) VALUES($1, $2, $3)]
2019-08-07 11:26:47.844 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.286 ms execute <unnamed>: INSERT INTO ohdsi.BATCH_STEP_EXECUTION_CONTEXT (SHORT_CONTEXT, SERIALIZED_CONTEXT, STEP_EXECUTION_ID) VALUES($1, $2, $3)]
2019-08-07 11:26:47.844 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.844 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@31350534]
2019-08-07 11:26:47.856 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@31350534] after transaction
2019-08-07 11:26:47.856 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.856 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.856 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.010 ms parse S_1: COMMIT
2019-08-07 11:26:47.856 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.856 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.006 ms bind S_1: COMMIT
2019-08-07 11:26:47.856 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.856 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 10.786 ms execute S_1: COMMIT
2019-08-07 11:26:47.857 INFO taskExecutor-1 org.springframework.batch.core.job.SimpleStepHandler - - Executing step: [warmCacheStep]
2019-08-07 11:26:47.857 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.update]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:47.857 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@30bb6cbd] for JPA transaction
2019-08-07 11:26:47.857 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.866 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@392a4f55]
2019-08-07 11:26:47.867 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.867 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = ?, END_TIME = ?, STATUS = ?, COMMIT_COUNT = ?, READ_COUNT = ?, FILTER_COUNT = ?, WRITE_COUNT = ?, EXIT_CODE = ?, EXIT_MESSAGE = ?, VERSION = ?, READ_SKIP_COUNT = ?, PROCESS_SKIP_COUNT = ?, WRITE_SKIP_COUNT = ?, ROLLBACK_COUNT = ?, LAST_UPDATED = ? where STEP_EXECUTION_ID = ? and VERSION = ?]
2019-08-07 11:26:47.872 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.872 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.011 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:47.873 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:47.873 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.003 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:47.873 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.302 ms parse <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:47.873 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.375 ms bind <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:47.873 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.140 ms execute <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:47.873 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:47.874 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=?]
2019-08-07 11:26:47.875 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.094 ms parse <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:47.875 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.125 ms bind <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:47.875 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.016 ms execute <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:47.876 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.876 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@30bb6cbd]
2019-08-07 11:26:47.879 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@30bb6cbd] after transaction
2019-08-07 11:26:47.879 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.879 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.879 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.017 ms parse S_1: COMMIT
2019-08-07 11:26:47.879 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.879 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.009 ms bind S_1: COMMIT
2019-08-07 11:26:47.879 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.879 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 2.969 ms execute S_1: COMMIT
2019-08-07 11:26:47.883 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.updateExecutionContext]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:47.883 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@229f6468] for JPA transaction
2019-08-07 11:26:47.884 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.889 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@7f8d4454]
2019-08-07 11:26:47.890 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:47.896 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = ?, SERIALIZED_CONTEXT = ? WHERE STEP_EXECUTION_ID = ?]
2019-08-07 11:26:47.899 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:47.900 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.012 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:47.900 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:47.900 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.003 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:47.900 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.258 ms parse <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:47.900 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.286 ms bind <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:47.900 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.124 ms execute <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:47.901 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:47.904 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@229f6468]
2019-08-07 11:26:47.908 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@229f6468] after transaction
2019-08-07 11:26:47.908 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:47.908 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.908 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.012 ms parse S_1: COMMIT
2019-08-07 11:26:47.908 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.908 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.006 ms bind S_1: COMMIT
2019-08-07 11:26:47.908 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:47.908 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 2.939 ms execute S_1: COMMIT
2019-08-07 11:26:47.961 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [null]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:47.961 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@854780] for JPA transaction
2019-08-07 11:26:47.962 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:47.971 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@509f79ae]
2019-08-07 11:26:48.176 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:48.204 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [WITH concepts AS (
SELECT
CAST(ancestor_concept_id AS VARCHAR) ancestor_id,
CAST(descendant_concept_id AS VARCHAR) descendant_id
FROM public.concept_ancestor ca
), counts AS (
SELECT stratum_1 concept_id, MAX (count_value) agg_count_value
FROM ohdsi.achilles_results
WHERE analysis_id IN (2, 4, 5, 201, 301, 401, 501, 505, 601, 701, 801, 901, 1001, 1201, 1801)

GROUP BY stratum_1
UNION
SELECT stratum_2 AS concept_id, SUM (count_value) AS agg_count_value
FROM ohdsi.achilles_results
WHERE analysis_id IN (405, 605, 705, 805, 807, 1805, 1807)

GROUP BY stratum_2
)
SELECT
concepts.ancestor_id concept_id,
COALESCE(max(c1.agg_count_value),0) record_count,
COALESCE(sum(c2.agg_count_value),0) descendant_record_count

FROM concepts
LEFT JOIN counts c1 ON concepts.ancestor_id = c1.concept_id
LEFT JOIN counts c2 ON concepts.descendant_id = c2.concept_id
GROUP BY concepts.ancestor_id
HAVING COALESCE(max(c1.agg_count_value),0) > 0 OR COALESCE(sum(c2.agg_count_value),0) > 0]
2019-08-07 11:26:48.205 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DataSourceUtils - - Fetching JDBC Connection from DataSource
2019-08-07 11:26:48.214 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://localhost:5432/ohdsi?user=ohdsi_app_user&password=admin1]
2019-08-07 11:26:48.532 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.533 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.534 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.535 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.535 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.544 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.549 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.549 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.653 ERROR taskExecutor-1 org.ohdsi.webapi.cdmresults.CDMResultsCacheTasklet - - Failed to warm cache for OHDSI-CDMV5. Exception: Could not get JDBC Connection; nested exception is org.postgresql.util.PSQLException: Connection to localhost:5432 refused. Check that the hostname and port are correct and that the postmaster is accepting TCP/IP connections.
2019-08-07 11:26:48.654 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Found thread-bound EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@854780] for JPA transaction
2019-08-07 11:26:48.654 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Participating in existing transaction
2019-08-07 11:26:48.654 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:48.655 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = ?, SERIALIZED_CONTEXT = ? WHERE STEP_EXECUTION_ID = ?]
2019-08-07 11:26:48.656 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:48.656 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.030 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:48.656 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.007 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:48.657 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:48.657 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.347 ms parse <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:48.657 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.331 ms bind <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:48.660 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.120 ms execute <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:48.661 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Found thread-bound EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@854780] for JPA transaction
2019-08-07 11:26:48.661 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Participating in existing transaction
2019-08-07 11:26:48.664 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:48.664 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = ?, END_TIME = ?, STATUS = ?, COMMIT_COUNT = ?, READ_COUNT = ?, FILTER_COUNT = ?, WRITE_COUNT = ?, EXIT_CODE = ?, EXIT_MESSAGE = ?, VERSION = ?, READ_SKIP_COUNT = ?, PROCESS_SKIP_COUNT = ?, WRITE_SKIP_COUNT = ?, ROLLBACK_COUNT = ?, LAST_UPDATED = ? where STEP_EXECUTION_ID = ? and VERSION = ?]
2019-08-07 11:26:48.667 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:48.667 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.155 ms parse <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:48.667 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.193 ms bind <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:48.668 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.061 ms execute <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:48.668 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:48.668 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=?]
2019-08-07 11:26:48.669 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.100 ms parse <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.669 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.157 ms bind <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.669 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.017 ms execute <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.672 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:48.672 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@854780]
2019-08-07 11:26:48.678 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@854780] after transaction
2019-08-07 11:26:48.678 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.678 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.678 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.028 ms parse S_1: COMMIT
2019-08-07 11:26:48.678 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.679 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.008 ms bind S_1: COMMIT
2019-08-07 11:26:48.679 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.679 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 6.016 ms execute S_1: COMMIT
2019-08-07 11:26:48.681 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.updateExecutionContext]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:48.681 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@24700e6] for JPA transaction
2019-08-07 11:26:48.681 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:48.689 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@53ee3454]
2019-08-07 11:26:48.689 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:48.689 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = ?, SERIALIZED_CONTEXT = ? WHERE STEP_EXECUTION_ID = ?]
2019-08-07 11:26:48.691 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:48.691 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.011 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:48.691 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.005 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:48.691 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.003 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:48.691 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.284 ms parse <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:48.691 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.306 ms bind <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:48.692 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.141 ms execute <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE STEP_EXECUTION_ID = $3]
2019-08-07 11:26:48.692 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:48.692 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@24700e6]
2019-08-07 11:26:48.696 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@24700e6] after transaction
2019-08-07 11:26:48.696 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.697 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.697 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.011 ms parse S_1: COMMIT
2019-08-07 11:26:48.697 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.697 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.006 ms bind S_1: COMMIT
2019-08-07 11:26:48.697 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.697 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 3.315 ms execute S_1: COMMIT
2019-08-07 11:26:48.700 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.update]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:48.700 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@52e2c321] for JPA transaction
2019-08-07 11:26:48.700 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:48.705 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@708c11bd]
2019-08-07 11:26:48.705 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:48.705 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = ?, END_TIME = ?, STATUS = ?, COMMIT_COUNT = ?, READ_COUNT = ?, FILTER_COUNT = ?, WRITE_COUNT = ?, EXIT_CODE = ?, EXIT_MESSAGE = ?, VERSION = ?, READ_SKIP_COUNT = ?, PROCESS_SKIP_COUNT = ?, WRITE_SKIP_COUNT = ?, ROLLBACK_COUNT = ?, LAST_UPDATED = ? where STEP_EXECUTION_ID = ? and VERSION = ?]
2019-08-07 11:26:48.709 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:48.709 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.010 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:48.710 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.005 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:48.710 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.003 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:48.710 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.317 ms parse <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:48.710 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.328 ms bind <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:48.710 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.118 ms execute <unnamed>: UPDATE ohdsi.BATCH_STEP_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, COMMIT_COUNT = $4, READ_COUNT = $5, FILTER_COUNT = $6, WRITE_COUNT = $7, EXIT_CODE = $8, EXIT_MESSAGE = $9, VERSION = $10, READ_SKIP_COUNT = $11, PROCESS_SKIP_COUNT = $12, WRITE_SKIP_COUNT = $13, ROLLBACK_COUNT = $14, LAST_UPDATED = $15 where STEP_EXECUTION_ID = $16 and VERSION = $17]
2019-08-07 11:26:48.710 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:48.710 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=?]
2019-08-07 11:26:48.711 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.086 ms parse <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.711 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.126 ms bind <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.711 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.015 ms execute <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.712 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:48.712 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@52e2c321]
2019-08-07 11:26:48.716 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@52e2c321] after transaction
2019-08-07 11:26:48.716 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.716 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.720 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.017 ms parse S_1: COMMIT
2019-08-07 11:26:48.720 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.720 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.008 ms bind S_1: COMMIT
2019-08-07 11:26:48.720 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.720 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 2.672 ms execute S_1: COMMIT
2019-08-07 11:26:48.722 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.updateExecutionContext]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:48.723 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@1e6b7e28] for JPA transaction
2019-08-07 11:26:48.723 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:48.727 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@5ad44d0f]
2019-08-07 11:26:48.727 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:48.727 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_JOB_EXECUTION_CONTEXT SET SHORT_CONTEXT = ?, SERIALIZED_CONTEXT = ? WHERE JOB_EXECUTION_ID = ?]
2019-08-07 11:26:48.732 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:48.732 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.010 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:48.732 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.003 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:48.732 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.002 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:48.733 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.208 ms parse <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE JOB_EXECUTION_ID = $3]
2019-08-07 11:26:48.733 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.257 ms bind <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE JOB_EXECUTION_ID = $3]
2019-08-07 11:26:48.733 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.128 ms execute <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION_CONTEXT SET SHORT_CONTEXT = $1, SERIALIZED_CONTEXT = $2 WHERE JOB_EXECUTION_ID = $3]
2019-08-07 11:26:48.733 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:48.733 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@1e6b7e28]
2019-08-07 11:26:48.737 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@1e6b7e28] after transaction
2019-08-07 11:26:48.737 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.737 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.737 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.011 ms parse S_1: COMMIT
2019-08-07 11:26:48.737 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.737 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.006 ms bind S_1: COMMIT
2019-08-07 11:26:48.737 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.737 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 3.129 ms execute S_1: COMMIT
2019-08-07 11:26:48.739 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [org.springframework.batch.core.repository.support.SimpleJobRepository.update]: PROPAGATION_REQUIRED,ISOLATION_DEFAULT
2019-08-07 11:26:48.739 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@8c15358] for JPA transaction
2019-08-07 11:26:48.739 DEBUG taskExecutor-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:48.744 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@919be54]
2019-08-07 11:26:48.744 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:48.744 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=?]
2019-08-07 11:26:48.745 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.010 ms parse <unnamed>: BEGIN]
2019-08-07 11:26:48.748 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.004 ms bind <unnamed>: BEGIN]
2019-08-07 11:26:48.748 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.003 ms execute <unnamed>: BEGIN]
2019-08-07 11:26:48.749 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.187 ms parse <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.749 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.255 ms bind <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.749 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.043 ms execute <unnamed>: SELECT VERSION FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID=$1]
2019-08-07 11:26:48.749 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL query
2019-08-07 11:26:48.752 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [SELECT COUNT(*) FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID = ?]
2019-08-07 11:26:48.753 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.114 ms parse <unnamed>: SELECT COUNT(*) FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID = $1]
2019-08-07 11:26:48.753 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.098 ms bind <unnamed>: SELECT COUNT(*) FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID = $1]
2019-08-07 11:26:48.753 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.033 ms execute <unnamed>: SELECT COUNT(*) FROM ohdsi.BATCH_JOB_EXECUTION WHERE JOB_EXECUTION_ID = $1]
2019-08-07 11:26:48.753 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL update
2019-08-07 11:26:48.753 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - Executing prepared SQL statement [UPDATE ohdsi.BATCH_JOB_EXECUTION set START_TIME = ?, END_TIME = ?, STATUS = ?, EXIT_CODE = ?, EXIT_MESSAGE = ?, VERSION = ?, CREATE_TIME = ?, LAST_UPDATED = ? where JOB_EXECUTION_ID = ? and VERSION = ?]
2019-08-07 11:26:48.756 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQL update affected 1 rows
2019-08-07 11:26:48.756 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.118 ms parse <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, EXIT_CODE = $4, EXIT_MESSAGE = $5, VERSION = $6, CREATE_TIME = $7, LAST_UPDATED = $8 where JOB_EXECUTION_ID = $9 and VERSION = $10]
2019-08-07 11:26:48.757 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.102 ms bind <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, EXIT_CODE = $4, EXIT_MESSAGE = $5, VERSION = $6, CREATE_TIME = $7, LAST_UPDATED = $8 where JOB_EXECUTION_ID = $9 and VERSION = $10]
2019-08-07 11:26:48.757 DEBUG taskExecutor-1 org.springframework.jdbc.core.JdbcTemplate - - SQLWarning ignored: SQL state '00000', error code '0', message [duration: 0.132 ms execute <unnamed>: UPDATE ohdsi.BATCH_JOB_EXECUTION set START_TIME = $1, END_TIME = $2, STATUS = $3, EXIT_CODE = $4, EXIT_MESSAGE = $5, VERSION = $6, CREATE_TIME = $7, LAST_UPDATED = $8 where JOB_EXECUTION_ID = $9 and VERSION = $10]
2019-08-07 11:26:48.757 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:48.757 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@8c15358]
2019-08-07 11:26:48.761 DEBUG taskExecutor-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@8c15358] after transaction
2019-08-07 11:26:48.761 DEBUG taskExecutor-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.761 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.762 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.010 ms parse S_1: COMMIT
2019-08-07 11:26:48.762 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.762 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.006 ms bind S_1: COMMIT
2019-08-07 11:26:48.762 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:48.762 WARN taskExecutor-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 3.582 ms execute S_1: COMMIT
2019-08-07 11:26:48.764 INFO taskExecutor-1 org.springframework.batch.core.launch.support.SimpleJobLauncher - - Job: [SimpleJob: [name=warmCache]] completed with the following parameters: [{jobName=warming OHDSI-CDMV5 cache , time=1565177207532, jobAuthor=system}] and the following status: [COMPLETED]
2019-08-07 11:26:48.816 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.816 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.817 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.817 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.817 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.817 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.818 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.820 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.902 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.903 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.903 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.903 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.904 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.904 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:48.904 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:48.905 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.031 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.032 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.037 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.037 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.037 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.038 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.038 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.039 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.124 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.128 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.129 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.129 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.129 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.130 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.130 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.130 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.180 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.180 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.180 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.181 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.181 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.181 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.182 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.182 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.217 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.217 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.218 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.218 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.219 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.219 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.219 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.220 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.260 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.260 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.261 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.264 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.265 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.265 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.266 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.266 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.332 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.336 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.337 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.337 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.337 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.338 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.340 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.340 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.423 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.424 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.424 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.425 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.425 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.425 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.426 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.426 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.481 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.481 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.482 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.482 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.482 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.484 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.485 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.485 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.514 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Creating new transaction with name [null]: PROPAGATION_REQUIRES_NEW,ISOLATION_DEFAULT
2019-08-07 11:26:49.514 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Opened new EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@98b4925] for JPA transaction
2019-08-07 11:26:49.514 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:49.521 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Exposing JPA transaction as JDBC transaction [org.springframework.orm.jpa.vendor.HibernateJpaDialect$HibernateConnectionHandle@415dd187]
Hibernate: select executioni0_.analysis_id as analysis1_35_, executioni0_.source_id as source_i2_35_, executioni0_.execution_duration as executio3_35_, executioni0_.is_valid as is_valid4_35_, executioni0_.message as message5_35_, executioni0_.start_time as start_ti6_35_, executioni0_.status as status7_35_ from ohdsi.ir_execution executioni0_ where executioni0_.status in (? , ?)
2019-08-07 11:26:49.534 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Found thread-bound EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@98b4925] for JPA transaction
2019-08-07 11:26:49.536 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Participating in existing transaction
2019-08-07 11:26:49.537 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Initiating transaction commit
2019-08-07 11:26:49.537 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Committing JPA transaction on EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@98b4925]
2019-08-07 11:26:49.537 DEBUG localhost-startStop-1 org.springframework.orm.jpa.JpaTransactionManager - - Closing JPA EntityManager [org.hibernate.jpa.internal.EntityManagerImpl@98b4925] after transaction
2019-08-07 11:26:49.537 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.537 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:49.538 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.038 ms parse S_1: COMMIT
2019-08-07 11:26:49.538 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:49.538 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.009 ms bind S_1: COMMIT
2019-08-07 11:26:49.538 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - SQL Warning Code: 0, SQLState: 00000
2019-08-07 11:26:49.538 WARN localhost-startStop-1 org.hibernate.engine.jdbc.spi.SqlExceptionHelper - - duration: 0.008 ms execute S_1: COMMIT
2019-08-07 11:26:49.625 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DataSourceUtils - - Fetching JDBC Connection from DataSource
2019-08-07 11:26:49.626 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:49.630 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DataSourceUtils - - Returning JDBC Connection to DataSource
2019-08-07 11:26:49.635 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DataSourceUtils - - Fetching JDBC Connection from DataSource
2019-08-07 11:26:49.635 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:49.639 DEBUG localhost-startStop-1 org.springframework.jdbc.datasource.DataSourceUtils - - Returning JDBC Connection to DataSource
2019-08-07 11:26:49.728 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.729 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.730 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.730 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.731 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.731 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.732 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.732 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.841 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.842 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.842 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.843 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.844 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.845 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.845 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.845 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.908 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.912 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.913 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.913 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.913 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.914 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.914 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.914 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.950 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.951 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.953 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.953 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.953 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.953 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.954 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:49.954 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:49.999 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.004 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.005 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.005 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.005 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.005 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.006 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.006 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.223 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.223 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.224 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.224 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.225 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.225 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.225 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.226 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.328 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.337 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.338 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.339 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.341 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.341 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.342 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.342 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.348 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.356 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.357 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.358 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.358 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.358 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.358 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.359 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.378 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.379 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.380 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.380 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.380 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.381 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.381 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.384 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.397 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.400 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.401 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.401 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.401 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.402 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.402 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.402 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.423 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.424 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.426 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.426 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.427 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.427 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.428 DEBUG localhost-startStop-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
2019-08-07 11:26:50.432 DEBUG localhost-startStop-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:26:50.483 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.009 ms parse <unnamed>: BEGIN
2019-08-07 11:26:50.483 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: BEGIN
2019-08-07 11:26:50.483 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.002 ms execute <unnamed>: BEGIN
2019-08-07 11:26:50.483 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.008 ms parse <unnamed>: RESET ROLE
2019-08-07 11:26:50.484 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: RESET ROLE
2019-08-07 11:26:50.484 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.006 ms execute <unnamed>: RESET ROLE
2019-08-07 11:26:50.485 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.010 ms parse <unnamed>: BEGIN
2019-08-07 11:26:50.485 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: BEGIN
2019-08-07 11:26:50.485 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.002 ms execute <unnamed>: BEGIN
2019-08-07 11:26:50.485 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.010 ms parse <unnamed>: RESET ROLE
2019-08-07 11:26:50.485 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: RESET ROLE
2019-08-07 11:26:50.486 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms execute <unnamed>: RESET ROLE
2019-08-07 11:26:50.622 INFO localhost-startStop-1 org.flywaydb.core.internal.command.DbMigrate - - Current version of schema "ohdsi": 2.6.0.20181009115500
2019-08-07 11:26:50.632 WARN localhost-startStop-1 org.flywaydb.core.internal.command.DbMigrate - - outOfOrder mode is active. Migration of schema "ohdsi" may not be reproducible.
2019-08-07 11:26:50.633 INFO localhost-startStop-1 org.flywaydb.core.internal.command.DbMigrate - - Schema "ohdsi" is up to date. No migration necessary.
2019-08-07 11:26:50.633 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.010 ms parse <unnamed>: BEGIN
2019-08-07 11:26:50.633 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: BEGIN
2019-08-07 11:26:50.634 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.003 ms execute <unnamed>: BEGIN
2019-08-07 11:26:50.634 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.008 ms parse <unnamed>: RESET ROLE
2019-08-07 11:26:50.634 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: RESET ROLE
2019-08-07 11:26:50.634 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.009 ms execute <unnamed>: RESET ROLE
2019-08-07 11:26:50.635 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.008 ms parse <unnamed>: BEGIN
2019-08-07 11:26:50.635 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: BEGIN
2019-08-07 11:26:50.635 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.002 ms execute <unnamed>: BEGIN
2019-08-07 11:26:50.635 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.010 ms parse <unnamed>: RESET ROLE
2019-08-07 11:26:50.636 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.004 ms bind <unnamed>: RESET ROLE
2019-08-07 11:26:50.636 INFO localhost-startStop-1 org.flywaydb.core.internal.dbsupport.JdbcTemplate - - DB: duration: 0.005 ms execute <unnamed>: RESET ROLE
2019-08-07 11:26:51.451 INFO localhost-startStop-1 org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter - - Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@31aad3ca: startup date [Wed Aug 07 11:26:25 UTC 2019]; root of context hierarchy
2019-08-07 11:26:51.615 INFO localhost-startStop-1 org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - - Mapped "{[/error],produces=[text/html]}" onto public org.springframework.web.servlet.ModelAndView org.springframework.boot.autoconfigure.web.BasicErrorController.errorHtml(javax.servlet.http.HttpServletRequest,javax.servlet.http.HttpServletResponse)
2019-08-07 11:26:51.617 INFO localhost-startStop-1 org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping - - Mapped "{[/error]}" onto public org.springframework.http.ResponseEntity<java.util.Map<java.lang.String, java.lang.Object>> org.springframework.boot.autoconfigure.web.BasicErrorController.error(javax.servlet.http.HttpServletRequest)
2019-08-07 11:26:51.680 INFO localhost-startStop-1 org.springframework.web.servlet.handler.SimpleUrlHandlerMapping - - Mapped URL path [/webjars/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2019-08-07 11:26:51.684 INFO localhost-startStop-1 org.springframework.web.servlet.handler.SimpleUrlHandlerMapping - - Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2019-08-07 11:26:51.783 INFO localhost-startStop-1 org.springframework.web.servlet.handler.SimpleUrlHandlerMapping - - Mapped URL path [/**/favicon.ico] onto handler of type [class org.springframework.web.servlet.resource.ResourceHttpRequestHandler]
2019-08-07 11:26:52.145 INFO localhost-startStop-1 org.springframework.ldap.core.support.AbstractContextSource - - Property 'userDn' not set - anonymous context will be used for read-write operations
2019-08-07 11:26:52.720 INFO localhost-startStop-1 org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler - - Initializing ExecutorService 'taskScheduler'
2019-08-07 11:26:53.881 INFO localhost-startStop-1 org.springframework.jmx.export.annotation.AnnotationMBeanExporter - - Registering beans for JMX exposure on startup
2019-08-07 11:26:53.904 INFO localhost-startStop-1 org.springframework.jmx.export.annotation.AnnotationMBeanExporter - - Bean with name 'integrationMbeanExporter' has been autodetected for JMX exposure
2019-08-07 11:26:53.907 INFO localhost-startStop-1 org.springframework.jmx.export.annotation.AnnotationMBeanExporter - - Located managed bean 'integrationMbeanExporter': registering with JMX server as MBean [org.springframework.integration.monitor:name=integrationMbeanExporter,type=IntegrationMBeanExporter]
2019-08-07 11:26:53.979 INFO localhost-startStop-1 org.springframework.integration.monitor.IntegrationMBeanExporter - - Registering beans for JMX exposure on startup
2019-08-07 11:26:53.988 INFO localhost-startStop-1 org.springframework.integration.monitor.IntegrationMBeanExporter - - Registering MessageChannel errorChannel
2019-08-07 11:26:53.991 INFO localhost-startStop-1 org.springframework.integration.monitor.IntegrationMBeanExporter - - Located managed bean 'org.springframework.integration:type=MessageChannel,name=errorChannel': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=errorChannel]
2019-08-07 11:26:54.053 INFO localhost-startStop-1 org.springframework.integration.monitor.IntegrationMBeanExporter - - Registering MessageChannel nullChannel
2019-08-07 11:26:54.055 INFO localhost-startStop-1 org.springframework.integration.monitor.IntegrationMBeanExporter - - Located managed bean 'org.springframework.integration:type=MessageChannel,name=nullChannel': registering with JMX server as MBean [org.springframework.integration:type=MessageChannel,name=nullChannel]
2019-08-07 11:26:54.075 INFO localhost-startStop-1 org.springframework.integration.monitor.IntegrationMBeanExporter - - Registering MessageHandler errorLogger
2019-08-07 11:26:54.083 INFO localhost-startStop-1 org.springframework.integration.monitor.IntegrationMBeanExporter - - 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]
2019-08-07 11:26:54.123 INFO localhost-startStop-1 org.springframework.context.support.DefaultLifecycleProcessor - - Starting beans in phase 0
2019-08-07 11:26:54.128 INFO localhost-startStop-1 org.springframework.integration.endpoint.EventDrivenConsumer - - Adding {logging-channel-adapter:_org.springframework.integration.errorLogger} as a subscriber to the 'errorChannel' channel
2019-08-07 11:26:54.129 INFO localhost-startStop-1 org.springframework.integration.channel.PublishSubscribeChannel - - Channel 'application:default:8090.errorChannel' has 1 subscriber(s).
2019-08-07 11:26:54.129 INFO localhost-startStop-1 org.springframework.integration.endpoint.EventDrivenConsumer - - started _org.springframework.integration.errorLogger
2019-08-07 11:26:54.129 INFO localhost-startStop-1 org.springframework.context.support.DefaultLifecycleProcessor - - Starting beans in phase 2147483647
2019-08-07 11:26:54.154 DEBUG task-scheduler-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
Hibernate: select analysisex0_.id as id1_0_, analysisex0_.analysis_id as analysis2_0_, analysisex0_.analysis_type as analysis3_0_, analysisex0_.duration as duration4_0_, analysisex0_.executed as executed5_0_, analysisex0_.executionStatus as executio6_0_, analysisex0_.source_id as source_i7_0_, analysisex0_.update_password as update_p8_0_, analysisex0_.sec_user_id as sec_user9_0_ from ohdsi.analysis_execution analysisex0_ where analysisex0_.executed<? and (analysisex0_.executionStatus in (? , ? , ?))
2019-08-07 11:26:54.183 DEBUG task-scheduler-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:26:54.192 INFO localhost-startStop-1 org.ohdsi.webapi.WebApi - - Started WebApi in 29.449 seconds (JVM running for 50.829)
2019-08-07 11:26:54.194 DEBUG task-scheduler-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:27:04.201 DEBUG task-scheduler-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
Hibernate: select analysisex0_.id as id1_0_, analysisex0_.analysis_id as analysis2_0_, analysisex0_.analysis_type as analysis3_0_, analysisex0_.duration as duration4_0_, analysisex0_.executed as executed5_0_, analysisex0_.executionStatus as executio6_0_, analysisex0_.source_id as source_i7_0_, analysisex0_.update_password as update_p8_0_, analysisex0_.sec_user_id as sec_user9_0_ from ohdsi.analysis_execution analysisex0_ where analysisex0_.executed<? and (analysisex0_.executionStatus in (? , ? , ?))
2019-08-07 11:27:04.215 DEBUG task-scheduler-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:27:04.247 DEBUG task-scheduler-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:27:14.249 DEBUG task-scheduler-2 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
Hibernate: select analysisex0_.id as id1_0_, analysisex0_.analysis_id as analysis2_0_, analysisex0_.analysis_type as analysis3_0_, analysisex0_.duration as duration4_0_, analysisex0_.executed as executed5_0_, analysisex0_.executionStatus as executio6_0_, analysisex0_.source_id as source_i7_0_, analysisex0_.update_password as update_p8_0_, analysisex0_.sec_user_id as sec_user9_0_ from ohdsi.analysis_execution analysisex0_ where analysisex0_.executed<? and (analysisex0_.executionStatus in (? , ? , ?))
2019-08-07 11:27:14.251 DEBUG task-scheduler-2 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:27:14.267 DEBUG task-scheduler-2 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager
2019-08-07 11:27:24.280 DEBUG task-scheduler-1 org.springframework.orm.jpa.SharedEntityManagerCreator$SharedEntityManagerInvocationHandler - - Creating new EntityManager for shared EntityManager invocation
Hibernate: select analysisex0_.id as id1_0_, analysisex0_.analysis_id as analysis2_0_, analysisex0_.analysis_type as analysis3_0_, analysisex0_.duration as duration4_0_, analysisex0_.executed as executed5_0_, analysisex0_.executionStatus as executio6_0_, analysisex0_.source_id as source_i7_0_, analysisex0_.update_password as update_p8_0_, analysisex0_.sec_user_id as sec_user9_0_ from ohdsi.analysis_execution analysisex0_ where analysisex0_.executed<? and (analysisex0_.executionStatus in (? , ? , ?))
2019-08-07 11:27:24.281 DEBUG task-scheduler-1 org.springframework.jdbc.datasource.DriverManagerDataSource - - Creating new JDBC DriverManager Connection to [jdbc:postgresql://10.65.4.5:5432/ohdsi]
2019-08-07 11:27:24.299 DEBUG task-scheduler-1 org.springframework.orm.jpa.EntityManagerFactoryUtils - - Closing JPA EntityManager