Started by user Matthias Mueller Running as Matthias Mueller Running in Durability level: MAX_SURVIVABILITY [Pipeline] Start of Pipeline [Pipeline] node Running on Jenkins in /var/lib/jenkins/workspace/appNG_Scheduler [Pipeline] { [Pipeline] tool [Pipeline] stage [Pipeline] { (git clone) [Pipeline] git The recommended git tool is: NONE No credentials specified > git rev-parse --is-inside-work-tree # timeout=10 Fetching changes from the remote Git repository > git config remote.origin.url git@github.com:appNG/appng-scheduler.git # timeout=10 Fetching upstream changes from git@github.com:appNG/appng-scheduler.git > git --version # timeout=10 > git --version # 'git version 2.11.0' > git fetch --tags --progress -- git@github.com:appNG/appng-scheduler.git +refs/heads/*:refs/remotes/origin/* # timeout=10 > git rev-parse refs/remotes/origin/appng-scheduler-1.14.x^{commit} # timeout=10 Checking out Revision 121d3eeeda41c09a75046fc4983de4136432c07c (refs/remotes/origin/appng-scheduler-1.14.x) > git config core.sparsecheckout # timeout=10 > git checkout -f 121d3eeeda41c09a75046fc4983de4136432c07c # timeout=10 > git branch -a -v --no-abbrev # timeout=10 > git branch -D appng-scheduler-1.14.x # timeout=10 > git checkout -b appng-scheduler-1.14.x 121d3eeeda41c09a75046fc4983de4136432c07c # timeout=10 Commit message: "SCHED-43" > git rev-list --no-walk 121d3eeeda41c09a75046fc4983de4136432c07c # timeout=10 [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Maven Build) [Pipeline] sh + /var/lib/jenkins/tools/hudson.tasks.Maven_MavenInstallation/Maven_3.5.0/bin/mvn clean deploy [INFO] Scanning for projects... [INFO] [INFO] ------------------------------------------------------------------------ [INFO] Building Scheduler 1.14.0-SNAPSHOT [INFO] ------------------------------------------------------------------------ [INFO] [INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ appng-scheduler --- [INFO] Deleting /var/lib/jenkins/workspace/appNG_Scheduler/target [INFO] [INFO] --- openapi-generator-maven-plugin:5.1.1: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: java (client) [INFO] Generator 'java' is considered stable. [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] '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/JobRecord.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/test/java/org/appng/scheduler/openapi/model/JobRecordTest.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/docs/JobRecord.md [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/test/java/org/appng/scheduler/openapi/model/JobStateTest.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/docs/JobState.md [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/SchedulerApi.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/test/java/org/appng/scheduler/openapi/SchedulerApiTest.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/docs/SchedulerApi.md [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/build.gradle [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/build.sbt [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/settings.gradle [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/gradle.properties [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/AndroidManifest.xml [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/.travis.yml [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/ApiClient.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/ServerConfiguration.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/ServerVariable.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/api/openapi.yaml [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/JavaTimeFormatter.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/auth/HttpBasicAuth.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/auth/HttpBearerAuth.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/auth/ApiKeyAuth.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/gradlew [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/gradlew.bat [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/gradle/wrapper/gradle-wrapper.properties [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/gradle/wrapper/gradle-wrapper.jar [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/git_push.sh [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/.gitignore [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/auth/Authentication.java [INFO] writing file /var/lib/jenkins/workspace/appNG_Scheduler/target/generated-sources/openapi/src/main/java/org/appng/scheduler/openapi/invoke/RFC3339DateFormat.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.20.2: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.20.2: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 38 source files to /var/lib/jenkins/workspace/appNG_Scheduler/target/classes [INFO] /var/lib/jenkins/workspace/appNG_Scheduler/src/main/java/org/appng/application/scheduler/quartz/DriverDelegateWrapper.java: /var/lib/jenkins/workspace/appNG_Scheduler/src/main/java/org/appng/application/scheduler/quartz/DriverDelegateWrapper.java uses or overrides a deprecated API. [INFO] /var/lib/jenkins/workspace/appNG_Scheduler/src/main/java/org/appng/application/scheduler/quartz/DriverDelegateWrapper.java: Recompile with -Xlint:deprecation for details. [INFO] [INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ appng-scheduler --- [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] Copying 19 resources [INFO] [INFO] --- maven-compiler-plugin:3.3:testCompile (default-testCompile) @ appng-scheduler --- [INFO] Changes detected - recompiling the module! [INFO] Compiling 6 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.SchedulingTest 2021-09-24 09:51:15,724 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] 2021-09-24 09:51:15,741 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@23529fee, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@4fe767f3, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@2805c96b, org.springframework.test.context.support.DirtiesContextTestExecutionListener@184cf7cf, org.springframework.test.context.transaction.TransactionalTestExecutionListener@2fd6b6c7, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@5bfa9431] 2021-09-24 09:51:16,489 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2021-09-24 09:51:16,791 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext.xml] 2021-09-24 09:51:16,822 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test.xml] 2021-09-24 09:51:16,851 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@7880cdf3: startup date [Fri Sep 24 09:51:16 CEST 2021]; root of context hierarchy 2021-09-24 09:51:17,181 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring Sep 24, 2021 9:51:17 AM ch.sla.jdbcperflogger.Logger info INFO: Using config file jdbcperflogger-fallback.xml 2021-09-24 09:51:18,048 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2021-09-24 09:51:18,072 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2021-09-24 09:51:18,072 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.1 created. 2021-09-24 09:51:18,086 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2021-09-24 09:51:18,087 INFO [main] org.quartz.core.QuartzScheduler: Scheduler meta-data: Quartz Scheduler (v2.3.1) '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. 2021-09-24 09:51:18,087 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2021-09-24 09:51:18,087 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.1 2021-09-24 09:51:18,088 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@64f857e7 2021-09-24 09:51:18,389 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2021-09-24 09:51:18,408 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] in 19 ms. 2021-09-24 09:51:18,976 INFO [main] org.hibernate.validator.internal.util.Version: HV000001: Hibernate Validator 6.0.17.Final 2021-09-24 09:51:19,261 WARN [main] org.appng.api.support.RequestFactoryBean: invalid value for platform property 'uploadDir', folder 'null/target/uploads' does not exist! 2021-09-24 09:51:19,438 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_longRunningJob 2021-09-24 09:51:19,471 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_longRunningJob-crontrigger-1908505175' for job 'localhost.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2021-09-24 09:51:19,475 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_indexJob 2021-09-24 09:51:19,479 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_houseKeepingJob 2021-09-24 09:51:19,485 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2021-09-24 09:51:19,487 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2021-09-24 09:51:19,487 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2021-09-24 09:51:19,488 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2021-09-24 09:51:19,491 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2021-09-24 09:51:19,493 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:19,680 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_mytestjob 2021-09-24 09:51:19,686 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_mytestjob-crontrigger-853955254' for job 'localhost.appng-scheduler_mytestjob' with expression '0 0/10 * * * ? 2042' 2021-09-24 09:51:20,572 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_anotherJob 2021-09-24 09:51:20,578 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_anotherJob-crontrigger-503427379' for job 'localhost.appng-scheduler_anotherJob' with expression '0 0/20 * * * ? 2042' 2021-09-24 09:51:20,631 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:20,651 INFO [main] org.appng.application.scheduler.SchedulerUtils: Deleted job: localhost.appng-scheduler_mytestjob 2021-09-24 09:51:20,893 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:20,904 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_indexJob-crontrigger-1391249801' for job 'localhost.appng-scheduler_indexJob' with expression '0 0/5 * * * ? 2042' 2021-09-24 09:51:20,950 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:21,074 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:21,269 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:21,282 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.simpletrigger-1563929103' for job 'org.appng.application.scheduler.job.IndexJob' with start time 'Fri Sep 24 09:51:21 CEST 2021' 2021-09-24 09:51:21,346 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 2021-09-24 09:51:21,350 INFO [Scheduler_localhost] org.springframework.scheduling.quartz.LocalDataSourceJobStore: All triggers of Job localhost.simpletrigger-1563929103 set to ERROR state. 2021-09-24 09:51:21,387 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:21,396 INFO [main] org.appng.application.scheduler.SchedulerUtils: Deleted trigger 'localhost.appng-scheduler_indexJob-crontrigger-1391249801' for job 'localhost.appng-scheduler_indexJob' with expression '0 0/5 * * * ? 2042' 2021-09-24 09:51:21,437 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:21,491 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@7880cdf3: startup date [Fri Sep 24 09:51:16 CEST 2021]; root of context hierarchy 2021-09-24 09:51:21,494 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2021-09-24 09:51:21,495 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2021-09-24 09:51:21,497 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2021-09-24 09:51:21,497 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2021-09-24 09:51:21,497 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2021-09-24 09:51:21,497 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. [INFO] Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.118 s - in org.appng.application.scheduler.SchedulingTest [INFO] Running org.appng.application.scheduler.service.JobStateRestControllerTest 2021-09-24 09:51:21,509 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] 2021-09-24 09:51:21,509 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@573870cb, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@5b88af70, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@2739ecc0, org.springframework.test.context.support.DirtiesContextTestExecutionListener@2932e15f, org.springframework.test.context.transaction.TransactionalTestExecutionListener@3d98729a, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@2375a976] 2021-09-24 09:51:21,517 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2021-09-24 09:51:21,602 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext.xml] 2021-09-24 09:51:21,619 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test.xml] 2021-09-24 09:51:21,635 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@4bc21e34: startup date [Fri Sep 24 09:51:21 CEST 2021]; root of context hierarchy 2021-09-24 09:51:21,668 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2021-09-24 09:51:21,704 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2021-09-24 09:51:21,706 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2021-09-24 09:51:21,706 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.1 created. 2021-09-24 09:51:21,706 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2021-09-24 09:51:21,706 INFO [main] org.quartz.core.QuartzScheduler: Scheduler meta-data: Quartz Scheduler (v2.3.1) '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. 2021-09-24 09:51:21,706 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2021-09-24 09:51:21,706 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.1 2021-09-24 09:51:21,707 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@7809b43a 2021-09-24 09:51:21,796 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2021-09-24 09:51:21,818 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] in 22 ms. 2021-09-24 09:51:21,910 WARN [main] org.appng.api.support.RequestFactoryBean: invalid value for platform property 'uploadDir', folder 'null/target/uploads' does not exist! 2021-09-24 09:51:21,930 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_longRunningJob 2021-09-24 09:51:21,936 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_longRunningJob-crontrigger-1175156694' for job 'localhost.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2021-09-24 09:51:21,939 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_indexJob 2021-09-24 09:51:21,941 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_houseKeepingJob 2021-09-24 09:51:21,942 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2021-09-24 09:51:21,943 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2021-09-24 09:51:21,944 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2021-09-24 09:51:21,944 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2021-09-24 09:51:21,944 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2021-09-24 09:51:21,951 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:22,165 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,boolean,java.util.List,org.appng.api.model.Site) 2021-09-24 09:51:22,231 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@3340ce58 2021-09-24 09:51:22,348 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2021-09-24 09:51:22,348 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2021-09-24 09:51:22,351 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 3 ms 2021-09-24 09:51:22,540 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:22,542 INFO [main] org.springframework.test.web.servlet.setup.StandaloneMockMvcBuilder$StaticRequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,boolean,java.util.List,org.appng.api.model.Site) 2021-09-24 09:51:22,562 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@212c0aff 2021-09-24 09:51:22,570 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2021-09-24 09:51:22,570 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2021-09-24 09:51:22,570 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 0 ms 2021-09-24 09:51:22,579 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@4bc21e34: startup date [Fri Sep 24 09:51:21 CEST 2021]; root of context hierarchy 2021-09-24 09:51:22,579 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2021-09-24 09:51:22,579 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2021-09-24 09:51:22,580 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2021-09-24 09:51:22,580 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2021-09-24 09:51:22,580 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2021-09-24 09:51:22,581 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.011 s - in org.appng.application.scheduler.service.JobStateRestControllerTest [INFO] Running org.appng.application.scheduler.DriverDelegateWrapperTest 2021-09-24 09:51:22,602 INFO [main] org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory: Starting embedded database: url='jdbc:hsqldb:mem:testdb', username='sa' 2021-09-24 09:51:22,630 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [sql/init_quartz_tables.sql] 2021-09-24 09:51:22,638 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [sql/init_quartz_tables.sql] in 8 ms. 2021-09-24 09:51:22,643 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: Using thread monitor-based data access locking (synchronization). 2021-09-24 09:51:22,643 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: JobStoreTX initialized. 2021-09-24 09:51:22,645 INFO [main] org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory: Starting embedded database: url='jdbc:hsqldb:mem:testdb', username='sa' 2021-09-24 09:51:22,645 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [sql/init_quartz_tables.sql] 2021-09-24 09:51:22,656 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [sql/init_quartz_tables.sql] in 11 ms. 2021-09-24 09:51:22,656 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: Using thread monitor-based data access locking (synchronization). 2021-09-24 09:51:22,656 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: JobStoreTX initialized. [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.072 s - in org.appng.application.scheduler.DriverDelegateWrapperTest [INFO] Running org.appng.application.scheduler.business.TestJobRecordings 2021-09-24 09:51:22,672 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] 2021-09-24 09:51:22,673 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@34065642, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@c85486, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@51e1e058, org.springframework.test.context.support.DirtiesContextTestExecutionListener@23e0c200, org.springframework.test.context.transaction.TransactionalTestExecutionListener@25d23478, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@677c2820] 2021-09-24 09:51:22,679 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2021-09-24 09:51:22,732 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext.xml] 2021-09-24 09:51:22,742 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-core.xml] 2021-09-24 09:51:22,834 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-jpa.xml] 2021-09-24 09:51:22,847 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test-core.xml] 2021-09-24 09:51:22,856 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@39c1e7b7: startup date [Fri Sep 24 09:51:22 CEST 2021]; root of context hierarchy 2021-09-24 09:51:22,931 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2021-09-24 09:51:23,016 INFO [HSQLDB Server @6a8da5c5] hsqldb.db.HSQLDB7C16C99873.ENGINE: Checkpoint start 2021-09-24 09:51:23,016 INFO [HSQLDB Server @6a8da5c5] hsqldb.db.HSQLDB7C16C99873.ENGINE: checkpointClose start 2021-09-24 09:51:23,057 INFO [HSQLDB Server @6a8da5c5] hsqldb.db.HSQLDB7C16C99873.ENGINE: checkpointClose synched 2021-09-24 09:51:23,110 INFO [HSQLDB Server @6a8da5c5] hsqldb.db.HSQLDB7C16C99873.ENGINE: checkpointClose script done 2021-09-24 09:51:23,130 INFO [HSQLDB Server @6a8da5c5] hsqldb.db.HSQLDB7C16C99873.ENGINE: checkpointClose end 2021-09-24 09:51:23,130 INFO [HSQLDB Server @6a8da5c5] hsqldb.db.HSQLDB7C16C99873.ENGINE: Checkpoint end - txts: 1 2021-09-24 09:51:23,251 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default' 2021-09-24 09:51:23,278 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default] 2021-09-24 09:51:23,410 INFO [main] org.hibernate.Version : HHH000412: Hibernate Core {5.4.3.Final} 2021-09-24 09:51:23,660 INFO [main] org.hibernate.annotations.common.Version: HCANN000001: Hibernate Commons Annotations {5.1.0.Final} 2021-09-24 09:51:24,242 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect 2021-09-24 09:51:24,332 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true 2021-09-24 09:51:26,365 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2021-09-24 09:51:26,369 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default' 2021-09-24 09:51:26,510 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2021-09-24 09:51:26,511 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2021-09-24 09:51:26,511 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.1 created. 2021-09-24 09:51:26,613 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2021-09-24 09:51:26,613 INFO [main] org.quartz.core.QuartzScheduler: Scheduler meta-data: Quartz Scheduler (v2.3.1) '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. 2021-09-24 09:51:26,613 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2021-09-24 09:51:26,613 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.1 2021-09-24 09:51:26,613 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@47b5c247 2021-09-24 09:51:27,618 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context org.springframework.context.support.GenericApplicationContext@39c1e7b7: startup date [Fri Sep 24 09:51:22 CEST 2021]; root of context hierarchy 2021-09-24 09:51:28,057 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.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. 2021-09-24 09:51:28,057 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. 2021-09-24 09:51:28,292 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2021-09-24 09:51:28,318 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] in 26 ms. 2021-09-24 09:51:28,385 WARN [main] org.appng.api.support.RequestFactoryBean: invalid value for platform property 'uploadDir', folder 'null/target/uploads' does not exist! 2021-09-24 09:51:29,577 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_longRunningJob 2021-09-24 09:51:29,710 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_longRunningJob-crontrigger-1311212526' for job 'localhost.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2021-09-24 09:51:29,739 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_indexJob 2021-09-24 09:51:29,788 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_houseKeepingJob 2021-09-24 09:51:29,895 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2021-09-24 09:51:29,908 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2021-09-24 09:51:29,908 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2021-09-24 09:51:29,917 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2021-09-24 09:51:29,917 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2021-09-24 09:51:29,918 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2021-09-24 09:51:30,081 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@39c1e7b7: startup date [Fri Sep 24 09:51:22 CEST 2021]; root of context hierarchy 2021-09-24 09:51:30,081 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2021-09-24 09:51:30,081 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2021-09-24 09:51:30,188 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2021-09-24 09:51:30,188 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2021-09-24 09:51:30,188 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2021-09-24 09:51:30,189 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. 2021-09-24 09:51:30,189 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default' 2021-09-24 09:51:30,189 INFO [main] org.hibernate.tool.schema.internal.SchemaDropperImpl$DelayedDropActionImpl: HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down' 2021-09-24 09:51:30,598 INFO [HSQLDB Connection @9288759] hsqldb.db.HSQLDB7C16C99873.ENGINE: Database closed [ERROR] Tests run: 5, Failures: 3, Errors: 0, Skipped: 0, Time elapsed: 8.135 s <<< FAILURE! - in org.appng.application.scheduler.business.TestJobRecordings [ERROR] testGetRecordsForJobId(org.appng.application.scheduler.business.TestJobRecordings) Time elapsed: 0.145 s <<< FAILURE! junit.framework.AssertionFailedError: XML does not match control XML org.custommonkey.xmlunit.Diff [different] Expected number of element attributes '4' but was '3' - comparing at /datasource[1]/config[1]/meta-data[1]/field[3] to at /datasource[1]/config[1]/meta-data[1]/field[3] Recorded Job Executions thejob led_red led_green preview , org.custommonkey.xmlunit.Diff [different] Expected number of element attributes '4' but was '3' - comparing at /datasource[1]/config[1]/meta-data[1]/field[3] to at /datasource[1]/config[1]/meta-data[1]/field[3] at org.appng.application.scheduler.business.TestJobRecordings.testGetRecordsForJobId(TestJobRecordings.java:137) [ERROR] testGetRecordsWithFilter(org.appng.application.scheduler.business.TestJobRecordings) Time elapsed: 0.137 s <<< FAILURE! junit.framework.AssertionFailedError: XML does not match control XML org.custommonkey.xmlunit.Diff [different] Expected number of element attributes '4' but was '3' - comparing at /datasource[1]/config[1]/meta-data[1]/field[3] to at /datasource[1]/config[1]/meta-data[1]/field[3] Recorded Job Executions thejob led_red led_green preview , org.custommonkey.xmlunit.Diff [different] Expected number of element attributes '4' but was '3' - comparing at /datasource[1]/config[1]/meta-data[1]/field[3] to at /datasource[1]/config[1]/meta-data[1]/field[3] at org.appng.application.scheduler.business.TestJobRecordings.testGetRecordsWithFilter(TestJobRecordings.java:150) [ERROR] testJobDetails(org.appng.application.scheduler.business.TestJobRecordings) Time elapsed: 0.651 s <<< FAILURE! junit.framework.AssertionFailedError: XML does not match control XML org.custommonkey.xmlunit.Diff [different] Expected text value '{ "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : "true", "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost" }' but was '{ "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" }' - comparing { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : "true", "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost" } at /datasource[1]/data[1]/result[1]/field[4]/value[1]/text()[1] to { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" } at /datasource[1]/data[1]/result[1]/field[4]/value[1]/text()[1] Jobs appng-scheduler_longRunningJob appng-scheduler_longRunningJob 0 0/10 * 1/1 * ? * { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" } , org.custommonkey.xmlunit.Diff [different] Expected text value '{ "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : "true", "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost" }' but was '{ "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" }' - comparing { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : "true", "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost" } at /datasource[1]/data[1]/result[1]/field[4]/value[1]/text()[1] to { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" } at /datasource[1]/data[1]/result[1]/field[4]/value[1]/text()[1] at org.appng.application.scheduler.business.TestJobRecordings.testJobDetails(TestJobRecordings.java:94) [INFO] [INFO] Results: [INFO] [ERROR] Failures: [ERROR] TestJobRecordings.testGetRecordsForJobId:137->TestBase.validate:528 XML does not match control XML org.custommonkey.xmlunit.Diff [different] Expected number of element attributes '4' but was '3' - comparing at /datasource[1]/config[1]/meta-data[1]/field[3] to at /datasource[1]/config[1]/meta-data[1]/field[3] Recorded Job Executions thejob led_red led_green preview , org.custommonkey.xmlunit.Diff [different] Expected number of element attributes '4' but was '3' - comparing at /datasource[1]/config[1]/meta-data[1]/field[3] to at /datasource[1]/config[1]/meta-data[1]/field[3] [ERROR] TestJobRecordings.testGetRecordsWithFilter:150->TestBase.validate:528 XML does not match control XML org.custommonkey.xmlunit.Diff [different] Expected number of element attributes '4' but was '3' - comparing at /datasource[1]/config[1]/meta-data[1]/field[3] to at /datasource[1]/config[1]/meta-data[1]/field[3] Recorded Job Executions thejob led_red led_green preview , org.custommonkey.xmlunit.Diff [different] Expected number of element attributes '4' but was '3' - comparing at /datasource[1]/config[1]/meta-data[1]/field[3] to at /datasource[1]/config[1]/meta-data[1]/field[3] [ERROR] TestJobRecordings.testJobDetails:94->TestBase.validate:523 XML does not match control XML org.custommonkey.xmlunit.Diff [different] Expected text value '{ "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : "true", "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost" }' but was '{ "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" }' - comparing { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : "true", "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost" } at /datasource[1]/data[1]/result[1]/field[4]/value[1]/text()[1] to { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" } at /datasource[1]/data[1]/result[1]/field[4]/value[1]/text()[1] Jobs appng-scheduler_longRunningJob appng-scheduler_longRunningJob 0 0/10 * 1/1 * ? * { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" } , org.custommonkey.xmlunit.Diff [different] Expected text value '{ "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : "true", "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost" }' but was '{ "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" }' - comparing { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : "true", "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost" } at /datasource[1]/data[1]/result[1]/field[4]/value[1]/text()[1] to { "SCHEDULED_JOB" : "org.appng.application.scheduler.job.LongRunningJob", "cronExpression" : "0 0/10 * 1/1 * ? *", "enabled" : true, "forceState" : true, "jobBeanName" : "longRunningJob", "origin" : "appng-scheduler", "siteName" : "localhost", "thresholdError" : 5, "thresholdTimeunit" : "DAY" } at /datasource[1]/data[1]/result[1]/field[4]/value[1]/text()[1] [INFO] [ERROR] Tests run: 17, Failures: 3, Errors: 0, Skipped: 0 [INFO] [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 25.730 s [INFO] Finished at: 2021-09-24T09:51:31+02:00 [INFO] Final Memory: 73M/816M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:3.0.0-M3:test (default-test) on project appng-scheduler: There are test failures. [ERROR] [ERROR] Please refer to /var/lib/jenkins/workspace/appNG_Scheduler/target/surefire-reports for the individual test results. [ERROR] Please refer to dump files (if any exist) [date].dump, [date]-jvmRun[N].dump and [date].dumpstream. [ERROR] -> [Help 1] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException [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.14.x [Pipeline] sh + mkdir --parents /srv/www/appng.org/appng-scheduler/docs/1.14.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] } [Pipeline] // stage [Pipeline] } [Pipeline] // node [Pipeline] End of Pipeline ERROR: No such saved stash ‘jenkins_artifacts’ Finished: FAILURE