Started by user Matthias Mueller [Pipeline] Start of Pipeline [Pipeline] node Running on Jenkins in /var/lib/jenkins/workspace/appNG_Scheduler [Pipeline] { [Pipeline] tool [Pipeline] tool [Pipeline] stage [Pipeline] { (git clone) [Pipeline] git The recommended git tool is: NONE No credentials specified Cloning the remote Git repository Cloning repository git@github.com:appNG/appng-scheduler.git > git init /var/lib/jenkins/workspace/appNG_Scheduler # timeout=10 Fetching upstream changes from git@github.com:appNG/appng-scheduler.git > git --version # timeout=10 > git --version # 'git version 2.30.2' > git fetch --tags --force --progress -- git@github.com:appNG/appng-scheduler.git +refs/heads/*:refs/remotes/origin/* # timeout=10 > git config remote.origin.url git@github.com:appNG/appng-scheduler.git # timeout=10 > git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10 Avoid second fetch > git rev-parse refs/remotes/origin/appng-scheduler-1.17.x^{commit} # timeout=10 Checking out Revision 75a31fc6d526a2429256d0c824137ce5192d132e (refs/remotes/origin/appng-scheduler-1.17.x) > git config core.sparsecheckout # timeout=10 > git checkout -f 75a31fc6d526a2429256d0c824137ce5192d132e # timeout=10 > git branch -a -v --no-abbrev # timeout=10 > git checkout -b appng-scheduler-1.17.x 75a31fc6d526a2429256d0c824137ce5192d132e # timeout=10 Commit message: "SCHED-58" > git rev-list --no-walk d5714103f7ac5dedf96e783642367467afad5bc3 # timeout=10 [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Maven Build) [Pipeline] sh + export JAVA_HOME=/var/lib/jenkins/tools/hudson.model.JDK/OpenJDK8/jdk8u332-b09 [Pipeline] sh + /var/lib/jenkins/tools/hudson.tasks.Maven_MavenInstallation/Maven_3.6.3/bin/mvn -version Apache Maven 3.8.6 (84538c9988a25aec085021c365c560670ad80f63) Maven home: /var/lib/jenkins/tools/hudson.tasks.Maven_MavenInstallation/Maven_3.6.3 Java version: 1.8.0_332, vendor: Temurin, runtime: /var/lib/jenkins/tools/hudson.model.JDK/OpenJDK8/jdk8u332-b09/jre Default locale: en_US, platform encoding: UTF-8 OS name: "linux", version: "5.10.0-15-amd64", arch: "amd64", family: "unix" [Pipeline] sh + /var/lib/jenkins/tools/hudson.tasks.Maven_MavenInstallation/Maven_3.6.3/bin/mvn clean deploy [INFO] Scanning for projects... Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-application-parent/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 614 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-application-parent/1.25.0-SNAPSHOT/maven-metadata.xml (614 B at 437 B/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-application-bom/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 611 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-application-bom/1.25.0-SNAPSHOT/maven-metadata.xml (611 B at 34 kB/s) [INFO] [INFO] ---------------------< org.appng:appng-scheduler >---------------------- [INFO] Building Scheduler 1.17.0-SNAPSHOT [INFO] --------------------------------[ jar ]--------------------------------- Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-core/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 991 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-core/1.25.0-SNAPSHOT/maven-metadata.xml (991 B at 31 kB/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-forms/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 996 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-forms/1.25.0-SNAPSHOT/maven-metadata.xml (996 B at 66 kB/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-mail/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 995 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-mail/1.25.0-SNAPSHOT/maven-metadata.xml (995 B at 50 kB/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-api/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 994 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-api/1.25.0-SNAPSHOT/maven-metadata.xml (994 B at 7.6 kB/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-xmlapi/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 997 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-xmlapi/1.25.0-SNAPSHOT/maven-metadata.xml (997 B at 40 kB/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-tools/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 996 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-tools/1.25.0-SNAPSHOT/maven-metadata.xml (996 B at 59 kB/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-persistence/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 1.0 kB Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-persistence/1.25.0-SNAPSHOT/maven-metadata.xml (1.0 kB at 50 kB/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-search/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 997 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-search/1.25.0-SNAPSHOT/maven-metadata.xml (997 B at 47 kB/s) Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-testsupport/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 1.0 kB Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-testsupport/1.25.0-SNAPSHOT/maven-metadata.xml (1.0 kB at 24 kB/s) [INFO] [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ appng-scheduler --- [INFO] [INFO] --- openapi-generator-maven-plugin:5.3.0:generate (rest-api) @ appng-scheduler --- [INFO] Generating with dryRun=false [INFO] Output directory (/var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi) does not exist, or is inaccessible. No file (.openapi-generator-ignore) will be evaluated. [INFO] OpenAPI Generator: spring (server) [INFO] Generator 'spring' is considered stable. [INFO] ---------------------------------- [WARNING] java8 option has been deprecated as it's set to true by default (JDK7 support has been deprecated) [INFO] Set base package to invoker package (org.appng.scheduler.openapi.invoke) [INFO] Environment variable JAVA_POST_PROCESS_FILE not defined so the Java code may not be properly formatted. To define it, try 'export JAVA_POST_PROCESS_FILE="/usr/local/bin/clang-format -i"' (Linux/Mac) [INFO] NOTE: To enable file post-processing, 'enablePostProcessFile' must be set to `true` (--enable-post-process-file for CLI). [INFO] Processing operation getJobState [INFO] Processing operation getJobs [INFO] 'host' (OAS 2.0) or 'servers' (OAS 3.0) not defined in the spec. Default to [http://localhost] for server URL [http://localhost/] [INFO] 'host' (OAS 2.0) or 'servers' (OAS 3.0) not defined in the spec. Default to [http://localhost] for server URL [http://localhost/] [INFO] 'host' (OAS 2.0) or 'servers' (OAS 3.0) not defined in the spec. Default to [http://localhost] for server URL [http://localhost/] [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/model/Job.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/model/JobRecord.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/model/JobState.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/model/Jobs.java [INFO] 'host' (OAS 2.0) or 'servers' (OAS 3.0) not defined in the spec. Default to [http://localhost] for server URL [http://localhost/] [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/JobStateApi.java [INFO] 'host' (OAS 2.0) or 'servers' (OAS 3.0) not defined in the spec. Default to [http://localhost] for server URL [http://localhost/] [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/pom.xml [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/README.md [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/ApiUtil.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/.openapi-generator-ignore [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/.openapi-generator/VERSION [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/.openapi-generator/FILES ################################################################################ # Thanks for using OpenAPI Generator. # # Please consider donation to help us maintain this project 🙏 # # https://opencollective.com/openapi_generator/donate # ################################################################################ [INFO] [INFO] --- build-helper-maven-plugin:3.0.0:add-source (default) @ appng-scheduler --- [INFO] Source directory: /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/constants added. [INFO] Source directory: /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java added. [INFO] [INFO] --- appng-maven-plugin:1.25.0-SNAPSHOT:generateApplicationConstants (applicationConstants) @ appng-scheduler --- [INFO] generate application constants for [/var/lib/jenkins/workspace/appNG_Scheduler/application-home/application.xml, org.appng.application.scheduler.PropertyConstants, /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/constants] [INFO] [INFO] --- appng-maven-plugin:1.25.0-SNAPSHOT:generateMessageConstants (messageConstants) @ appng-scheduler --- [INFO] generate message constants for [/var/lib/jenkins/workspace/appNG_Scheduler/application-home/dictionary/messages-scheduler.properties, org.appng.application.scheduler.MessageConstants, /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/constants] [INFO] [INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ appng-scheduler --- [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] Copying 1 resource [INFO] [INFO] --- maven-compiler-plugin:3.3:compile (default-compile) @ appng-scheduler --- [INFO] Changes detected - recompiling the module! [INFO] Compiling 34 source files to /var/lib/jenkins/workspace/appNG_Scheduler/target/classes [INFO] [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ appng-scheduler --- [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] Copying 21 resources [INFO] [INFO] --- maven-compiler-plugin:3.3:testCompile (default-testCompile) @ appng-scheduler --- [INFO] Changes detected - recompiling the module! [INFO] Compiling 9 source files to /var/lib/jenkins/workspace/appNG_Scheduler/target/test-classes [INFO] [INFO] --- maven-surefire-plugin:3.0.0-M3:test (default-test) @ appng-scheduler --- [INFO] [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- [INFO] Running org.appng.application.scheduler.business.TestJobRecordings 2023-02-10 13:05:54,486 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Loaded default TestExecutionListener class names from location [META-INF/spring.factories]: [org.springframework.test.context.web.ServletTestExecutionListener, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener, org.springframework.test.context.support.DependencyInjectionTestExecutionListener, org.springframework.test.context.support.DirtiesContextTestExecutionListener, org.springframework.test.context.transaction.TransactionalTestExecutionListener, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener] 2023-02-10 13:05:54,523 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@61dd025, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@124c278f, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@15b204a1, org.springframework.test.context.support.DirtiesContextTestExecutionListener@77167fb7, org.springframework.test.context.transaction.TransactionalTestExecutionListener@1fe20588, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@6ce139a4] 2023-02-10 13:05:56,179 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2023-02-10 13:05:57,633 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-core.xml] 2023-02-10 13:05:58,798 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-jpa.xml] 2023-02-10 13:05:58,856 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test-core.xml] 2023-02-10 13:05:58,875 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'server' with a different definition: replacing [Generic bean: class [org.appng.testsupport.persistence.HsqlServerFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=init; destroyMethodName=destroy; defined in class path resource [org/appng/testsupport/application-testcontext-jpa.xml]] with [Generic bean: class [java.lang.Object]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=true; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [beans-test-core.xml]] 2023-02-10 13:05:58,876 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'datasource' with a different definition: replacing [Generic bean: class [org.springframework.jdbc.datasource.DriverManagerDataSource]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [org/appng/testsupport/application-testcontext-jpa.xml]] with [Generic bean: class [org.springframework.jdbc.datasource.DriverManagerDataSource]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [beans-test-core.xml]] 2023-02-10 13:05:58,885 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@36c88a32: startup date [Fri Feb 10 13:05:58 CET 2023]; root of context hierarchy 2023-02-10 13:05:59,416 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'byteArrayHttpMessageConverter' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=schedulerTestConfig; factoryMethodName=byteArrayHttpMessageConverter; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.application.scheduler.config.SchedulerTestConfig] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=schedulerConfig; factoryMethodName=byteArrayHttpMessageConverter; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.application.scheduler.configuration.SchedulerConfig] 2023-02-10 13:05:59,893 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 MappingJackson2HttpMessageConverters: 2023-02-10 13:05:59,894 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 ObjectMappers: 2023-02-10 13:05:59,894 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 Modules: 2023-02-10 13:05:59,905 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 @Primary Beans: 2023-02-10 13:06:01,748 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] No ObjectMapper found in context, creating default. 2023-02-10 13:06:01,773 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] No MappingJackson2HttpMessageConverter found in context, creating default. 2023-02-10 13:06:01,802 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Registering ObjectMapper 'defaultObjectMapper' 2023-02-10 13:06:01,802 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Registering MappingJackson2HttpMessageConverter 'defaultJacksonConverter' 2023-02-10 13:06:01,812 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2023-02-10 13:06:02,113 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:02,537 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default] 2023-02-10 13:06:03,262 INFO [main] org.hibernate.Version : HHH000412: Hibernate ORM core version 5.4.32.Final 2023-02-10 13:06:04,216 INFO [main] org.hibernate.annotations.common.Version: HCANN000001: Hibernate Commons Annotations {5.1.2.Final} Feb 10, 2023 1:06:05 PM ch.sla.jdbcperflogger.Logger info INFO: Using config file jdbcperflogger-fallback.xml 2023-02-10 13:06:08,014 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect 2023-02-10 13:06:08,167 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true 2023-02-10 13:06:09,533 INFO [main] org.hibernate.validator.internal.util.Version: HV000001: Hibernate Validator 6.2.0.Final 2023-02-10 13:06:11,733 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2023-02-10 13:06:11,745 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:11,970 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2023-02-10 13:06:11,989 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2023-02-10 13:06:11,989 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.2 created. 2023-02-10 13:06:12,047 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2023-02-10 13:06:12,049 INFO [main] org.quartz.core.QuartzScheduler: Scheduler meta-data: Quartz Scheduler (v2.3.2) 'Scheduler_localhost' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads. Using job-store 'org.springframework.scheduling.quartz.LocalDataSourceJobStore' - which supports persistence. and is not clustered. 2023-02-10 13:06:12,049 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2023-02-10 13:06:12,049 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.2 2023-02-10 13:06:12,050 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@a0e33db 2023-02-10 13:06:14,799 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context org.springframework.context.support.GenericApplicationContext@36c88a32: startup date [Fri Feb 10 13:05:58 CET 2023]; root of context hierarchy 2023-02-10 13:06:15,156 INFO [main] org.springframework.aop.framework.CglibAopProxy: Method [org.appng.core.service.MigrationService$MigrationStatus org.appng.core.service.DatabaseService.migrateApplication(java.io.File,org.appng.core.domain.SiteApplication,java.lang.String)] is package-visible across different ClassLoaders and cannot get proxied via CGLIB: Declare this method as public or protected if you need to support invocations through the proxy. 2023-02-10 13:06:15,156 INFO [main] org.springframework.aop.framework.CglibAopProxy: Method [org.appng.core.service.MigrationService$MigrationStatus org.appng.core.service.DatabaseService.dropDataBaseAndUser(org.appng.core.domain.DatabaseConnection)] is package-visible across different ClassLoaders and cannot get proxied via CGLIB: Declare this method as public or protected if you need to support invocations through the proxy. 2023-02-10 13:06:15,264 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2023-02-10 13:06:15,275 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] in 11 ms. 2023-02-10 13:06:18,088 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_longRunningJob 2023-02-10 13:06:18,120 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_longRunningJob-crontrigger-469805552' for job 'localhost.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2023-02-10 13:06:18,124 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_indexJob 2023-02-10 13:06:18,124 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:18,127 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_houseKeepingJob 2023-02-10 13:06:18,127 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:18,131 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2023-02-10 13:06:18,134 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2023-02-10 13:06:18,134 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2023-02-10 13:06:18,134 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2023-02-10 13:06:18,134 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2023-02-10 13:06:18,136 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:18,224 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@36c88a32: startup date [Fri Feb 10 13:05:58 CET 2023]; root of context hierarchy 2023-02-10 13:06:18,226 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2023-02-10 13:06:18,227 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2023-02-10 13:06:18,230 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2023-02-10 13:06:18,231 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2023-02-10 13:06:18,231 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2023-02-10 13:06:18,231 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. 2023-02-10 13:06:18,232 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:18,232 INFO [main] org.hibernate.tool.schema.internal.SchemaDropperImpl$DelayedDropActionImpl: HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down' [INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 25.459 s - in org.appng.application.scheduler.business.TestJobRecordings [INFO] Running org.appng.application.scheduler.SchedulerUtilsTest 2023-02-10 13:06:18,246 INFO [main] org.appng.application.scheduler.SchedulerUtils: Job 'mysite.myjob' was disabled forcefully and is being reenabled. [INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in org.appng.application.scheduler.SchedulerUtilsTest [INFO] Running org.appng.application.scheduler.SchedulingTest 2023-02-10 13:06:18,253 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Loaded default TestExecutionListener class names from location [META-INF/spring.factories]: [org.springframework.test.context.web.ServletTestExecutionListener, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener, org.springframework.test.context.support.DependencyInjectionTestExecutionListener, org.springframework.test.context.support.DirtiesContextTestExecutionListener, org.springframework.test.context.transaction.TransactionalTestExecutionListener, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener] 2023-02-10 13:06:18,253 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@20f94e9a, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@3f213e97, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@693c7741, org.springframework.test.context.support.DirtiesContextTestExecutionListener@7d3b4646, org.springframework.test.context.transaction.TransactionalTestExecutionListener@175c4ae5, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@21bf1b1f] 2023-02-10 13:06:18,271 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2023-02-10 13:06:18,311 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-core.xml] 2023-02-10 13:06:18,356 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-jpa.xml] 2023-02-10 13:06:18,368 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test.xml] 2023-02-10 13:06:18,378 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'jobExecutionRecordRepository' with a different definition: replacing [Root bean: class [org.appng.persistence.repository.SearchRepositoryFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Generic bean: class [org.mockito.Mockito]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=mock; initMethodName=null; destroyMethodName=null; defined in class path resource [beans-test.xml]] 2023-02-10 13:06:18,379 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@2b33e616: startup date [Fri Feb 10 13:06:18 CET 2023]; root of context hierarchy 2023-02-10 13:06:18,407 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'byteArrayHttpMessageConverter' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=schedulerTestConfig; factoryMethodName=byteArrayHttpMessageConverter; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.application.scheduler.config.SchedulerTestConfig] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=schedulerConfig; factoryMethodName=byteArrayHttpMessageConverter; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.application.scheduler.configuration.SchedulerConfig] 2023-02-10 13:06:18,417 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 MappingJackson2HttpMessageConverters: 2023-02-10 13:06:18,417 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 ObjectMappers: 2023-02-10 13:06:18,417 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 Modules: 2023-02-10 13:06:18,418 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 @Primary Beans: 2023-02-10 13:06:18,418 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] No ObjectMapper found in context, creating default. 2023-02-10 13:06:18,418 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] No MappingJackson2HttpMessageConverter found in context, creating default. 2023-02-10 13:06:18,418 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Registering ObjectMapper 'defaultObjectMapper' 2023-02-10 13:06:18,418 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Registering MappingJackson2HttpMessageConverter 'defaultJacksonConverter' 2023-02-10 13:06:18,421 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2023-02-10 13:06:18,474 INFO [HSQLDB Server @1f40bb80] hsqldb.db.HSQLDB863B381C59.ENGINE: Checkpoint start 2023-02-10 13:06:18,474 INFO [HSQLDB Server @1f40bb80] hsqldb.db.HSQLDB863B381C59.ENGINE: checkpointClose start 2023-02-10 13:06:18,628 INFO [HSQLDB Server @1f40bb80] hsqldb.db.HSQLDB863B381C59.ENGINE: checkpointClose synched 2023-02-10 13:06:18,638 INFO [HSQLDB Server @1f40bb80] hsqldb.db.HSQLDB863B381C59.ENGINE: checkpointClose script done 2023-02-10 13:06:18,655 INFO [HSQLDB Server @1f40bb80] hsqldb.db.HSQLDB863B381C59.ENGINE: checkpointClose end 2023-02-10 13:06:18,656 INFO [HSQLDB Server @1f40bb80] hsqldb.db.HSQLDB863B381C59.ENGINE: Checkpoint end - txts: 1 2023-02-10 13:06:18,770 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:18,770 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default] 2023-02-10 13:06:18,975 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect 2023-02-10 13:06:18,989 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true 2023-02-10 13:06:19,570 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2023-02-10 13:06:19,571 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:19,620 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2023-02-10 13:06:19,621 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2023-02-10 13:06:19,621 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.2 created. 2023-02-10 13:06:19,621 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2023-02-10 13:06:19,622 INFO [main] org.quartz.core.QuartzScheduler: Scheduler meta-data: Quartz Scheduler (v2.3.2) 'Scheduler_localhost' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads. Using job-store 'org.springframework.scheduling.quartz.LocalDataSourceJobStore' - which supports persistence. and is not clustered. 2023-02-10 13:06:19,622 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2023-02-10 13:06:19,622 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.2 2023-02-10 13:06:19,622 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@74efc394 2023-02-10 13:06:19,813 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context org.springframework.context.support.GenericApplicationContext@2b33e616: startup date [Fri Feb 10 13:06:18 CET 2023]; root of context hierarchy 2023-02-10 13:06:20,135 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2023-02-10 13:06:20,143 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] in 8 ms. 2023-02-10 13:06:20,232 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_longRunningJob 2023-02-10 13:06:20,239 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_longRunningJob-crontrigger-1293299047' for job 'localhost.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2023-02-10 13:06:20,242 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_indexJob 2023-02-10 13:06:20,242 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,244 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_houseKeepingJob 2023-02-10 13:06:20,244 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,245 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2023-02-10 13:06:20,246 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2023-02-10 13:06:20,246 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2023-02-10 13:06:20,247 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2023-02-10 13:06:20,247 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2023-02-10 13:06:20,248 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:20,321 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_mytestjob 2023-02-10 13:06:20,326 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_mytestjob-crontrigger-1666683319' for job 'localhost.appng-scheduler_mytestjob' with expression '0 0/10 * * * ? 2042' 2023-02-10 13:06:20,347 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_anotherJob 2023-02-10 13:06:20,351 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_anotherJob-crontrigger-1394849424' for job 'localhost.appng-scheduler_anotherJob' with expression '0 0/20 * * * ? 2042' 2023-02-10 13:06:20,398 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,401 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,401 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:20,418 INFO [main] org.appng.application.scheduler.SchedulerUtils: Deleted job: localhost.appng-scheduler_mytestjob 2023-02-10 13:06:20,460 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,463 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,463 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:20,473 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_indexJob-crontrigger-1445689627' for job 'localhost.appng-scheduler_indexJob' with expression '0 0/5 * * * ? 2042' 2023-02-10 13:06:20,559 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,559 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:20,602 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,603 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:20,682 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,682 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:20,692 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_indexJob-simpletrigger-1242586395' for job 'org.appng.application.scheduler.job.IndexJob' with start time 'Fri Feb 10 13:06:20 CET 2023' 2023-02-10 13:06:20,730 ERROR [Scheduler_localhost] org.quartz.core.ErrorLogger : An error occured instantiating job to be executed. job= 'localhost.appng-scheduler_indexJob' org.quartz.SchedulerException: Job instantiation failed [See nested exception: java.lang.ClassCastException: org.springframework.context.support.GenericApplicationContext cannot be cast to org.appng.core.model.ApplicationContext] at org.springframework.scheduling.quartz.AdaptableJobFactory.newJob(AdaptableJobFactory.java:45) at org.quartz.core.JobRunShell.initialize(JobRunShell.java:127) at org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:392) Caused by: java.lang.ClassCastException: org.springframework.context.support.GenericApplicationContext cannot be cast to org.appng.core.model.ApplicationContext at org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory.createJobInstance(SpringQuartzSchedulerFactory.java:68) at org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory.createJobInstance(SpringQuartzSchedulerFactory.java:53) at org.springframework.scheduling.quartz.AdaptableJobFactory.newJob(AdaptableJobFactory.java:41) ... 2 more 2023-02-10 13:06:20,736 INFO [Scheduler_localhost] org.springframework.scheduling.quartz.LocalDataSourceJobStore: All triggers of Job localhost.appng-scheduler_indexJob-simpletrigger-1242586395 set to ERROR state. 2023-02-10 13:06:20,757 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,757 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:20,767 INFO [main] org.appng.application.scheduler.SchedulerUtils: Deleted trigger 'localhost.appng-scheduler_indexJob-crontrigger-1445689627' for job 'localhost.appng-scheduler_indexJob' with expression '0 0/5 * * * ? 2042' 2023-02-10 13:06:20,798 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,800 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: localhost, application: appng-scheduler) 2023-02-10 13:06:20,801 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:20,835 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@2b33e616: startup date [Fri Feb 10 13:06:18 CET 2023]; root of context hierarchy 2023-02-10 13:06:20,835 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2023-02-10 13:06:20,835 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2023-02-10 13:06:20,837 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2023-02-10 13:06:20,837 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2023-02-10 13:06:20,837 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2023-02-10 13:06:20,837 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. 2023-02-10 13:06:20,837 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:20,838 INFO [main] org.hibernate.tool.schema.internal.SchemaDropperImpl$DelayedDropActionImpl: HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down' 2023-02-10 13:06:21,087 INFO [HSQLDB Connection @475a89c2] hsqldb.db.HSQLDB863B381C59.ENGINE: Database closed [INFO] Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3 s - in org.appng.application.scheduler.SchedulingTest [INFO] Running org.appng.application.scheduler.DriverDelegateWrapperTest 2023-02-10 13:06:21,307 INFO [main] org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory: Starting embedded database: url='jdbc:hsqldb:mem:testdb', username='sa' 2023-02-10 13:06:21,367 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [sql/init_quartz_tables.sql] 2023-02-10 13:06:21,374 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [sql/init_quartz_tables.sql] in 7 ms. 2023-02-10 13:06:21,394 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: Using thread monitor-based data access locking (synchronization). 2023-02-10 13:06:21,394 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: JobStoreTX initialized. 2023-02-10 13:06:21,395 INFO [main] org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory: Starting embedded database: url='jdbc:hsqldb:mem:testdb', username='sa' 2023-02-10 13:06:21,396 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [sql/init_quartz_tables.sql] 2023-02-10 13:06:21,414 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [sql/init_quartz_tables.sql] in 18 ms. 2023-02-10 13:06:21,414 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: Using thread monitor-based data access locking (synchronization). 2023-02-10 13:06:21,414 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: JobStoreTX initialized. [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.396 s - in org.appng.application.scheduler.DriverDelegateWrapperTest [INFO] Running org.appng.application.scheduler.service.JobStateRestControllerTest 2023-02-10 13:06:21,428 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Loaded default TestExecutionListener class names from location [META-INF/spring.factories]: [org.springframework.test.context.web.ServletTestExecutionListener, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener, org.springframework.test.context.support.DependencyInjectionTestExecutionListener, org.springframework.test.context.support.DirtiesContextTestExecutionListener, org.springframework.test.context.transaction.TransactionalTestExecutionListener, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener] 2023-02-10 13:06:21,428 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@5e7967a, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@46577744, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@7807e2bb, org.springframework.test.context.support.DirtiesContextTestExecutionListener@68e7f7c4, org.springframework.test.context.transaction.TransactionalTestExecutionListener@510af8e4, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@2c1a48d6] 2023-02-10 13:06:21,439 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2023-02-10 13:06:21,471 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-core.xml] 2023-02-10 13:06:21,538 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-jpa.xml] 2023-02-10 13:06:21,549 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test-core.xml] 2023-02-10 13:06:21,557 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'server' with a different definition: replacing [Generic bean: class [org.appng.testsupport.persistence.HsqlServerFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=init; destroyMethodName=destroy; defined in class path resource [org/appng/testsupport/application-testcontext-jpa.xml]] with [Generic bean: class [java.lang.Object]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=true; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [beans-test-core.xml]] 2023-02-10 13:06:21,557 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'datasource' with a different definition: replacing [Generic bean: class [org.springframework.jdbc.datasource.DriverManagerDataSource]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [org/appng/testsupport/application-testcontext-jpa.xml]] with [Generic bean: class [org.springframework.jdbc.datasource.DriverManagerDataSource]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [beans-test-core.xml]] 2023-02-10 13:06:21,558 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@6bcf75d4: startup date [Fri Feb 10 13:06:21 CET 2023]; root of context hierarchy 2023-02-10 13:06:21,601 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'byteArrayHttpMessageConverter' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=schedulerTestConfig; factoryMethodName=byteArrayHttpMessageConverter; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.application.scheduler.config.SchedulerTestConfig] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=schedulerConfig; factoryMethodName=byteArrayHttpMessageConverter; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.application.scheduler.configuration.SchedulerConfig] 2023-02-10 13:06:21,617 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 MappingJackson2HttpMessageConverters: 2023-02-10 13:06:21,617 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 ObjectMappers: 2023-02-10 13:06:21,617 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 Modules: 2023-02-10 13:06:21,618 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Found 0 @Primary Beans: 2023-02-10 13:06:21,618 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] No ObjectMapper found in context, creating default. 2023-02-10 13:06:21,618 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] No MappingJackson2HttpMessageConverter found in context, creating default. 2023-02-10 13:06:21,618 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Registering ObjectMapper 'defaultObjectMapper' 2023-02-10 13:06:21,618 INFO [main] org.appng.api.config.RestConfig: [appng:appng-scheduler] Registering MappingJackson2HttpMessageConverter 'defaultJacksonConverter' 2023-02-10 13:06:21,619 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2023-02-10 13:06:21,666 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:21,666 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default] 2023-02-10 13:06:21,744 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect 2023-02-10 13:06:21,749 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true 2023-02-10 13:06:21,998 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2023-02-10 13:06:21,998 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:22,013 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2023-02-10 13:06:22,013 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2023-02-10 13:06:22,013 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.2 created. 2023-02-10 13:06:22,014 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2023-02-10 13:06:22,014 INFO [main] org.quartz.core.QuartzScheduler: Scheduler meta-data: Quartz Scheduler (v2.3.2) 'Scheduler_localhost' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 3 threads. Using job-store 'org.springframework.scheduling.quartz.LocalDataSourceJobStore' - which supports persistence. and is not clustered. 2023-02-10 13:06:22,014 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2023-02-10 13:06:22,014 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.2 2023-02-10 13:06:22,014 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@19b6eefb 2023-02-10 13:06:22,149 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context org.springframework.context.support.GenericApplicationContext@6bcf75d4: startup date [Fri Feb 10 13:06:21 CET 2023]; root of context hierarchy 2023-02-10 13:06:22,380 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2023-02-10 13:06:22,388 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] in 8 ms. 2023-02-10 13:06:23,616 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobs(java.lang.Boolean,java.lang.Boolean,java.lang.Boolean) 2023-02-10 13:06:23,617 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2023-02-10 13:06:24,080 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@50c903e 2023-02-10 13:06:24,505 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2023-02-10 13:06:24,505 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2023-02-10 13:06:24,510 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 4 ms 2023-02-10 13:06:24,525 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: appng.appng-scheduler_longRunningJob 2023-02-10 13:06:24,530 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'appng.appng-scheduler_longRunningJob-crontrigger-141432590' for job 'appng.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2023-02-10 13:06:24,532 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: appng.appng-scheduler_indexJob 2023-02-10 13:06:24,532 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: appng, application: appng-scheduler) 2023-02-10 13:06:24,534 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: appng.appng-scheduler_houseKeepingJob 2023-02-10 13:06:24,534 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: appng, application: appng-scheduler) 2023-02-10 13:06:24,535 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2023-02-10 13:06:24,535 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2023-02-10 13:06:24,535 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2023-02-10 13:06:24,536 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2023-02-10 13:06:24,536 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2023-02-10 13:06:24,537 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:25,061 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobs(java.lang.Boolean,java.lang.Boolean,java.lang.Boolean) 2023-02-10 13:06:25,061 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2023-02-10 13:06:25,073 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@26be15d7 2023-02-10 13:06:25,078 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2023-02-10 13:06:25,078 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2023-02-10 13:06:25,078 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 0 ms 2023-02-10 13:06:25,085 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: appng, application: appng-scheduler) 2023-02-10 13:06:25,087 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: appng, application: appng-scheduler) 2023-02-10 13:06:25,087 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:25,138 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobs(java.lang.Boolean,java.lang.Boolean,java.lang.Boolean) 2023-02-10 13:06:25,138 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2023-02-10 13:06:25,150 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@71867474 2023-02-10 13:06:25,155 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2023-02-10 13:06:25,155 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2023-02-10 13:06:25,155 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 0 ms 2023-02-10 13:06:25,162 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: appng, application: appng-scheduler) 2023-02-10 13:06:25,164 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: appng, application: appng-scheduler) 2023-02-10 13:06:25,164 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:25,190 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobs(java.lang.Boolean,java.lang.Boolean,java.lang.Boolean) 2023-02-10 13:06:25,190 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2023-02-10 13:06:25,201 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@356d9441 2023-02-10 13:06:25,206 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2023-02-10 13:06:25,206 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2023-02-10 13:06:25,206 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 0 ms 2023-02-10 13:06:25,212 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'indexJob' is disabled (site: appng, application: appng-scheduler) 2023-02-10 13:06:25,214 INFO [main] org.appng.application.scheduler.business.SchedulingController: Job 'houseKeepingJob' is disabled (site: appng, application: appng-scheduler) 2023-02-10 13:06:25,214 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2023-02-10 13:06:25,230 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@6bcf75d4: startup date [Fri Feb 10 13:06:21 CET 2023]; root of context hierarchy 2023-02-10 13:06:25,231 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2023-02-10 13:06:25,231 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2023-02-10 13:06:25,233 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2023-02-10 13:06:25,233 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2023-02-10 13:06:25,233 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2023-02-10 13:06:25,234 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. 2023-02-10 13:06:25,234 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default' 2023-02-10 13:06:25,234 INFO [main] org.hibernate.tool.schema.internal.SchemaDropperImpl$DelayedDropActionImpl: HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down' [INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.535 s - in org.appng.application.scheduler.service.JobStateRestControllerTest [INFO] [INFO] Results: [INFO] [INFO] Tests run: 20, Failures: 0, Errors: 0, Skipped: 0 [INFO] [INFO] [INFO] --- maven-jar-plugin:3.0.2:jar (default-jar) @ appng-scheduler --- [INFO] Building jar: /var/lib/jenkins/workspace/appNG_Scheduler/target/appng-scheduler-1.17.0-SNAPSHOT.jar [INFO] [INFO] --- maven-javadoc-plugin:2.10.4:jar (generate-docs) @ appng-scheduler --- [INFO] Loading source file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/ApiUtil.java... Loading source file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/model/JobRecord.java... Loading source file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/model/Job.java... Loading source file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/model/JobState.java... Loading source file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/model/Jobs.java... Loading source file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/JobStateApi.java... Constructing Javadoc information... Standard Doclet version 1.8.0_332 Building tree for all the packages and classes... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/ApiUtil.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/JobStateApi.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/Job.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/JobRecord.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/JobRecord.StateEnum.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/Jobs.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/JobState.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/JobState.StateNameEnum.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/overview-frame.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/package-frame.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/package-summary.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/package-tree.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/package-frame.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/package-summary.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/package-tree.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/constant-values.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/class-use/ApiUtil.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/class-use/JobRecord.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/class-use/JobRecord.StateEnum.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/class-use/Job.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/class-use/JobState.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/class-use/JobState.StateNameEnum.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/class-use/Jobs.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/class-use/JobStateApi.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/package-use.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/org/appng/scheduler/openapi/model/package-use.html... Building index for all the packages and classes... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/overview-tree.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/index-all.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/deprecated-list.html... Building index for all classes... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/allclasses-frame.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/allclasses-noframe.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/index.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/overview-summary.html... Generating /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs/help-doc.html... [INFO] Building jar: /var/lib/jenkins/workspace/appNG_Scheduler/target/appng-scheduler-1.17.0-SNAPSHOT-javadoc.jar [INFO] [INFO] --- maven-assembly-plugin:3.0.0:single (default) @ appng-scheduler --- Downloading from apache.snapshots: https://repository.apache.org/snapshots/org/appng/appng-application-assembly/1.25.0-SNAPSHOT/maven-metadata.xml Downloading from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-parent/1.25.0-SNAPSHOT/maven-metadata.xml Progress (1): 604 B Downloaded from appng-snapshot: https://www.appng.org/nexus/repository/appng-snapshot/org/appng/appng-parent/1.25.0-SNAPSHOT/maven-metadata.xml (604 B at 21 kB/s) Downloading from sonatype-nexus-snapshots: https://oss.sonatype.org/content/repositories/snapshots/org/appng/appng-application-parent/1.25.0-SNAPSHOT/maven-metadata.xml [INFO] Building zip: /var/lib/jenkins/workspace/appNG_Scheduler/target/appng-scheduler-1.17.0-SNAPSHOT-20230210-1204.zip [INFO] [INFO] --- maven-source-plugin:3.0.1:jar-no-fork (default) @ appng-scheduler --- [INFO] Building jar: /var/lib/jenkins/workspace/appNG_Scheduler/target/appng-scheduler-1.17.0-SNAPSHOT-sources.jar [INFO] [INFO] --- maven-install-plugin:2.4:install (default-install) @ appng-scheduler --- [INFO] Installing /var/lib/jenkins/workspace/appNG_Scheduler/target/appng-scheduler-1.17.0-SNAPSHOT.jar to /var/lib/jenkins/.m2/repository/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-SNAPSHOT.jar [INFO] Installing /var/lib/jenkins/workspace/appNG_Scheduler/pom.xml to /var/lib/jenkins/.m2/repository/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-SNAPSHOT.pom [INFO] Installing /var/lib/jenkins/workspace/appNG_Scheduler/target/appng-scheduler-1.17.0-SNAPSHOT-javadoc.jar to /var/lib/jenkins/.m2/repository/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-SNAPSHOT-javadoc.jar [INFO] Installing /var/lib/jenkins/workspace/appNG_Scheduler/target/appng-scheduler-1.17.0-SNAPSHOT-20230210-1204.zip to /var/lib/jenkins/.m2/repository/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-SNAPSHOT.zip [INFO] Installing /var/lib/jenkins/workspace/appNG_Scheduler/target/appng-scheduler-1.17.0-SNAPSHOT-sources.jar to /var/lib/jenkins/.m2/repository/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-SNAPSHOT-sources.jar [INFO] [INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ appng-scheduler --- Downloading from appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml Progress (1): 1.4 kB Downloaded from appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml (1.4 kB at 6.5 kB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4.jar Progress (1): 4.1/110 kB Progress (1): 8.2/110 kB Progress (1): 12/110 kB Progress (1): 16/110 kB Progress (1): 20/110 kB Progress (1): 25/110 kB Progress (1): 29/110 kB Progress (1): 33/110 kB Progress (1): 37/110 kB Progress (1): 41/110 kB Progress (1): 45/110 kB Progress (1): 49/110 kB Progress (1): 53/110 kB Progress (1): 57/110 kB Progress (1): 61/110 kB Progress (1): 66/110 kB Progress (1): 70/110 kB Progress (1): 74/110 kB Progress (1): 78/110 kB Progress (1): 82/110 kB Progress (1): 86/110 kB Progress (1): 90/110 kB Progress (1): 94/110 kB Progress (1): 98/110 kB Progress (1): 102/110 kB Progress (1): 106/110 kB Progress (1): 110 kB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4.jar (110 kB at 296 kB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4.pom Progress (1): 4.1/10 kB Progress (1): 8.2/10 kB Progress (1): 10 kB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4.pom (10 kB at 33 kB/s) Downloading from appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/maven-metadata.xml Progress (1): 695 B Downloaded from appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/maven-metadata.xml (695 B at 6.3 kB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml Progress (1): 1.4 kB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml (1.4 kB at 1.2 kB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/maven-metadata.xml Progress (1): 695 B Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/maven-metadata.xml (695 B at 1.0 kB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4-javadoc.jar Progress (1): 4.1/78 kB Progress (1): 8.2/78 kB Progress (1): 12/78 kB Progress (1): 16/78 kB Progress (1): 20/78 kB Progress (1): 25/78 kB Progress (1): 29/78 kB Progress (1): 33/78 kB Progress (1): 37/78 kB Progress (1): 41/78 kB Progress (1): 45/78 kB Progress (1): 49/78 kB Progress (1): 53/78 kB Progress (1): 57/78 kB Progress (1): 61/78 kB Progress (1): 66/78 kB Progress (1): 70/78 kB Progress (1): 74/78 kB Progress (1): 78/78 kB Progress (1): 78 kB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4-javadoc.jar (78 kB at 1.6 MB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml Progress (1): 1.4 kB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml (1.4 kB at 2.3 kB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4.zip Progress (1): 0/1.1 MB Progress (1): 0/1.1 MB Progress (1): 0/1.1 MB Progress (1): 0/1.1 MB Progress (1): 0/1.1 MB Progress (1): 0/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.1/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.2/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.3/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.4/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.5/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.6/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.7/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.8/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 0.9/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.0/1.1 MB Progress (1): 1.1/1.1 MB Progress (1): 1.1/1.1 MB Progress (1): 1.1/1.1 MB Progress (1): 1.1/1.1 MB Progress (1): 1.1/1.1 MB Progress (1): 1.1/1.1 MB Progress (1): 1.1/1.1 MB Progress (1): 1.1 MB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4.zip (1.1 MB at 10 MB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml Progress (1): 1.4 kB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml (1.4 kB at 1.1 kB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4-sources.jar Progress (1): 4.1/64 kB Progress (1): 8.2/64 kB Progress (1): 12/64 kB Progress (1): 16/64 kB Progress (1): 20/64 kB Progress (1): 25/64 kB Progress (1): 29/64 kB Progress (1): 33/64 kB Progress (1): 37/64 kB Progress (1): 41/64 kB Progress (1): 45/64 kB Progress (1): 49/64 kB Progress (1): 53/64 kB Progress (1): 57/64 kB Progress (1): 61/64 kB Progress (1): 64 kB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/appng-scheduler-1.17.0-20230210.120440-4-sources.jar (64 kB at 500 kB/s) Uploading to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml Progress (1): 1.4 kB Uploaded to appng-snapshot: https://appng.org/nexus/repository/appng-snapshot/org/appng/appng-scheduler/1.17.0-SNAPSHOT/maven-metadata.xml (1.4 kB at 1.1 kB/s) [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ [INFO] Total time: 02:03 min [INFO] Finished at: 2023-02-10T13:06:44+01:00 [INFO] ------------------------------------------------------------------------ [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Stash the Build) [Pipeline] dir Running in /var/lib/jenkins/workspace/appNG_Scheduler/target [Pipeline] { [Pipeline] stash Stashed 1 file(s) [Pipeline] } [Pipeline] // dir [Pipeline] dir Running in /var/lib/jenkins/workspace/appNG_Scheduler/target/apidocs [Pipeline] { [Pipeline] stash Stashed 37 file(s) [Pipeline] } [Pipeline] // dir [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Results) [Pipeline] junit Recording test results [Checks API] No suitable checks publisher found. [Pipeline] } [Pipeline] // stage [Pipeline] } [Pipeline] // node [Pipeline] node Running on Jenkins in /var/lib/jenkins/workspace/appNG_Scheduler [Pipeline] { [Pipeline] stage [Pipeline] { (Prepare doc folder) [Pipeline] sh + rm -rf /srv/www/appng.org/appng-scheduler/docs/1.15.x [Pipeline] sh + mkdir --parents /srv/www/appng.org/appng-scheduler/docs/1.15.x/javadoc [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Unstash Build) [Pipeline] dir Running in /srv/www/appng.org/appng/builds/snapshot [Pipeline] { [Pipeline] unstash [Pipeline] } [Pipeline] // dir [Pipeline] dir Running in /srv/www/appng.org/appng-scheduler/docs/1.15.x/javadoc [Pipeline] { [Pipeline] unstash [Pipeline] } [Pipeline] // dir [Pipeline] } [Pipeline] // stage [Pipeline] } [Pipeline] // node [Pipeline] End of Pipeline Finished: SUCCESS