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 --resolve-git-dir /var/lib/jenkins/workspace/appNG_Scheduler/.git # 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 2c5b6306fea6564034444807d72499e2cf4b718c (refs/remotes/origin/appng-scheduler-1.14.x) > git config core.sparsecheckout # timeout=10 > git checkout -f 2c5b6306fea6564034444807d72499e2cf4b718c # 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 2c5b6306fea6564034444807d72499e2cf4b718c # timeout=10 Commit message: "SCHED-43" > git rev-list --no-walk a925bf708057d5cd8ab6d50ccd375bc0a520abd8 # 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.2-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.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.23.0: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.23.0: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 32 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 20 resources [INFO] [INFO] --- maven-compiler-plugin:3.3:testCompile (default-testCompile) @ appng-scheduler --- [INFO] Changes detected - recompiling the module! [INFO] Compiling 8 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 2022-01-14 12:47:32,585 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] 2022-01-14 12:47:32,600 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@14d3bc22, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@12d4bf7e, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@4c1d9d4b, org.springframework.test.context.support.DirtiesContextTestExecutionListener@7b227d8d, org.springframework.test.context.transaction.TransactionalTestExecutionListener@7219ec67, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@45018215] 2022-01-14 12:47:33,356 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2022-01-14 12:47:33,639 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-core.xml] 2022-01-14 12:47:33,788 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-jpa.xml] 2022-01-14 12:47:33,812 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test.xml] 2022-01-14 12:47:33,850 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]] 2022-01-14 12:47:33,854 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@49b0b76: startup date [Fri Jan 14 12:47:33 CET 2022]; root of context hierarchy 2022-01-14 12:47:34,339 WARN [main] org.springframework.context.annotation.ConfigurationClassEnhancer: @Bean method ApplicationTestConfig.CustomScopeConfigurer is non-static and returns an object assignable to Spring's BeanFactoryPostProcessor interface. This will result in a failure to process annotations such as @Autowired, @Resource and @PostConstruct within the method's declaring @Configuration class. Add the 'static' modifier to this method to avoid these container lifecycle issues; see @Bean javadoc for complete details. 2022-01-14 12:47:34,360 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring Jan 14, 2022 12:47:34 PM ch.sla.jdbcperflogger.Logger info INFO: Using config file jdbcperflogger-fallback.xml 2022-01-14 12:47:35,054 INFO [HSQLDB Server @1bb9aa43] hsqldb.db.HSQLDB7E586A17F3.ENGINE: Checkpoint start 2022-01-14 12:47:35,054 INFO [HSQLDB Server @1bb9aa43] hsqldb.db.HSQLDB7E586A17F3.ENGINE: checkpointClose start 2022-01-14 12:47:35,061 INFO [HSQLDB Server @1bb9aa43] hsqldb.db.HSQLDB7E586A17F3.ENGINE: checkpointClose synched 2022-01-14 12:47:35,094 INFO [HSQLDB Server @1bb9aa43] hsqldb.db.HSQLDB7E586A17F3.ENGINE: checkpointClose script done 2022-01-14 12:47:35,136 INFO [HSQLDB Server @1bb9aa43] hsqldb.db.HSQLDB7E586A17F3.ENGINE: checkpointClose end 2022-01-14 12:47:35,137 INFO [HSQLDB Server @1bb9aa43] hsqldb.db.HSQLDB7E586A17F3.ENGINE: Checkpoint end - txts: 1 2022-01-14 12:47:35,316 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:35,342 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default] 2022-01-14 12:47:35,424 INFO [main] org.hibernate.Version : HHH000412: Hibernate ORM core version 5.4.32.Final 2022-01-14 12:47:35,625 INFO [main] org.hibernate.annotations.common.Version: HCANN000001: Hibernate Commons Annotations {5.1.2.Final} 2022-01-14 12:47:36,137 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect 2022-01-14 12:47:36,226 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true 2022-01-14 12:47:36,878 INFO [main] org.hibernate.validator.internal.util.Version: HV000001: Hibernate Validator 6.2.0.Final 2022-01-14 12:47:38,666 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2022-01-14 12:47:38,684 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:38,970 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2022-01-14 12:47:38,993 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2022-01-14 12:47:38,993 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.2 created. 2022-01-14 12:47:39,008 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2022-01-14 12:47:39,009 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. 2022-01-14 12:47:39,009 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2022-01-14 12:47:39,009 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.2 2022-01-14 12:47:39,010 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@4b7ab7ab 2022-01-14 12:47:39,346 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context org.springframework.context.support.GenericApplicationContext@49b0b76: startup date [Fri Jan 14 12:47:33 CET 2022]; root of context hierarchy 2022-01-14 12:47:40,370 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. 2022-01-14 12:47:40,370 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. 2022-01-14 12:47:40,520 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2022-01-14 12:47:40,532 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] in 12 ms. 2022-01-14 12:47:40,588 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:40,590 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:40,592 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobRecords],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<java.util.List<org.appng.application.scheduler.model.JobRecord>> org.appng.application.scheduler.service.RecordsRestController.getJobRecords(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.Integer,java.util.List<java.lang.String>,org.appng.api.model.Application,org.appng.api.model.Site) 2022-01-14 12:47:40,622 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:40,622 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:40,623 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobRecords],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<java.util.List<org.appng.application.scheduler.model.JobRecord>> org.appng.application.scheduler.service.RecordsRestController.getJobRecords(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.Integer,java.util.List<java.lang.String>,org.appng.api.model.Application,org.appng.api.model.Site) 2022-01-14 12:47:41,451 WARN [main] org.appng.api.support.RequestFactoryBean: invalid value for platform property 'uploadDir', folder 'null/target/uploads' does not exist! 2022-01-14 12:47:41,536 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_longRunningJob 2022-01-14 12:47:41,578 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_longRunningJob-crontrigger-1354033074' for job 'localhost.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2022-01-14 12:47:41,582 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_indexJob 2022-01-14 12:47:41,584 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_houseKeepingJob 2022-01-14 12:47:41,589 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2022-01-14 12:47:41,591 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2022-01-14 12:47:41,591 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2022-01-14 12:47:41,592 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2022-01-14 12:47:41,592 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2022-01-14 12:47:41,594 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:41,722 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_mytestjob 2022-01-14 12:47:41,728 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_mytestjob-crontrigger-1055297202' for job 'localhost.appng-scheduler_mytestjob' with expression '0 0/10 * * * ? 2042' 2022-01-14 12:47:42,975 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_anotherJob 2022-01-14 12:47:42,980 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_anotherJob-crontrigger-1690529928' for job 'localhost.appng-scheduler_anotherJob' with expression '0 0/20 * * * ? 2042' 2022-01-14 12:47:43,038 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:43,059 INFO [main] org.appng.application.scheduler.SchedulerUtils: Deleted job: localhost.appng-scheduler_mytestjob 2022-01-14 12:47:43,101 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:43,112 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_indexJob-crontrigger-1579030959' for job 'localhost.appng-scheduler_indexJob' with expression '0 0/5 * * * ? 2042' 2022-01-14 12:47:43,155 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:43,258 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:43,411 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:43,421 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.simpletrigger-1352505756' for job 'org.appng.application.scheduler.job.IndexJob' with start time 'Fri Jan 14 12:47:43 CET 2022' 2022-01-14 12:47:43,532 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:43,551 INFO [main] org.appng.application.scheduler.SchedulerUtils: Deleted trigger 'localhost.appng-scheduler_indexJob-crontrigger-1579030959' for job 'localhost.appng-scheduler_indexJob' with expression '0 0/5 * * * ? 2042' 2022-01-14 12:47:43,574 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 2022-01-14 12:47:43,580 INFO [Scheduler_localhost] org.springframework.scheduling.quartz.LocalDataSourceJobStore: All triggers of Job localhost.simpletrigger-1352505756 set to ERROR state. 2022-01-14 12:47:43,618 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:43,661 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@49b0b76: startup date [Fri Jan 14 12:47:33 CET 2022]; root of context hierarchy 2022-01-14 12:47:43,664 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2022-01-14 12:47:43,665 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2022-01-14 12:47:43,669 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2022-01-14 12:47:43,670 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2022-01-14 12:47:43,670 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2022-01-14 12:47:43,671 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. 2022-01-14 12:47:43,672 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:43,673 INFO [main] org.hibernate.tool.schema.internal.SchemaDropperImpl$DelayedDropActionImpl: HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down' 2022-01-14 12:47:44,090 INFO [HSQLDB Connection @12101056] hsqldb.db.HSQLDB7E586A17F3.ENGINE: Database closed [INFO] Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 12.047 s - in org.appng.application.scheduler.SchedulingTest [INFO] Running org.appng.application.scheduler.service.JobStateRestControllerTest 2022-01-14 12:47:44,327 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] 2022-01-14 12:47:44,330 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@41dcfd0, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@1fcb01f8, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@59845cb3, org.springframework.test.context.support.DirtiesContextTestExecutionListener@63ee2891, org.springframework.test.context.transaction.TransactionalTestExecutionListener@9670873, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@4ee41c66] 2022-01-14 12:47:44,342 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2022-01-14 12:47:44,428 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-core.xml] 2022-01-14 12:47:44,473 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-jpa.xml] 2022-01-14 12:47:44,485 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test-core.xml] 2022-01-14 12:47:44,494 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]] 2022-01-14 12:47:44,495 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]] 2022-01-14 12:47:44,495 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@35fea0cd: startup date [Fri Jan 14 12:47:44 CET 2022]; root of context hierarchy 2022-01-14 12:47:44,529 WARN [main] org.springframework.context.annotation.ConfigurationClassEnhancer: @Bean method ApplicationTestConfig.CustomScopeConfigurer is non-static and returns an object assignable to Spring's BeanFactoryPostProcessor interface. This will result in a failure to process annotations such as @Autowired, @Resource and @PostConstruct within the method's declaring @Configuration class. Add the 'static' modifier to this method to avoid these container lifecycle issues; see @Bean javadoc for complete details. 2022-01-14 12:47:44,531 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2022-01-14 12:47:44,558 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:44,558 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default] 2022-01-14 12:47:44,710 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect 2022-01-14 12:47:44,717 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true 2022-01-14 12:47:45,095 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2022-01-14 12:47:45,095 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:45,115 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2022-01-14 12:47:45,116 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2022-01-14 12:47:45,116 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.2 created. 2022-01-14 12:47:45,116 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2022-01-14 12:47:45,116 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. 2022-01-14 12:47:45,116 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2022-01-14 12:47:45,116 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.2 2022-01-14 12:47:45,116 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@2c95cb24 2022-01-14 12:47:45,282 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context org.springframework.context.support.GenericApplicationContext@35fea0cd: startup date [Fri Jan 14 12:47:44 CET 2022]; root of context hierarchy 2022-01-14 12:47:45,571 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2022-01-14 12:47:45,579 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. 2022-01-14 12:47:45,583 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:45,584 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:45,584 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobRecords],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<java.util.List<org.appng.application.scheduler.model.JobRecord>> org.appng.application.scheduler.service.RecordsRestController.getJobRecords(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.Integer,java.util.List<java.lang.String>,org.appng.api.model.Application,org.appng.api.model.Site) 2022-01-14 12:47:45,589 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:45,589 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:45,590 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobRecords],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<java.util.List<org.appng.application.scheduler.model.JobRecord>> org.appng.application.scheduler.service.RecordsRestController.getJobRecords(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.Integer,java.util.List<java.lang.String>,org.appng.api.model.Application,org.appng.api.model.Site) 2022-01-14 12:47:45,656 WARN [main] org.appng.api.support.RequestFactoryBean: invalid value for platform property 'uploadDir', folder 'null/target/uploads' does not exist! 2022-01-14 12:47:45,758 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.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:45,759 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.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:45,843 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@e944541 2022-01-14 12:47:45,919 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2022-01-14 12:47:45,919 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2022-01-14 12:47:45,922 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 2 ms 2022-01-14 12:47:45,944 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: appng.appng-scheduler_longRunningJob 2022-01-14 12:47:45,949 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'appng.appng-scheduler_longRunningJob-crontrigger-662267010' for job 'appng.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2022-01-14 12:47:45,950 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: appng.appng-scheduler_indexJob 2022-01-14 12:47:45,952 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: appng.appng-scheduler_houseKeepingJob 2022-01-14 12:47:45,953 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2022-01-14 12:47:45,954 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2022-01-14 12:47:45,954 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2022-01-14 12:47:45,954 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2022-01-14 12:47:45,955 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2022-01-14 12:47:45,955 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:46,237 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.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:46,237 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.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:46,249 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@339b1c34 2022-01-14 12:47:46,254 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2022-01-14 12:47:46,254 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2022-01-14 12:47:46,254 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 0 ms 2022-01-14 12:47:46,264 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:46,301 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.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:46,301 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.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:46,314 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.test.web.servlet.setup.StubWebApplicationContext@3f114f13 2022-01-14 12:47:46,319 INFO [main] org.springframework.mock.web.MockServletContext: Initializing Spring FrameworkServlet '' 2022-01-14 12:47:46,319 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization started 2022-01-14 12:47:46,320 INFO [main] org.springframework.test.web.servlet.TestDispatcherServlet: FrameworkServlet '': initialization completed in 1 ms 2022-01-14 12:47:46,329 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:46,345 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@35fea0cd: startup date [Fri Jan 14 12:47:44 CET 2022]; root of context hierarchy 2022-01-14 12:47:46,345 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2022-01-14 12:47:46,345 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2022-01-14 12:47:46,348 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2022-01-14 12:47:46,348 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2022-01-14 12:47:46,348 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2022-01-14 12:47:46,350 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. 2022-01-14 12:47:46,350 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:46,350 INFO [main] org.hibernate.tool.schema.internal.SchemaDropperImpl$DelayedDropActionImpl: HHH000477: Starting delayed evictData of schema as part of SessionFactory shut-down' [ERROR] Tests run: 3, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 2.029 s <<< FAILURE! - in org.appng.application.scheduler.service.JobStateRestControllerTest [ERROR] testJobs(org.appng.application.scheduler.service.JobStateRestControllerTest) Time elapsed: 0.064 s <<< ERROR! org.springframework.web.util.NestedServletException: Request processing failed; nested exception is java.lang.NullPointerException at org.appng.application.scheduler.service.JobStateRestControllerTest.testJobs(JobStateRestControllerTest.java:91) Caused by: java.lang.NullPointerException at org.appng.application.scheduler.service.JobStateRestControllerTest.testJobs(JobStateRestControllerTest.java:91) [INFO] Running org.appng.application.scheduler.DriverDelegateWrapperTest 2022-01-14 12:47:46,370 INFO [main] org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory: Starting embedded database: url='jdbc:hsqldb:mem:testdb', username='sa' 2022-01-14 12:47:46,385 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [sql/init_quartz_tables.sql] 2022-01-14 12:47:46,392 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [sql/init_quartz_tables.sql] in 7 ms. 2022-01-14 12:47:46,399 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: Using thread monitor-based data access locking (synchronization). 2022-01-14 12:47:46,399 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: JobStoreTX initialized. 2022-01-14 12:47:46,401 INFO [main] org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory: Starting embedded database: url='jdbc:hsqldb:mem:testdb', username='sa' 2022-01-14 12:47:46,401 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [sql/init_quartz_tables.sql] 2022-01-14 12:47:46,410 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [sql/init_quartz_tables.sql] in 9 ms. 2022-01-14 12:47:46,411 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: Using thread monitor-based data access locking (synchronization). 2022-01-14 12:47:46,411 INFO [main] org.quartz.impl.jdbcjobstore.JobStoreTX: JobStoreTX initialized. [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.043 s - in org.appng.application.scheduler.DriverDelegateWrapperTest [INFO] Running org.appng.application.scheduler.business.TestJobRecordings 2022-01-14 12:47:46,426 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] 2022-01-14 12:47:46,427 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@1261fd82, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@5dea9867, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@1de36da2, org.springframework.test.context.support.DirtiesContextTestExecutionListener@2e4c4373, org.springframework.test.context.transaction.TransactionalTestExecutionListener@33dc36d5, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@3afca969] 2022-01-14 12:47:46,434 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from URL [file:application-home/beans.xml] 2022-01-14 12:47:46,470 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-core.xml] 2022-01-14 12:47:46,512 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext-jpa.xml] 2022-01-14 12:47:46,522 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [beans-test-core.xml] 2022-01-14 12:47:46,531 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]] 2022-01-14 12:47:46,531 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]] 2022-01-14 12:47:46,531 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@3c744de9: startup date [Fri Jan 14 12:47:46 CET 2022]; root of context hierarchy 2022-01-14 12:47:46,560 WARN [main] org.springframework.context.annotation.ConfigurationClassEnhancer: @Bean method ApplicationTestConfig.CustomScopeConfigurer is non-static and returns an object assignable to Spring's BeanFactoryPostProcessor interface. This will result in a failure to process annotations such as @Autowired, @Resource and @PostConstruct within the method's declaring @Configuration class. Add the 'static' modifier to this method to avoid these container lifecycle issues; see @Bean javadoc for complete details. 2022-01-14 12:47:46,561 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring 2022-01-14 12:47:46,585 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:46,585 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default] 2022-01-14 12:47:46,654 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect 2022-01-14 12:47:46,660 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true 2022-01-14 12:47:46,929 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform] 2022-01-14 12:47:46,930 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:46,952 INFO [main] org.quartz.impl.StdSchedulerFactory: Using default implementation for ThreadExecutor 2022-01-14 12:47:46,955 INFO [main] org.quartz.core.SchedulerSignalerImpl: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 2022-01-14 12:47:46,955 INFO [main] org.quartz.core.QuartzScheduler: Quartz Scheduler v.2.3.2 created. 2022-01-14 12:47:46,956 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: JobStoreCMT initialized. 2022-01-14 12:47:46,956 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. 2022-01-14 12:47:46,956 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler 'Scheduler_localhost' initialized from an externally provided properties instance. 2022-01-14 12:47:46,956 INFO [main] org.quartz.impl.StdSchedulerFactory: Quartz scheduler version: 2.3.2 2022-01-14 12:47:46,956 INFO [main] org.quartz.core.QuartzScheduler: JobFactory set to: org.appng.application.scheduler.quartz.SpringQuartzSchedulerFactory@3feafba2 2022-01-14 12:47:47,179 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context org.springframework.context.support.GenericApplicationContext@3c744de9: startup date [Fri Jan 14 12:47:46 CET 2022]; root of context hierarchy 2022-01-14 12:47:47,569 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executing SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] 2022-01-14 12:47:47,582 INFO [main] org.springframework.jdbc.datasource.init.ScriptUtils: Executed SQL script from class path resource [org/quartz/impl/jdbcjobstore/tables_hsqldb.sql] in 13 ms. 2022-01-14 12:47:47,587 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:47,588 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:47,588 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobRecords],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<java.util.List<org.appng.application.scheduler.model.JobRecord>> org.appng.application.scheduler.service.RecordsRestController.getJobRecords(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.Integer,java.util.List<java.lang.String>,org.appng.api.model.Application,org.appng.api.model.Site) 2022-01-14 12:47:47,593 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/list],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.Jobs> org.appng.application.scheduler.service.JobStateRestController.getJobs() 2022-01-14 12:47:47,593 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobState/{application}/{job}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity<org.appng.scheduler.openapi.model.JobState> org.appng.application.scheduler.service.JobStateRestController.getJobState(java.lang.String,java.lang.String,java.lang.Integer,java.lang.Boolean) 2022-01-14 12:47:47,594 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/jobRecords],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<java.util.List<org.appng.application.scheduler.model.JobRecord>> org.appng.application.scheduler.service.RecordsRestController.getJobRecords(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.Integer,java.util.List<java.lang.String>,org.appng.api.model.Application,org.appng.api.model.Site) 2022-01-14 12:47:47,686 WARN [main] org.appng.api.support.RequestFactoryBean: invalid value for platform property 'uploadDir', folder 'null/target/uploads' does not exist! 2022-01-14 12:47:47,980 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_longRunningJob 2022-01-14 12:47:47,984 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created trigger 'localhost.appng-scheduler_longRunningJob-crontrigger-909845674' for job 'localhost.appng-scheduler_longRunningJob' with expression '0 0/10 * 1/1 * ? *' 2022-01-14 12:47:47,986 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_indexJob 2022-01-14 12:47:47,987 INFO [main] org.appng.application.scheduler.SchedulerUtils: Created job: localhost.appng-scheduler_houseKeepingJob 2022-01-14 12:47:47,988 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Freed 0 triggers from 'acquired' / 'blocked' state. 2022-01-14 12:47:47,988 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovering 0 jobs that were in-progress at the time of the last shut-down. 2022-01-14 12:47:47,989 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Recovery complete. 2022-01-14 12:47:47,989 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 'complete' triggers. 2022-01-14 12:47:47,989 INFO [main] org.springframework.scheduling.quartz.LocalDataSourceJobStore: Removed 0 stale fired job entries. 2022-01-14 12:47:47,991 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED started. 2022-01-14 12:47:48,015 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@3c744de9: startup date [Fri Jan 14 12:47:46 CET 2022]; root of context hierarchy 2022-01-14 12:47:48,016 INFO [main] org.springframework.context.support.DefaultLifecycleProcessor: Stopping beans in phase 2147483647 2022-01-14 12:47:48,016 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2022-01-14 12:47:48,017 INFO [main] org.springframework.scheduling.quartz.SchedulerFactoryBean: Shutting down Quartz Scheduler 2022-01-14 12:47:48,017 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutting down. 2022-01-14 12:47:48,017 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED paused. 2022-01-14 12:47:48,018 INFO [main] org.quartz.core.QuartzScheduler: Scheduler Scheduler_localhost_$_NON_CLUSTERED shutdown complete. 2022-01-14 12:47:48,018 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default' 2022-01-14 12:47:48,019 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: 1.595 s - in org.appng.application.scheduler.business.TestJobRecordings [INFO] [INFO] Results: [INFO] [ERROR] Errors: [ERROR] JobStateRestControllerTest.testJobs:91 » NestedServlet Request processing fail... [INFO] [ERROR] Tests run: 18, Failures: 0, Errors: 1, Skipped: 0 [INFO] [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 26.543 s [INFO] Finished at: 2022-01-14T12:47:48+01:00 [INFO] Final Memory: 68M/803M [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