=platformTestConfig; factoryMethodName=marshallService; initMethodName=init; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,959 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'coreService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=coreService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=coreService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,959 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'databaseService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=databaseService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=databaseService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,959 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'templateService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=templateService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=templateService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,959 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'initializerService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=initializerService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=initializerService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,959 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'ldapService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=ldapService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=ldapService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,960 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'thymeleafProcessor' with a different definition: replacing [Root bean: class [null]; scope=request; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=thymeleafProcessor; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=thymeleafProcessor; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,960 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'platformTransformer' with a different definition: replacing [Root bean: class [null]; scope=request; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=platformTransformer; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=platformTransformer; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,960 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'requestProcessor' with a different definition: replacing [Root bean: class [null]; scope=request; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=requestProcessor; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=requestProcessor; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:27,991 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2022-01-10 18:00:28,004 INFO [main] org.springframework.orm.jpa.LocalEntityManagerFactoryBean: Building JPA EntityManagerFactory for persistence unit 'hsql-testdb'
2022-01-10 18:00:28,015 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: hsql-testdb]
2022-01-10 18:00:28,059 WARN [main] org.hibernate.orm.connections.pooling: HHH10001002: Using Hibernate built-in connection pool (not for production use!)
2022-01-10 18:00:28,060 INFO [main] org.hibernate.orm.connections.pooling: HHH10001005: using driver [org.hsqldb.jdbc.JDBCDriver] at URL [jdbc:hsqldb:mem:hsql-testdb]
2022-01-10 18:00:28,060 INFO [main] org.hibernate.orm.connections.pooling: HHH10001001: Connection properties: {user=sa, password=****}
2022-01-10 18:00:28,060 INFO [main] org.hibernate.orm.connections.pooling: HHH10001003: Autocommit mode: false
2022-01-10 18:00:28,060 INFO [main] org.hibernate.engine.jdbc.connections.internal.DriverManagerConnectionProviderImpl: HHH000115: Hibernate connection pool size: 20 (min=1)
2022-01-10 18:00:28,061 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.appng.persistence.hibernate.dialect.HSQLDialect
2022-01-10 18:00:28,065 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true
2022-01-10 18:00:28,274 INFO [main] org.hibernate.orm.connections.access: HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@2a4e1b6] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
2022-01-10 18:00:28,278 INFO [main] org.hibernate.orm.connections.access: HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@4fe875f2] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
2022-01-10 18:00:28,300 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2022-01-10 18:00:28,301 INFO [main] org.springframework.orm.jpa.LocalEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'hsql-testdb'
2022-01-10 18:00:28,302 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context appNG platform context: startup date [Mon Jan 10 18:00:27 CET 2022]; root of context hierarchy
2022-01-10 18:00:28,490 INFO [main] org.springframework.test.context.transaction.TransactionContext: Began transaction (1) for test context [DefaultTestContext@9c88602 testClass = SubjectRepositoryTest, testInstance = org.appng.core.repository.SubjectRepositoryTest@770f9cbc, testMethod = test@AbstractRepositoryTest, testException = [null], mergedContextConfiguration = [MergedContextConfiguration@358f156c testClass = SubjectRepositoryTest, locations = '{}', classes = '{class org.appng.core.service.PlatformTestConfig}', contextInitializerClasses = '[class org.appng.core.repository.SubjectRepositoryTest]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[[empty]], contextLoader = 'org.springframework.test.context.support.DelegatingSmartContextLoader', parent = [null]]]; transaction manager [org.springframework.orm.jpa.JpaTransactionManager@1eed495c]; rollback [true]
2022-01-10 18:00:28,495 INFO [main] org.appng.core.domain.PlatformEventListener: Created entry null CREATE appNG platform Group name
2022-01-10 18:00:28,497 INFO [main] org.appng.core.domain.PlatformEventListener: Created entry null CREATE appNG platform Site name
2022-01-10 18:00:28,503 INFO [main] org.appng.core.domain.PlatformEventListener: Created entry null CREATE appNG platform Application name
2022-01-10 18:00:28,505 INFO [main] org.appng.core.domain.PlatformEventListener: Created entry null CREATE appNG platform Role name
2022-01-10 18:00:28,506 INFO [main] org.appng.core.domain.PlatformEventListener: Created entry null CREATE appNG platform Subject name
2022-01-10 18:00:28,521 INFO [main] org.springframework.test.context.transaction.TransactionContext: Rolled back transaction for test: [DefaultTestContext@9c88602 testClass = SubjectRepositoryTest, testInstance = org.appng.core.repository.SubjectRepositoryTest@770f9cbc, testMethod = test@AbstractRepositoryTest, testException = [null], mergedContextConfiguration = [MergedContextConfiguration@358f156c testClass = SubjectRepositoryTest, locations = '{}', classes = '{class org.appng.core.service.PlatformTestConfig}', contextInitializerClasses = '[class org.appng.core.repository.SubjectRepositoryTest]', activeProfiles = '{}', propertySourceLocations = '{}', propertySourceProperties = '{}', contextCustomizers = set[[empty]], contextLoader = 'org.springframework.test.context.support.DelegatingSmartContextLoader', parent = [null]]]
2022-01-10 18:00:28,522 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing appNG platform context: startup date [Mon Jan 10 18:00:27 CET 2022]; root of context hierarchy
2022-01-10 18:00:28,523 INFO [main] org.springframework.orm.jpa.LocalEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'hsql-testdb'
2022-01-10 18:00:28,523 INFO [main] org.hibernate.orm.connections.pooling: HHH10001008: Cleaning up connection pool [jdbc:hsqldb:mem:hsql-testdb]
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.761 s - in org.appng.core.repository.SubjectRepositoryTest
[INFO] Running org.appng.core.model.PlatformTransformerTest
2022-01-10 18:00:28,538 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-10 18:00:28,538 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@6572a8ba, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@784231a6, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@18c0cde6, org.springframework.test.context.support.DirtiesContextTestExecutionListener@129ac2ed, org.springframework.test.context.transaction.TransactionalTestExecutionListener@62dd9f7c, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@bfb4d80]
2022-01-10 18:00:28,539 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing appNG platform context: startup date [Mon Jan 10 18:00:28 CET 2022]; root of context hierarchy
2022-01-10 18:00:28,721 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'platformEventListener' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=platformEventListener; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=platformEventListener; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,721 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'dataSource' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,721 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'entityManagerFactory' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=entityManagerFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=entityManagerFactory; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,721 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'coreTxManager' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=coreTxManager; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=coreTxManager; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,721 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'entityManager' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=entityManager; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=entityManager; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,722 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'documentBuilderFactory' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=documentBuilderFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=documentBuilderFactory; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,722 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'transformerFactory' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=transformerFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=transformerFactory; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,722 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'styleSheetProvider' with a different definition: replacing [Root bean: class [null]; scope=request; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=styleSheetProvider; initMethodName=init; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=styleSheetProvider; initMethodName=init; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,722 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'marshallService' with a different definition: replacing [Root bean: class [null]; scope=prototype; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=marshallService; initMethodName=init; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=prototype; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=marshallService; initMethodName=init; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,722 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'coreService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=coreService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=coreService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,722 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'databaseService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=databaseService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=databaseService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,722 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'templateService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=templateService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=templateService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,722 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'initializerService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=initializerService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=initializerService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,723 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'ldapService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=ldapService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=ldapService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,723 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'thymeleafProcessor' with a different definition: replacing [Root bean: class [null]; scope=request; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=thymeleafProcessor; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=thymeleafProcessor; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,723 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'platformTransformer' with a different definition: replacing [Root bean: class [null]; scope=request; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=platformTransformer; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=platformTransformer; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,723 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'requestProcessor' with a different definition: replacing [Root bean: class [null]; scope=request; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=requestProcessor; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/controller/PlatformConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=requestProcessor; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.service.PlatformTestConfig]
2022-01-10 18:00:28,754 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2022-01-10 18:00:28,765 INFO [main] org.springframework.orm.jpa.LocalEntityManagerFactoryBean: Building JPA EntityManagerFactory for persistence unit 'hsql-testdb'
2022-01-10 18:00:28,775 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: hsql-testdb]
2022-01-10 18:00:28,819 WARN [main] org.hibernate.orm.connections.pooling: HHH10001002: Using Hibernate built-in connection pool (not for production use!)
2022-01-10 18:00:28,820 INFO [main] org.hibernate.orm.connections.pooling: HHH10001005: using driver [org.hsqldb.jdbc.JDBCDriver] at URL [jdbc:hsqldb:mem:hsql-testdb]
2022-01-10 18:00:28,820 INFO [main] org.hibernate.orm.connections.pooling: HHH10001001: Connection properties: {user=sa, password=****}
2022-01-10 18:00:28,820 INFO [main] org.hibernate.orm.connections.pooling: HHH10001003: Autocommit mode: false
2022-01-10 18:00:28,820 INFO [main] org.hibernate.engine.jdbc.connections.internal.DriverManagerConnectionProviderImpl: HHH000115: Hibernate connection pool size: 20 (min=1)
2022-01-10 18:00:28,821 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.appng.persistence.hibernate.dialect.HSQLDialect
2022-01-10 18:00:28,826 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true
2022-01-10 18:00:29,038 INFO [main] org.hibernate.orm.connections.access: HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@53d299a8] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
2022-01-10 18:00:29,042 INFO [main] org.hibernate.orm.connections.access: HHH10001501: Connection obtained from JdbcConnectionAccess [org.hibernate.engine.jdbc.env.internal.JdbcEnvironmentInitiator$ConnectionProviderJdbcConnectionAccess@4c0177fc] for (non-JTA) DDL execution was not in auto-commit mode; the Connection 'local transaction' will be committed and the Connection will be set into auto-commit mode.
2022-01-10 18:00:29,066 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2022-01-10 18:00:29,067 INFO [main] org.springframework.orm.jpa.LocalEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'hsql-testdb'
2022-01-10 18:00:29,068 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context appNG platform context: startup date [Mon Jan 10 18:00:28 CET 2022]; root of context hierarchy
2022-01-10 18:00:31,280 ERROR [main] org.appng.core.model.PlatformTransformer: An empty sequence is not allowed as the value of variable $foo
; SystemID: ; Line#: 6; Column#: 10
net.sf.saxon.trans.XPathException: An empty sequence is not allowed as the value of variable $foo
at net.sf.saxon.expr.CardinalityCheckingIterator.typeError(CardinalityCheckingIterator.java:104)
at net.sf.saxon.expr.CardinalityCheckingIterator.<init>(CardinalityCheckingIterator.java:54)
at net.sf.saxon.expr.CardinalityChecker.iterate(CardinalityChecker.java:239)
at net.sf.saxon.expr.AtomicSequenceConverter.iterate(AtomicSequenceConverter.java:304)
at net.sf.saxon.expr.Expression.process(Expression.java:949)
at net.sf.saxon.expr.instruct.ValueOf.processLeavingTail(ValueOf.java:341)
at net.sf.saxon.expr.instruct.TemplateRule.applyLeavingTail(TemplateRule.java:389)
at net.sf.saxon.trans.Mode.applyTemplates(Mode.java:568)
at net.sf.saxon.trans.XsltController.applyTemplates(XsltController.java:661)
at net.sf.saxon.s9api.AbstractXsltTransformer.applyTemplatesToSource(AbstractXsltTransformer.java:360)
at net.sf.saxon.s9api.XsltTransformer.transform(XsltTransformer.java:349)
at net.sf.saxon.jaxp.TransformerImpl.transform(TransformerImpl.java:74)
at org.appng.core.model.PlatformTransformer.transform(PlatformTransformer.java:324)
at org.appng.core.model.PlatformTransformer.transform(PlatformTransformer.java:203)
at org.appng.core.model.PlatformTransformerTest.runErrornousTest(PlatformTransformerTest.java:160)
at org.appng.core.model.PlatformTransformerTest.testRuntimeError(PlatformTransformerTest.java:139)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.springframework.test.context.junit4.statements.RunBeforeTestMethodCallbacks.evaluate(RunBeforeTestMethodCallbacks.java:75)
at org.springframework.test.context.junit4.statements.RunAfterTestMethodCallbacks.evaluate(RunAfterTestMethodCallbacks.java:86)
at org.springframework.test.context.junit4.statements.SpringRepeat.evaluate(SpringRepeat.java:84)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:252)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:94)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61)
at org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:70)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:191)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:364)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:272)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:237)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:158)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:428)
at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:162)
at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:562)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:548)
2022-01-10 18:00:31,599 INFO [main] org.appng.core.model.PlatformTransformer: writing debug files to target/debug
2022-01-10 18:00:31,599 INFO [main] org.appng.core.model.PlatformTransformer: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/template.xsl
2022-01-10 18:00:31,599 INFO [main] org.appng.core.model.PlatformTransformer: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/platform.xml
2022-01-10 18:00:31,600 INFO [main] org.appng.core.model.PlatformTransformer: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/stacktrace.txt
2022-01-10 18:00:31,719 ERROR [main] org.appng.core.model.PlatformTransformer: Unknown XSLT element: <tmplate>
; SystemID: ; Line#: 5; Column#: 25
net.sf.saxon.trans.XPathException: Unknown XSLT element: <tmplate>
at net.sf.saxon.trans.XPathException.fromXmlProcessingError(XPathException.java:178)
at net.sf.saxon.lib.ErrorReporterToListener.report(ErrorReporterToListener.java:64)
at net.sf.saxon.style.Compilation.reportError(Compilation.java:447)
at net.sf.saxon.style.StyleElement.compileError(StyleElement.java:2323)
at net.sf.saxon.style.StyleElement.validateSubtree(StyleElement.java:1691)
at net.sf.saxon.style.AbsentExtensionElement.validateSubtree(AbsentExtensionElement.java:70)
at net.sf.saxon.style.PrincipalStylesheetModule.preprocess(PrincipalStylesheetModule.java:403)
at net.sf.saxon.style.Compilation.compilePackage(Compilation.java:288)
at net.sf.saxon.style.StylesheetModule.loadStylesheet(StylesheetModule.java:252)
at net.sf.saxon.style.Compilation.compileSingletonPackage(Compilation.java:113)
at net.sf.saxon.s9api.XsltCompiler.compile(XsltCompiler.java:838)
at net.sf.saxon.jaxp.SaxonTransformerFactory.newTemplates(SaxonTransformerFactory.java:154)
at org.appng.core.model.PlatformTransformer.transform(PlatformTransformer.java:187)
at org.appng.core.model.PlatformTransformerTest.runErrornousTest(PlatformTransformerTest.java:160)
at org.appng.core.model.PlatformTransformerTest.testCompileError(PlatformTransformerTest.java:134)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.springframework.test.context.junit4.statements.RunBeforeTestMethodCallbacks.evaluate(RunBeforeTestMethodCallbacks.java:75)
at org.springframework.test.context.junit4.statements.RunAfterTestMethodCallbacks.evaluate(RunAfterTestMethodCallbacks.java:86)
at org.springframework.test.context.junit4.statements.SpringRepeat.evaluate(SpringRepeat.java:84)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:252)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:94)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61)
at org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:70)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:191)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:364)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:272)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:237)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:158)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:428)
at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:162)
at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:562)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:548)
2022-01-10 18:00:31,720 INFO [main] org.appng.core.model.PlatformTransformer: writing debug files to target/debug
2022-01-10 18:00:31,720 INFO [main] org.appng.core.model.PlatformTransformer: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/template.xsl
2022-01-10 18:00:31,720 INFO [main] org.appng.core.model.PlatformTransformer: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/platform.xml
2022-01-10 18:00:31,721 INFO [main] org.appng.core.model.PlatformTransformer: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/stacktrace.txt
2022-01-10 18:00:31,753 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing appNG platform context: startup date [Mon Jan 10 18:00:28 CET 2022]; root of context hierarchy
2022-01-10 18:00:31,754 INFO [main] org.springframework.orm.jpa.LocalEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'hsql-testdb'
2022-01-10 18:00:31,754 INFO [main] org.hibernate.orm.connections.pooling: HHH10001008: Cleaning up connection pool [jdbc:hsqldb:mem:hsql-testdb]
[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.228 s - in org.appng.core.model.PlatformTransformerTest
[INFO] Running org.appng.core.model.RepositoryUtilsTest
[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.005 s - in org.appng.core.model.RepositoryUtilsTest
[INFO] Running org.appng.core.model.CacheProviderTest
2022-01-10 18:00:31,840 INFO [main] org.appng.core.model.CacheProvider: cleaning /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/WEB-INF/cache/platform/appNG
2022-01-10 18:00:31,840 INFO [main] org.appng.core.model.CacheProvider: cleaning /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/WEB-INF/cache/application/appNG
2022-01-10 18:00:31,870 INFO [main] org.appng.core.model.CacheProvider: cleaning /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/WEB-INF/cache/platform/appNG/foobar
2022-01-10 18:00:31,870 INFO [main] org.appng.core.model.CacheProvider: cleaning /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/WEB-INF/cache/application/appNG/foobar
[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.12 s - in org.appng.core.model.CacheProviderTest
[INFO] Running org.appng.core.model.JarInfoBuilderTest
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.124 s - in org.appng.core.model.JarInfoBuilderTest
[INFO] Running org.appng.core.model.ApplicationProviderTest
2022-01-10 18:00:32,039 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-10 18:00:32,041 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@7eb193b9, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@6b520d6d, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@7df53a8f, org.springframework.test.context.support.DirtiesContextTestExecutionListener@53cf4fb9, org.springframework.test.context.transaction.TransactionalTestExecutionListener@21913222, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@5ad36a18]
2022-01-10 18:00:32,519 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [applications/application1/beans.xml]
2022-01-10 18:00:33,465 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext.xml]
2022-01-10 18:00:33,482 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-10 18:00:33,521 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@ea6028f: startup date [Mon Jan 10 18:00:33 CET 2022]; root of context hierarchy
2022-01-10 18:00:33,861 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2022-01-10 18:00:33,959 INFO [HSQLDB Server @459ad724] hsqldb.db.HSQLDB7E44EF3324.ENGINE: Checkpoint start
2022-01-10 18:00:33,959 INFO [HSQLDB Server @459ad724] hsqldb.db.HSQLDB7E44EF3324.ENGINE: checkpointClose start
2022-01-10 18:00:34,017 INFO [HSQLDB Server @459ad724] hsqldb.db.HSQLDB7E44EF3324.ENGINE: checkpointClose synched
2022-01-10 18:00:34,025 INFO [HSQLDB Server @459ad724] hsqldb.db.HSQLDB7E44EF3324.ENGINE: checkpointClose script done
2022-01-10 18:00:34,148 INFO [HSQLDB Server @459ad724] hsqldb.db.HSQLDB7E44EF3324.ENGINE: checkpointClose end
2022-01-10 18:00:34,149 INFO [HSQLDB Server @459ad724] hsqldb.db.HSQLDB7E44EF3324.ENGINE: Checkpoint end - txts: 1
2022-01-10 18:00:34,433 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default'
2022-01-10 18:00:34,439 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default]
2022-01-10 18:00:34,731 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect
2022-01-10 18:00:34,744 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true
2022-01-10 18:00:34,842 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2022-01-10 18:00:34,842 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default'
2022-01-10 18:00:35,396 WARN [main] org.appng.api.support.RequestFactoryBean: invalid value for platform property 'uploadDir', folder 'null/target/uploads' does not exist!
2022-01-10 18:00:35,588 INFO [main] org.appng.core.model.ApplicationProvider: include condition for action 'create' of event 'events' did not match - ${action eq 'create' and empty entityId}
2022-01-10 18:00:35,589 INFO [main] org.appng.core.model.ApplicationProvider: include condition for datasource 'entities' did not match - ${empty action and empty entityId}
2022-01-10 18:00:35,628 WARN [main] org.springframework.context.support.ResourceBundleMessageSource: ResourceBundle [messages-demo] not found for MessageSource: Can't find bundle for base name messages-demo, locale en
2022-01-10 18:00:35,629 WARN [main] org.springframework.context.support.ResourceBundleMessageSource: ResourceBundle [testmessages] not found for MessageSource: Can't find bundle for base name testmessages, locale en
2022-01-10 18:00:36,066 INFO [main] org.appng.core.model.ApplicationProvider: include condition for action 'create' of event 'events' did not match - ${action eq 'create' and empty entityId}
2022-01-10 18:00:36,067 INFO [main] org.appng.core.model.ApplicationProvider: include condition for datasource 'entities' did not match - ${empty action and empty entityId}
2022-01-10 18:00:36,167 ERROR [main] org.appng.core.model.ApplicationProvider: [localhost:demo-application] error while building structure for page 'entity'
org.appng.api.ProcessingException: error performing action 'update' of event 'events', ID: 1761040334
at org.appng.api.support.CallableAction.execute(CallableAction.java:457)
at org.appng.api.support.CallableAction.perform(CallableAction.java:337)
at org.appng.core.model.ActionElement.perform(ActionElement.java:75)
at org.appng.core.model.ApplicationProvider.getActionSectionElement(ApplicationProvider.java:757)
at org.appng.core.model.ApplicationProvider.access$000(ApplicationProvider.java:129)
at org.appng.core.model.ApplicationProvider$2.perform(ApplicationProvider.java:635)
at org.appng.core.model.ApplicationProvider.doMonitored(ApplicationProvider.java:688)
at org.appng.core.model.ApplicationProvider.addElements(ApplicationProvider.java:646)
at org.appng.core.model.ApplicationProvider.buildStructure(ApplicationProvider.java:546)
at org.appng.core.model.ApplicationProvider.process(ApplicationProvider.java:282)
at org.appng.core.model.ApplicationProviderTest.runTest(ApplicationProviderTest.java:282)
at org.appng.core.model.ApplicationProviderTest.testUpdateException(ApplicationProviderTest.java:183)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.springframework.test.context.junit4.statements.RunBeforeTestMethodCallbacks.evaluate(RunBeforeTestMethodCallbacks.java:75)
at org.springframework.test.context.junit4.statements.RunAfterTestMethodCallbacks.evaluate(RunAfterTestMethodCallbacks.java:86)
at org.springframework.test.context.junit4.statements.SpringRepeat.evaluate(SpringRepeat.java:84)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:252)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:94)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61)
at org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:70)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:191)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:364)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:272)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:237)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:158)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:428)
at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:162)
at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:562)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:548)
Caused by: org.appng.api.ApplicationException: adfsf
at org.appng.testapplication.TestEntities.perform(TestEntities.java:71)
at org.appng.testapplication.TestEntities.perform(TestEntities.java:35)
at org.appng.api.support.CallableAction.execute(CallableAction.java:439)
... 44 more
2022-01-10 18:00:36,250 INFO [main] org.appng.core.model.ApplicationProvider: include condition for action 'update' of event 'events' did not match - ${action eq 'update' and not empty entityId}
2022-01-10 18:00:36,250 INFO [main] org.appng.core.model.ApplicationProvider: include condition for action 'create' of event 'events' did not match - ${action eq 'create' and empty entityId}
2022-01-10 18:00:36,384 INFO [main] org.appng.core.model.ApplicationProvider: include condition for action 'create' of event 'events' did not match - ${action eq 'create' and empty entityId}
2022-01-10 18:00:36,384 INFO [main] org.appng.core.model.ApplicationProvider: include condition for datasource 'entities' did not match - ${empty action and empty entityId}
2022-01-10 18:00:36,498 INFO [main] org.appng.core.model.ApplicationProvider: include condition for action 'create' of event 'events' did not match - ${action eq 'create' and empty entityId}
2022-01-10 18:00:36,498 INFO [main] org.appng.core.model.ApplicationProvider: include condition for datasource 'entities' did not match - ${empty action and empty entityId}
2022-01-10 18:00:36,500 ERROR [main] org.appng.core.model.ApplicationProvider: [localhost:demo-application] error while building structure for page 'entity'
org.appng.api.ProcessingException: error retrieving datasource 'entity', ID: 1772942322
at org.appng.api.support.CallableDataSource.perform(CallableDataSource.java:240)
at org.appng.api.support.CallableAction.retrieveData(CallableAction.java:235)
at org.appng.api.support.CallableAction.perform(CallableAction.java:335)
at org.appng.core.model.ActionElement.perform(ActionElement.java:75)
at org.appng.core.model.ApplicationProvider.getActionSectionElement(ApplicationProvider.java:757)
at org.appng.core.model.ApplicationProvider.access$000(ApplicationProvider.java:129)
at org.appng.core.model.ApplicationProvider$2.perform(ApplicationProvider.java:635)
at org.appng.core.model.ApplicationProvider.doMonitored(ApplicationProvider.java:688)
at org.appng.core.model.ApplicationProvider.addElements(ApplicationProvider.java:646)
at org.appng.core.model.ApplicationProvider.buildStructure(ApplicationProvider.java:546)
at org.appng.core.model.ApplicationProvider.process(ApplicationProvider.java:282)
at org.appng.core.model.ApplicationProviderTest.runTest(ApplicationProviderTest.java:282)
at org.appng.core.model.ApplicationProviderTest.testUpdateExceptionDataSource(ApplicationProviderTest.java:173)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.springframework.test.context.junit4.statements.RunBeforeTestMethodCallbacks.evaluate(RunBeforeTestMethodCallbacks.java:75)
at org.springframework.test.context.junit4.statements.RunAfterTestMethodCallbacks.evaluate(RunAfterTestMethodCallbacks.java:86)
at org.springframework.test.context.junit4.statements.SpringRepeat.evaluate(SpringRepeat.java:84)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:252)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:94)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61)
at org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:70)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:191)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:364)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:272)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:237)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:158)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:428)
at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:162)
at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:562)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:548)
Caused by: org.appng.api.ApplicationException: adfsf
at org.appng.testapplication.TestEntities.getData(TestEntities.java:45)
at org.appng.api.support.CallableDataSource.perform(CallableDataSource.java:201)
... 45 more
2022-01-10 18:00:36,716 INFO [main] org.appng.core.model.ApplicationProvider: include condition for action 'create' of event 'events' did not match - ${action eq 'create' and empty entityId}
2022-01-10 18:00:36,716 INFO [main] org.appng.core.model.ApplicationProvider: include condition for datasource 'entities' did not match - ${empty action and empty entityId}
2022-01-10 18:00:36,851 INFO [main] org.appng.core.model.ApplicationProvider: include condition for action 'update' of event 'events' did not match - ${action eq 'update' and not empty entityId}
2022-01-10 18:00:36,851 INFO [main] org.appng.core.model.ApplicationProvider: include condition for datasource 'entities' did not match - ${empty action and empty entityId}
2022-01-10 18:00:36,904 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@ea6028f: startup date [Mon Jan 10 18:00:33 CET 2022]; root of context hierarchy
2022-01-10 18:00:36,905 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default'
2022-01-10 18:00:37,204 INFO [HSQLDB Connection @31eb644c] hsqldb.db.HSQLDB7E44EF3324.ENGINE: Database closed
[INFO] Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.389 s - in org.appng.core.model.ApplicationProviderTest
[INFO] Running org.appng.core.model.ThymeleafProcessorTest
2022-01-10 18:00:37,653 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:37,623_main/platform.xml
2022-01-10 18:00:40,611 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:37,623_main/template/hello.html
2022-01-10 18:00:40,613 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:37,623_main/template/platform.html
2022-01-10 18:00:40,614 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:37,623_main/template/hello-custom.html
2022-01-10 18:00:41,625 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:37,623_main/index.html
2022-01-10 18:00:41,881 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,866_main/platform.xml
2022-01-10 18:00:41,895 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,866_main/template/hello.html
2022-01-10 18:00:41,896 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,866_main/template/platform.html
2022-01-10 18:00:41,896 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,866_main/template/hello-custom.html
2022-01-10 18:00:41,909 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,866_main/index.html
2022-01-10 18:00:41,956 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,947_main/platform.xml
2022-01-10 18:00:41,968 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,947_main/template/hello.html
2022-01-10 18:00:41,968 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,947_main/template/platform.html
2022-01-10 18:00:41,968 INFO [main] org.appng.core.model.ThymeleafProcessor: writing debug file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/debug/2022-01-10-18:00:41,947_main/template/hello-custom.html
[INFO] Tests run: 24, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 4.556 s - in org.appng.core.model.ThymeleafProcessorTest
[INFO] Running org.appng.core.model.OutputFormatAndTypeTest
[INFO] Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.07 s - in org.appng.core.model.OutputFormatAndTypeTest
[INFO] Running org.appng.core.model.PageParameterProcessorTest
2022-01-10 18:00:42,178 INFO [main] org.appng.core.model.PageParameterProcessor: retrieved new value for url-param 'hash' from session: 'hashFromSession'
[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.205 s - in org.appng.core.model.PageParameterProcessorTest
[INFO] Running org.appng.core.PathInfoTest
[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.03 s - in org.appng.core.PathInfoTest
[INFO] Running org.appng.core.application.PermissionProcessorTest
[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.028 s - in org.appng.core.application.PermissionProcessorTest
[INFO] Running org.appng.core.controller.rest.RestActionTest
2022-01-10 18:00:43,319 ERROR [main] org.appng.core.controller.rest.RestOperation$RestErrorHandler: error in REST service
java.io.IOException: BOOOM!
at org.appng.core.controller.rest.RestOperationTest.testHandleException(RestOperationTest.java:98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:364)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:272)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:237)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:158)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:428)
at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:162)
at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:562)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:548)
[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.989 s - in org.appng.core.controller.rest.RestActionTest
[INFO] Running org.appng.core.controller.rest.RestDataSourceTest
2022-01-10 18:00:43,679 ERROR [main] org.appng.core.controller.rest.RestOperation$RestErrorHandler: error in REST service
java.io.IOException: BOOOM!
at org.appng.core.controller.rest.RestOperationTest.testHandleException(RestOperationTest.java:98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:364)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:272)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:237)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:158)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:428)
at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:162)
at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:562)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:548)
[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.358 s - in org.appng.core.controller.rest.RestDataSourceTest
[INFO] Running org.appng.core.controller.rest.RestOperationTest
2022-01-10 18:00:43,701 ERROR [main] org.appng.core.controller.rest.RestOperation$RestErrorHandler: error in REST service
java.io.IOException: BOOOM!
at org.appng.core.controller.rest.RestOperationTest.testHandleException(RestOperationTest.java:98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:364)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:272)
at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:237)
at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:158)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:428)
at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:162)
at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:562)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:548)
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 s - in org.appng.core.controller.rest.RestOperationTest
[INFO] Running org.appng.core.controller.PlatformConfigTest
2022-01-10 18:00:44,038 INFO [main] org.springframework.context.annotation.AnnotationConfigApplicationContext: Refreshing org.springframework.context.annotation.AnnotationConfigApplicationContext@3c0bc826: startup date [Mon Jan 10 18:00:44 CET 2022]; root of context hierarchy
2022-01-10 18:00:44,258 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'platformEventListener' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=platformEventListener; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=platformEventListener; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,258 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'dataSource' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=dataSource; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,259 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'entityManagerFactory' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=entityManagerFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=entityManagerFactory; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,259 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'coreTxManager' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=coreTxManager; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=coreTxManager; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,259 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'entityManager' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=entityManager; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=entityManager; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,259 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'documentBuilderFactory' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=documentBuilderFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=documentBuilderFactory; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,259 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'transformerFactory' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=transformerFactory; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=transformerFactory; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,259 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'styleSheetProvider' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=styleSheetProvider; initMethodName=init; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=request; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=styleSheetProvider; initMethodName=init; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,259 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'marshallService' with a different definition: replacing [Root bean: class [null]; scope=prototype; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=marshallService; initMethodName=init; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=prototype; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=marshallService; initMethodName=init; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,260 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'coreService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=coreService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=coreService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,260 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'databaseService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=databaseService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=databaseService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,260 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'templateService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=templateService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=templateService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,260 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'initializerService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=initializerService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=initializerService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,260 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'ldapService' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=ldapService; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=ldapService; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,260 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'thymeleafProcessor' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=thymeleafProcessor; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=request; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=thymeleafProcessor; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,260 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'platformTransformer' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=platformTransformer; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=request; abstract=false; lazyInit=true; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=platformTransformer; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,260 INFO [main] org.springframework.beans.factory.support.DefaultListableBeanFactory: Overriding bean definition for bean 'requestProcessor' with a different definition: replacing [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformTestConfig; factoryMethodName=requestProcessor; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/appng/core/service/PlatformTestConfig.class]] with [Root bean: class [null]; scope=request; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=platformConfig; factoryMethodName=requestProcessor; initMethodName=null; destroyMethodName=(inferred); defined in org.appng.core.controller.PlatformConfig]
2022-01-10 18:00:44,308 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
Jan 10, 2022 6:00:44 PM java.util.prefs.FileSystemPreferences$6 run
WARNING: Prefs file removed in background /var/lib/jenkins/.java/.userPrefs/prefs.xml
Jan 10, 2022 6:00:44 PM java.util.prefs.FileSystemPreferences$6 run
WARNING: Prefs file removed in background /etc/.java/.systemPrefs/prefs.xml
2022-01-10 18:00:44,336 INFO [main] org.appng.core.repository.config.HikariCPConfigurer: connection jdbc:hsqldb:mem://appng uses datasource org.hsqldb.jdbc.JDBCDataSource
2022-01-10 18:00:44,361 INFO [main] com.zaxxer.hikari.HikariDataSource: appNG ROOT connection - Starting...
2022-01-10 18:00:44,412 INFO [main] com.zaxxer.hikari.pool.PoolBase: appNG ROOT connection - Driver does not support get/set network timeout for connections. (feature not supported)
2022-01-10 18:00:44,420 INFO [main] com.zaxxer.hikari.HikariDataSource: appNG ROOT connection - Start completed.
2022-01-10 18:00:44,449 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'appNG'
2022-01-10 18:00:44,450 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: appNG]
2022-01-10 18:00:44,556 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.appng.persistence.dialect.HSQLDialect
2022-01-10 18:00:44,580 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true
2022-01-10 18:00:44,789 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2022-01-10 18:00:44,789 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'appNG'
2022-01-10 18:00:44,799 INFO [main] org.appng.core.domain.PlatformEventListener: Using application context org.springframework.context.annotation.AnnotationConfigApplicationContext@3c0bc826: startup date [Mon Jan 10 18:00:44 CET 2022]; root of context hierarchy
2022-01-10 18:00:44,996 INFO [main] org.appng.core.domain.DatabaseConnection: Registered JDBC driver org.hsqldb.jdbc.JDBCDriver
2022-01-10 18:00:44,997 INFO [main] org.appng.core.service.MigrationService: connected to jdbc:hsqldb:mem://appng (HSQL Database Engine 2.5.0)
2022-01-10 18:00:45,001 INFO [main] org.flywaydb.core.internal.license.VersionPrinter: Flyway Community Edition 7.15.0 by Redgate
2022-01-10 18:00:45,001 INFO [main] org.flywaydb.core.internal.database.base.BaseDatabaseType: Database: jdbc:hsqldb:mem://appng (HSQL Database Engine 2.5)
2022-01-10 18:00:45,010 INFO [main] org.springframework.context.annotation.AnnotationConfigApplicationContext: Closing org.springframework.context.annotation.AnnotationConfigApplicationContext@3c0bc826: startup date [Mon Jan 10 18:00:44 CET 2022]; root of context hierarchy
2022-01-10 18:00:45,011 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'appNG'
2022-01-10 18:00:45,011 INFO [main] com.zaxxer.hikari.HikariDataSource: appNG ROOT connection - Shutdown initiated...
2022-01-10 18:00:45,022 INFO [main] com.zaxxer.hikari.HikariDataSource: appNG ROOT connection - Shutdown completed.
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.319 s - in org.appng.core.controller.PlatformConfigTest
[INFO] Running org.appng.core.controller.messaging.MessagingTest
2022-01-10 18:00:45,054 INFO [main] org.appng.core.controller.messaging.MulticastReceiver: about to execute org.appng.core.controller.messaging.MessagingTest$TestEvent - Origin: appng.node2 - Site: localhost
2022-01-10 18:00:45,055 INFO [main] org.appng.core.controller.messaging.MulticastReceiver: about to execute org.appng.core.controller.messaging.MessagingTest$TestEvent - Origin: appng.node1 - Site: localhost
2022-01-10 18:00:45,055 INFO [main] org.appng.core.controller.messaging.MulticastReceiver: about to execute org.appng.core.controller.messaging.MessagingTest$TestEvent - Origin: appng.node2 - Site: localhost
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.032 s - in org.appng.core.controller.messaging.MessagingTest
[INFO] Running org.appng.core.controller.RequestUtilTest
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in org.appng.core.controller.RequestUtilTest
[INFO] Running org.appng.core.controller.ApplicationContextTest
2022-01-10 18:00:45,073 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-10 18:00:45,074 INFO [main] org.springframework.test.context.support.DefaultTestContextBootstrapper: Using TestExecutionListeners: [org.springframework.test.context.web.ServletTestExecutionListener@495a4afc, org.springframework.test.context.support.DirtiesContextBeforeModesTestExecutionListener@4eae9add, org.springframework.test.context.support.DependencyInjectionTestExecutionListener@4e3a626f, org.springframework.test.context.support.DirtiesContextTestExecutionListener@5ed69d68, org.springframework.test.context.transaction.TransactionalTestExecutionListener@454031e4, org.springframework.test.context.jdbc.SqlScriptsTestExecutionListener@7f0223e3]
2022-01-10 18:00:45,084 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [applications/application1/beans.xml]
2022-01-10 18:00:45,109 INFO [main] org.springframework.beans.factory.xml.XmlBeanDefinitionReader: Loading XML bean definitions from class path resource [org/appng/testsupport/application-testcontext.xml]
2022-01-10 18:00:45,150 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-10 18:00:45,161 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@62b60f76: startup date [Mon Jan 10 18:00:45 CET 2022]; root of context hierarchy
2022-01-10 18:00:45,171 INFO [main] org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor: JSR-330 'javax.inject.Inject' annotation found and supported for autowiring
2022-01-10 18:00:45,288 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Building JPA container EntityManagerFactory for persistence unit 'default'
2022-01-10 18:00:45,288 INFO [main] org.hibernate.jpa.internal.util.LogHelper: HHH000204: Processing PersistenceUnitInfo [name: default]
2022-01-10 18:00:45,431 INFO [main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.dialect.HSQLDialect
2022-01-10 18:00:45,440 INFO [main] org.hibernate.envers.boot.internal.EnversServiceImpl: Envers integration enabled? : true
2022-01-10 18:00:45,700 INFO [main] org.hibernate.engine.transaction.jta.platform.internal.JtaPlatformInitiator: HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2022-01-10 18:00:45,700 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Initialized JPA EntityManagerFactory for persistence unit 'default'
2022-01-10 18:00:45,779 WARN [main] org.appng.api.support.RequestFactoryBean: invalid value for platform property 'uploadDir', folder 'null/target/uploads' does not exist!
2022-01-10 18:00:45,899 INFO [main] org.springframework.context.support.GenericApplicationContext: Closing org.springframework.context.support.GenericApplicationContext@62b60f76: startup date [Mon Jan 10 18:00:45 CET 2022]; root of context hierarchy
2022-01-10 18:00:45,899 INFO [main] org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean: Closing JPA EntityManagerFactory for persistence unit 'default'
2022-01-10 18:00:46,288 INFO [HSQLDB Connection @3bdf7f80] hsqldb.db.HSQLDB7E44EF3324.ENGINE: Database closed
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.417 s - in org.appng.core.controller.ApplicationContextTest
[INFO] Running org.appng.core.controller.RedirectTest
2022-01-10 18:00:46,504 INFO [main] org.appng.core.Redirect : Redirecting request to /targeturl (301)
2022-01-10 18:00:46,508 INFO [main] org.appng.core.Redirect : Redirecting request to targeturl (302)
2022-01-10 18:00:46,511 INFO [main] org.appng.core.Redirect : Redirecting request to http://www.example.com (301)
[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.01 s - in org.appng.core.controller.RedirectTest
[INFO] Running org.appng.core.controller.PlatformStartupTest
2022-01-10 18:00:46,692 INFO [main] org.appng.core.controller.Log4jConfigurer: Initialized log4j from classpath:log4j.properties
2022-01-10 18:00:46,692 INFO [main] org.appng.core.controller.PlatformStartup: ------------------------------------------------
2022-01-10 18:00:46,753 INFO [main] org.appng.core.controller.PlatformStartup: Launching _ _ ____
2022-01-10 18:00:46,753 INFO [main] org.appng.core.controller.PlatformStartup: __ _ _ __ _ __ | \ | |/ ___|
2022-01-10 18:00:46,753 INFO [main] org.appng.core.controller.PlatformStartup: / _` | '_ \| '_ \| \| | | _
2022-01-10 18:00:46,753 INFO [main] org.appng.core.controller.PlatformStartup: | (_| | |_) | |_) | |\ | |_| |
2022-01-10 18:00:46,753 INFO [main] org.appng.core.controller.PlatformStartup: \__,_| .__/| .__/|_| \_|\____|
2022-01-10 18:00:46,753 INFO [main] org.appng.core.controller.PlatformStartup: |_| |_|
2022-01-10 18:00:46,753 INFO [main] org.appng.core.controller.PlatformStartup: ----------------------------appNG.version-------
2022-01-10 18:00:46,753 INFO [main] org.appng.core.controller.PlatformStartup: ...the Next Generation Application Platform
2022-01-10 18:00:46,767 INFO [main] org.appng.core.service.HsqlStarter: starting HSQL Server 2.5.0 at /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/WEB-INF/database/appng on port 9050
2022-01-10 18:00:46,793 INFO [HSQLDB Server @4881600d] hsqldb.db.HSQLDB7E44EF6547.ENGINE: Checkpoint start
2022-01-10 18:00:46,794 INFO [HSQLDB Server @4881600d] hsqldb.db.HSQLDB7E44EF6547.ENGINE: checkpointClose start
2022-01-10 18:00:46,848 INFO [HSQLDB Server @4881600d] hsqldb.db.HSQLDB7E44EF6547.ENGINE: checkpointClose synched
2022-01-10 18:00:46,855 INFO [HSQLDB Server @4881600d] hsqldb.db.HSQLDB7E44EF6547.ENGINE: checkpointClose script done
2022-01-10 18:00:47,029 INFO [HSQLDB Server @4881600d] hsqldb.db.HSQLDB7E44EF6547.ENGINE: checkpointClose end
2022-01-10 18:00:47,029 INFO [HSQLDB Server @4881600d] hsqldb.db.HSQLDB7E44EF6547.ENGINE: Checkpoint end - txts: 1
2022-01-10 18:00:47,069 INFO [main] org.appng.core.domain.DatabaseConnection: Registered JDBC driver org.hsqldb.jdbc.JDBCDriver
2022-01-10 18:00:47,174 INFO [main] org.appng.core.service.MigrationService: connected to jdbc:hsqldb:hsql://localhost:9050/appng (HSQL Database Engine 2.5.0)
2022-01-10 18:00:47,289 INFO [main] org.flywaydb.core.internal.license.VersionPrinter: Flyway Community Edition 7.15.0 by Redgate
2022-01-10 18:00:47,290 INFO [main] org.flywaydb.core.internal.database.base.BaseDatabaseType: Database: jdbc:hsqldb:hsql://localhost:9050/appng (HSQL Database Engine 2.5)
2022-01-10 18:00:47,413 INFO [main] org.appng.core.service.MigrationService: connected to jdbc:hsqldb:hsql://localhost:9050/appng (HSQL Database Engine 2.5.0)
2022-01-10 18:00:47,520 INFO [main] org.flywaydb.core.internal.license.VersionPrinter: Flyway Community Edition 7.15.0 by Redgate
2022-01-10 18:00:47,521 INFO [main] org.flywaydb.core.internal.database.base.BaseDatabaseType: Database: jdbc:hsqldb:hsql://localhost:9050/appng (HSQL Database Engine 2.5)
2022-01-10 18:00:47,541 INFO [main] org.flywaydb.core.internal.command.DbValidate: Successfully validated 16 migrations (execution time 00:00.013s)
2022-01-10 18:00:47,555 INFO [main] org.flywaydb.core.internal.schemahistory.JdbcTableSchemaHistory: Creating Schema History table "PUBLIC"."schema_version" ...
2022-01-10 18:00:47,568 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Current version of schema "PUBLIC": << Empty Schema >>
2022-01-10 18:00:47,611 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.0 - appNG initial setup"
2022-01-10 18:00:47,655 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.1 - Quartz initial setup"
2022-01-10 18:00:47,675 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.2 - add template"
2022-01-10 18:00:47,686 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.2.1 - add repository strict"
2022-01-10 18:00:47,697 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.2.2 - add repository digest"
2022-01-10 18:00:47,708 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.2.3 - set resource bytes not null"
2022-01-10 18:00:47,718 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.3.0 - add type to template"
2022-01-10 18:00:47,728 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.3.1 - add global admin role"
2022-01-10 18:00:47,738 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "1.3.2 - add repository accepted certs"
2022-01-10 18:00:47,750 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "2.0.0 - add platform event"
2022-01-10 18:00:47,764 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "4.0.0 - add job execution record"
2022-01-10 18:00:47,774 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "4.1.0 - change caching properties"
2022-01-10 18:00:47,775 WARN [main] org.flywaydb.core.internal.sqlscript.DefaultSqlScriptExecutor: DB: no data (SQL State: 02000 - Error Code: -1100)
2022-01-10 18:00:47,776 WARN [main] org.flywaydb.core.internal.sqlscript.DefaultSqlScriptExecutor: DB: no data (SQL State: 02000 - Error Code: -1100)
2022-01-10 18:00:47,784 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "4.1.1 - add site reload count"
2022-01-10 18:00:47,796 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "4.1.2 - add property type"
2022-01-10 18:00:47,808 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "4.2 - add subject columns"
2022-01-10 18:00:47,811 WARN [main] org.flywaydb.core.internal.sqlscript.DefaultSqlScriptExecutor: DB: no data (SQL State: 02000 - Error Code: -1100)
2022-01-10 18:00:47,820 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Migrating schema "PUBLIC" to version "4.2.1 - change subject name length"
2022-01-10 18:00:47,828 INFO [main] org.flywaydb.core.internal.command.DbMigrate: Successfully applied 16 migrations to schema "PUBLIC", now at version v4.2.1 (execution time 00:00.263s)
2022-01-10 18:00:47,829 INFO [main] org.appng.core.controller.PlatformStartup: Platform connection: HSQL jdbc:hsqldb:hsql://localhost:9050/appng
2022-01-10 18:00:47,857 INFO [main] org.appng.core.controller.PlatformStartup: appNG null started in 1104 ms.
2022-01-10 18:00:47,857 INFO [main] org.appng.core.controller.PlatformStartup: ====================================================================================================
2022-01-10 18:00:47,861 INFO [main] org.appng.core.controller.PlatformStartup: Called com.mysql.cj.jdbc.AbandonedConnectionCleanupThread.checkedShutdown()
2022-01-10 18:00:52,862 INFO [main] org.appng.core.service.HazelcastConfigurer: Shutting down instance test
2022-01-10 18:00:52,862 INFO [main] com.hazelcast.core.LifecycleService: [10.102.4.100]:5702 [appNG] [5.0] [10.102.4.100]:5702 is SHUTTING_DOWN
2022-01-10 18:00:52,895 INFO [main] com.hazelcast.core.LifecycleService: [10.102.4.100]:5702 [appNG] [5.0] [10.102.4.100]:5702 is SHUTTING_DOWN
2022-01-10 18:00:52,973 INFO [hz.test.cached.thread-7] com.hazelcast.internal.partition.impl.MigrationManager: [10.102.4.100]:5702 [appNG] [5.0] Shutdown request of Member [10.102.4.100]:5702 - 220b719b-87d9-4d95-a02d-c007bc2350b0 this is handled
2022-01-10 18:00:52,994 INFO [main] com.hazelcast.instance.impl.Node: [10.102.4.100]:5702 [appNG] [5.0] Shutting down multicast service...
2022-01-10 18:00:52,994 INFO [main] com.hazelcast.instance.impl.Node: [10.102.4.100]:5702 [appNG] [5.0] Shutting down connection manager...
2022-01-10 18:00:52,998 INFO [main] com.hazelcast.instance.impl.Node: [10.102.4.100]:5702 [appNG] [5.0] Shutting down node engine...
2022-01-10 18:00:53,088 INFO [main] com.hazelcast.instance.impl.NodeExtension: [10.102.4.100]:5702 [appNG] [5.0] Destroying node NodeExtension.
2022-01-10 18:00:53,089 INFO [main] com.hazelcast.instance.impl.Node: [10.102.4.100]:5702 [appNG] [5.0] Hazelcast Shutdown is completed in 193 ms.
2022-01-10 18:00:53,089 INFO [main] com.hazelcast.core.LifecycleService: [10.102.4.100]:5702 [appNG] [5.0] [10.102.4.100]:5702 is SHUTDOWN
2022-01-10 18:00:53,089 INFO [main] com.hazelcast.instance.impl.Node: [10.102.4.100]:5702 [appNG] [5.0] Node is already shutting down... Waiting for shutdown process to complete...
2022-01-10 18:00:53,089 INFO [main] com.hazelcast.core.LifecycleService: [10.102.4.100]:5702 [appNG] [5.0] [10.102.4.100]:5702 is SHUTDOWN
2022-01-10 18:00:53,089 INFO [main] org.appng.core.controller.PlatformStartup: appNG stopped.
2022-01-10 18:00:53,089 INFO [main] org.appng.core.controller.PlatformStartup: ====================================================================================================
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.574 s - in org.appng.core.controller.PlatformStartupTest
[INFO] Running org.appng.core.controller.handler.GuiHandlerTest
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.025 s - in org.appng.core.controller.handler.GuiHandlerTest
[INFO] Running org.appng.core.controller.handler.ServiceRequestHandlerTest
2022-01-10 18:00:54,119 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@2002163f: startup date [Mon Jan 10 18:00:54 CET 2022]; root of context hierarchy
2022-01-10 18:00:54,125 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.context.support.GenericApplicationContext@2002163f: startup date [Mon Jan 10 18:00:54 CET 2022]; root of context hierarchy
2022-01-10 18:00:54,351 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/add/{a}/{b}],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest$Result> org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest.add(java.lang.Integer,java.lang.Integer) throws org.appng.api.BusinessException
2022-01-10 18:00:54,544 INFO [main] org.springframework.web.servlet.mvc.method.annotation.ExceptionHandlerExceptionResolver: Detected @ExceptionHandler methods in foobarRest
2022-01-10 18:00:54,551 WARN [main] org.appng.core.controller.handler.ServiceRequestHandler: No such application 'bar?jin=fizz' for site 'localhost' returning 404 (path: /services/localhost/bar?jin=fizz)
2022-01-10 18:00:54,557 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@58909d73: startup date [Mon Jan 10 18:00:54 CET 2022]; root of context hierarchy
2022-01-10 18:00:54,560 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.context.support.GenericApplicationContext@58909d73: startup date [Mon Jan 10 18:00:54 CET 2022]; root of context hierarchy
2022-01-10 18:00:54,561 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/add/{a}/{b}],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest$Result> org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest.add(java.lang.Integer,java.lang.Integer) throws org.appng.api.BusinessException
2022-01-10 18:00:54,876 ERROR [main] org.appng.core.controller.handler.ServiceRequestHandler: no webservice 'foobar' for application 'appng-demoapplication' in site 'localhost'
2022-01-10 18:00:54,891 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@5f3a906e: startup date [Mon Jan 10 18:00:54 CET 2022]; root of context hierarchy
2022-01-10 18:00:54,895 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.context.support.GenericApplicationContext@5f3a906e: startup date [Mon Jan 10 18:00:54 CET 2022]; root of context hierarchy
2022-01-10 18:00:54,897 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/add/{a}/{b}],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest$Result> org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest.add(java.lang.Integer,java.lang.Integer) throws org.appng.api.BusinessException
2022-01-10 18:00:54,905 WARN [main] org.appng.core.controller.handler.RestService: no @RestController found for /services/site1/appng-demoapplication/rest/notfound
2022-01-10 18:00:55,912 INFO [main] org.appng.api.RequestUtil : site 'localhost' is currently in state STARTING, waited 1000ms
2022-01-10 18:00:55,912 WARN [main] org.appng.core.controller.handler.ServiceRequestHandler: Site 'localhost' is in state STARTING, returning 503 (path: /services/localhost/foobar)
2022-01-10 18:00:55,921 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@65103a7a: startup date [Mon Jan 10 18:00:55 CET 2022]; root of context hierarchy
2022-01-10 18:00:55,924 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.context.support.GenericApplicationContext@65103a7a: startup date [Mon Jan 10 18:00:55 CET 2022]; root of context hierarchy
2022-01-10 18:00:55,926 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/add/{a}/{b}],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest$Result> org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest.add(java.lang.Integer,java.lang.Integer) throws org.appng.api.BusinessException
2022-01-10 18:00:55,941 INFO [main] org.springframework.web.servlet.mvc.method.annotation.ExceptionHandlerExceptionResolver: Detected @ExceptionHandler methods in foobarRest
2022-01-10 18:00:55,993 INFO [main] org.springframework.context.support.GenericApplicationContext: Refreshing org.springframework.context.support.GenericApplicationContext@44b7724c: startup date [Mon Jan 10 18:00:55 CET 2022]; root of context hierarchy
2022-01-10 18:00:55,996 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter: Looking for @ControllerAdvice: org.springframework.context.support.GenericApplicationContext@44b7724c: startup date [Mon Jan 10 18:00:55 CET 2022]; root of context hierarchy
2022-01-10 18:00:55,997 INFO [main] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping: Mapped "{[/add/{a}/{b}],methods=[GET],produces=[application/json;charset=UTF-8]}" onto public org.springframework.http.ResponseEntity<org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest$Result> org.appng.core.controller.handler.ServiceRequestHandlerTest$FoobarRest.add(java.lang.Integer,java.lang.Integer) throws org.appng.api.BusinessException
2022-01-10 18:00:56,011 INFO [main] org.springframework.web.servlet.mvc.method.annotation.ExceptionHandlerExceptionResolver: Detected @ExceptionHandler methods in foobarRest
2022-01-10 18:00:56,036 WARN [main] org.appng.core.controller.handler.ServiceRequestHandler: No such site: 'foo', returning 404 (path: /services/foo/bar?jin=fizz)
[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.875 s - in org.appng.core.controller.handler.ServiceRequestHandlerTest
[INFO] Running org.appng.core.controller.handler.MonitoringHandlerTest
2022-01-10 18:00:56,074 WARN [main] org.appng.core.service.HazelcastConfigurer: No Hazelcast configuration could be found, using default!
Jan 10, 2022 6:00:56 PM com.hazelcast.internal.config.AbstractConfigLocator
INFO: Loading 'hazelcast.xml' from the classpath.
2022-01-10 18:00:56,170 INFO [main] com.hazelcast.system.logo : [10.102.4.100]:5702 [appNG] [5.0]
+ + o o o o---o o----o o o---o o o----o o--o--o
+ + + + | | / \ / | | / / \ | |
+ + + + + o----o o o o o----o | o o o o----o |
+ + + + | | / \ / | | \ / \ | |
+ + o o o o o---o o----o o----o o---o o o o----o o
2022-01-10 18:00:56,171 INFO [main] com.hazelcast.system : [10.102.4.100]:5702 [appNG] [5.0] Copyright (c) 2008-2021, Hazelcast, Inc. All Rights Reserved.
2022-01-10 18:00:56,171 INFO [main] com.hazelcast.system : [10.102.4.100]:5702 [appNG] [5.0] Hazelcast Platform 5.0 (20210922 - dbaeffe) starting at [10.102.4.100]:5702
2022-01-10 18:00:56,171 INFO [main] com.hazelcast.system : [10.102.4.100]:5702 [appNG] [5.0] Cluster name: appNG
2022-01-10 18:00:56,171 INFO [main] com.hazelcast.system : [10.102.4.100]:5702 [appNG] [5.0] The Jet engine is disabled.
To enable the Jet engine on the members, please do one of the following:
- Change member config using Java API: config.getJetConfig().setEnabled(true);
- Change XML/YAML configuration property: Set hazelcast.jet.enabled to true
- Add system property: -Dhz.jet.enabled=true
- Add environment variable: HZ_JET_ENABLED=true
2022-01-10 18:00:56,195 INFO [main] com.hazelcast.system.security : [10.102.4.100]:5702 [appNG] [5.0] Enable DEBUG/FINE log level for log category com.hazelcast.system.security or use -Dhazelcast.security.recommendations system property to see 🔒 security recommendations and the status of current config.
2022-01-10 18:00:56,197 INFO [main] com.hazelcast.instance.impl.Node: [10.102.4.100]:5702 [appNG] [5.0] Using Multicast discovery
2022-01-10 18:00:56,197 WARN [main] com.hazelcast.cp.CPSubsystem : [10.102.4.100]:5702 [appNG] [5.0] CP Subsystem is not enabled. CP data structures will operate in UNSAFE mode! Please note that UNSAFE mode will not provide strong consistency guarantees.
2022-01-10 18:00:56,237 INFO [main] com.hazelcast.internal.diagnostics.Diagnostics: [10.102.4.100]:5702 [appNG] [5.0] Diagnostics disabled. To enable add -Dhazelcast.diagnostics.enabled=true to the JVM arguments.
2022-01-10 18:00:56,237 INFO [main] com.hazelcast.core.LifecycleService: [10.102.4.100]:5702 [appNG] [5.0] [10.102.4.100]:5702 is STARTING
2022-01-10 18:00:58,175 INFO [main] com.hazelcast.internal.cluster.ClusterService: [10.102.4.100]:5702 [appNG] [5.0]
Members {size:1, ver:1} [
Member [10.102.4.100]:5702 - c847fb9d-80c3-46e2-93e2-fec173c07d76 this
]
2022-01-10 18:00:58,175 WARN [main] com.hazelcast.instance.impl.Node: [10.102.4.100]:5702 [appNG] [5.0] Config seed port is 5701 and cluster size is 1. Some of the ports seem occupied!
2022-01-10 18:00:58,176 INFO [main] com.hazelcast.core.LifecycleService: [10.102.4.100]:5702 [appNG] [5.0] [10.102.4.100]:5702 is STARTED
2022-01-10 18:00:58,176 INFO [main] org.appng.core.service.HazelcastConfigurer: Created default instance HazelcastInstance{name='test', node=[10.102.4.100]:5702}
[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.193 s - in org.appng.core.controller.handler.MonitoringHandlerTest
[INFO] Running org.appng.core.controller.RepositoryWatcherTest
2022-01-10 18:00:58,249 INFO [Time-limited test] com.hazelcast.internal.partition.impl.PartitionStateManager: [10.102.4.100]:5702 [appNG] [5.0] Initializing cluster partition table arrangement...
2022-01-10 18:00:58,320 INFO [Time-limited test] com.hazelcast.cache.impl.CacheService: [10.102.4.100]:5702 [appNG] [5.0] Added cache config: CacheConfig{name='pageCache-localhost', managerPrefix='/hz/', inMemoryFormat=BINARY, backupCount=1, hotRestart=HotRestartConfig{enabled=false, fsync=false}, dataPersistenceConfig=DataPersistenceConfig{enabled=false, fsync=false}, wanReplicationRef=null, merkleTreeConfig=MerkleTreeConfig{enabled=null, depth=10}}
2022-01-10 18:00:58,371 INFO [Time-limited test] org.appng.core.service.CacheService: Created cache 'pageCache-localhost' (ttl: 1800s, maximum entries: 20000, statistics: true)
2022-01-10 18:00:59,000 INFO [Time-limited test] org.appng.core.controller.RepositoryWatcher: /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/conf/urlrewrite.xml has been read, 9 forward rules have been processed
2022-01-10 18:00:59,000 INFO [Time-limited test] org.appng.core.controller.RepositoryWatcher: watching /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/conf
2022-01-10 18:00:59,002 INFO [Time-limited test] org.appng.core.controller.RepositoryWatcher: watching /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de
2022-01-10 18:00:59,003 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: start watching...
2022-01-10 18:00:59,005 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for fehler.jsp
2022-01-10 18:00:59,043 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for test.jsp
2022-01-10 18:00:59,051 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/conf) received ENTRY_MODIFY for urlrewrite.xml
2022-01-10 18:00:59,068 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/conf/urlrewrite.xml has been read, 9 forward rules have been processed
2022-01-10 18:00:59,139 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 1 cache elements for /de/fault (cache size: 4)
2022-01-10 18:00:59,142 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 1 cache elements for /de/fehler (cache size: 4)
2022-01-10 18:00:59,158 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 1 cache elements for /de/error (cache size: 2)
2022-01-10 18:00:59,166 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 1 cache elements for /de/test (cache size: 1)
2022-01-10 18:00:59,201 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo0.txt
2022-01-10 18:00:59,201 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo0.txt
2022-01-10 18:00:59,209 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo0.txt
2022-01-10 18:00:59,211 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo1.txt
2022-01-10 18:00:59,216 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo1.txt
2022-01-10 18:00:59,219 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo1.txt
2022-01-10 18:00:59,224 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo2.txt
2022-01-10 18:00:59,226 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo2.txt
2022-01-10 18:00:59,236 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo2.txt
2022-01-10 18:00:59,243 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo3.txt
2022-01-10 18:00:59,243 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo3.txt
2022-01-10 18:00:59,243 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo3.txt
2022-01-10 18:00:59,244 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo0.txt (cache size: 0)
2022-01-10 18:00:59,244 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo4.txt
2022-01-10 18:00:59,244 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo4.txt
2022-01-10 18:00:59,244 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo4.txt
2022-01-10 18:00:59,245 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo5.txt
2022-01-10 18:00:59,245 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo5.txt
2022-01-10 18:00:59,245 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo5.txt
2022-01-10 18:00:59,245 WARN [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: events for /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de overflowed after 22 events
2022-01-10 18:00:59,246 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo185.txt
2022-01-10 18:00:59,258 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo0.txt (cache size: 0)
2022-01-10 18:00:59,259 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo186.txt
2022-01-10 18:00:59,273 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo186.txt
2022-01-10 18:00:59,274 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo186.txt
2022-01-10 18:00:59,278 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo187.txt
2022-01-10 18:00:59,285 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo1.txt (cache size: 0)
2022-01-10 18:00:59,299 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo187.txt
2022-01-10 18:00:59,300 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo187.txt
2022-01-10 18:00:59,307 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo188.txt
2022-01-10 18:00:59,314 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo188.txt
2022-01-10 18:00:59,319 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo188.txt
2022-01-10 18:00:59,326 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo189.txt
2022-01-10 18:00:59,326 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo189.txt
2022-01-10 18:00:59,329 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo189.txt
2022-01-10 18:00:59,330 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo190.txt
2022-01-10 18:00:59,330 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo190.txt
2022-01-10 18:00:59,330 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo190.txt
2022-01-10 18:00:59,330 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo191.txt
2022-01-10 18:00:59,331 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo191.txt
2022-01-10 18:00:59,331 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo191.txt
2022-01-10 18:00:59,331 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo192.txt
2022-01-10 18:00:59,331 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo192.txt
2022-01-10 18:00:59,332 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo192.txt
2022-01-10 18:00:59,338 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo193.txt
2022-01-10 18:00:59,344 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo193.txt
2022-01-10 18:00:59,389 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo193.txt
2022-01-10 18:00:59,396 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo194.txt
2022-01-10 18:00:59,399 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_MODIFY for foo194.txt
2022-01-10 18:00:59,400 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo194.txt
2022-01-10 18:00:59,405 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_CREATE for foo195.txt
2022-01-10 18:00:59,407 WARN [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: events for /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de overflowed after 52 events
2022-01-10 18:00:59,407 INFO [repositoryWatcher] org.appng.core.controller.RepositoryWatcher: (/var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/test-classes/repository/manager/www/de) received ENTRY_DELETE for foo750.txt
2022-01-10 18:00:59,554 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo2.txt (cache size: 0)
2022-01-10 18:00:59,559 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo1.txt (cache size: 0)
2022-01-10 18:00:59,562 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo2.txt (cache size: 0)
2022-01-10 18:00:59,568 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo0.txt (cache size: 0)
2022-01-10 18:00:59,569 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo4.txt (cache size: 0)
2022-01-10 18:00:59,571 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo3.txt (cache size: 0)
2022-01-10 18:00:59,572 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo4.txt (cache size: 0)
2022-01-10 18:00:59,574 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo3.txt (cache size: 0)
2022-01-10 18:00:59,575 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo3.txt (cache size: 0)
2022-01-10 18:00:59,581 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo4.txt (cache size: 0)
2022-01-10 18:00:59,581 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo5.txt (cache size: 0)
2022-01-10 18:00:59,582 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo5.txt (cache size: 0)
2022-01-10 18:00:59,584 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo5.txt (cache size: 0)
2022-01-10 18:00:59,586 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo1.txt (cache size: 0)
2022-01-10 18:00:59,588 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo2.txt (cache size: 0)
2022-01-10 18:00:59,597 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo185.txt (cache size: 0)
2022-01-10 18:00:59,598 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo186.txt (cache size: 0)
2022-01-10 18:00:59,614 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo186.txt (cache size: 0)
2022-01-10 18:00:59,620 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo189.txt (cache size: 0)
2022-01-10 18:00:59,621 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo190.txt (cache size: 0)
2022-01-10 18:00:59,621 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo188.txt (cache size: 0)
2022-01-10 18:00:59,621 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo189.txt (cache size: 0)
2022-01-10 18:00:59,622 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo190.txt (cache size: 0)
2022-01-10 18:00:59,622 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo186.txt (cache size: 0)
2022-01-10 18:00:59,622 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo193.txt (cache size: 0)
2022-01-10 18:00:59,623 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo189.txt (cache size: 0)
2022-01-10 18:00:59,623 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo187.txt (cache size: 0)
2022-01-10 18:00:59,623 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo187.txt (cache size: 0)
2022-01-10 18:00:59,624 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo187.txt (cache size: 0)
2022-01-10 18:00:59,625 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo188.txt (cache size: 0)
2022-01-10 18:00:59,626 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo188.txt (cache size: 0)
2022-01-10 18:00:59,626 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo192.txt (cache size: 0)
2022-01-10 18:00:59,627 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo192.txt (cache size: 0)
2022-01-10 18:00:59,627 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo191.txt (cache size: 0)
2022-01-10 18:00:59,627 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo191.txt (cache size: 0)
2022-01-10 18:00:59,627 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo191.txt (cache size: 0)
2022-01-10 18:00:59,627 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo192.txt (cache size: 0)
2022-01-10 18:00:59,628 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo190.txt (cache size: 0)
2022-01-10 18:00:59,629 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo193.txt (cache size: 0)
2022-01-10 18:00:59,630 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo194.txt (cache size: 0)
2022-01-10 18:00:59,630 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo193.txt (cache size: 0)
2022-01-10 18:00:59,631 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo194.txt (cache size: 0)
2022-01-10 18:00:59,631 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo194.txt (cache size: 0)
2022-01-10 18:00:59,632 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo750.txt (cache size: 0)
2022-01-10 18:00:59,632 INFO [pageCache-localhost] org.appng.core.service.CacheService: removed 0 cache elements for /de/foo195.txt (cache size: 0)
[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.175 s - in org.appng.core.controller.RepositoryWatcherTest
[INFO] Running org.appng.core.controller.SessionListenerTest
2022-01-10 18:01:00,460 INFO [main] org.appng.core.controller.SessionListener: Session created: ZUS383883OTOTOLSKKL (created: 2022-01-10 18:01:00)
2022-01-10 18:01:00,461 INFO [main] org.appng.core.controller.SessionListener: Session created: ERTERTZGFHFGHGFH234 (created: 2022-01-10 18:01:00)
2022-01-10 18:01:00,462 INFO [main] org.appng.core.controller.SessionListener: Session destroyed: ZUS383883OTOTOLSKKL (created: 2022-01-10 18:01:00, accessed: 2022-01-10 18:01:00, requests: 1, domain: http://localhost:8080, user-agent: null)
2022-01-10 18:01:00,464 INFO [main] org.appng.core.controller.SessionListener: Session created: ZUS383883OTOTOLSKKL (created: 2022-01-10 18:01:00)
[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.043 s - in org.appng.core.controller.SessionListenerTest
[INFO] Running org.appng.core.controller.ControllerTest
2022-01-10 18:01:00,489 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:00,516 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#902132056 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:00,835 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:00,851 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#1177994698 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:00,923 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:00,948 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#1515948058 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:00,966 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:00,987 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#291498121 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,001 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,018 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#450617383 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,036 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,053 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#1231502563 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,068 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,084 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#2123648654 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,098 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,114 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#18921311 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,131 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,147 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#1359955872 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,160 INFO [main] org.appng.core.Redirect : Redirecting request to /de/index (301)
2022-01-10 18:01:01,161 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,176 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#2040252203 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,197 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,214 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#1658093141 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,232 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,250 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#1769107784 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,263 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,280 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#1482552361 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,295 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,311 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#481575754 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
2022-01-10 18:01:01,324 INFO [main] org.appng.core.controller.Controller: Controller created
2022-01-10 18:01:01,339 INFO [main] org.appng.api.support.SiteClassLoader: SiteClassLoader#1130636202 for site manager with parent sun.misc.Launcher$AppClassLoader#1252169911 created
[ERROR] Java HotSpot(TM) 64-Bit Server VM warning: INFO: os::commit_memory(0x00000000d9a00000, 96468992, 0) failed; error='Cannot allocate memory' (errno=12)
[INFO]
[INFO] Results:
[INFO]
[WARNING] Tests run: 305, Failures: 0, Errors: 0, Skipped: 29
[INFO]
[WARNING] Corrupted STDOUT by directly writing to native stream in forked JVM 1. See FAQ web page and the dump file /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/surefire-reports/2022-01-10T17-56-28_777-jvmRun1.dumpstream
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] appNG Parent ....................................... SUCCESS [ 4.970 s]
[INFO] appNG Tools ........................................ SUCCESS [ 24.274 s]
[INFO] appNG Mail ......................................... SUCCESS [ 6.530 s]
[INFO] appNG Forms ........................................ SUCCESS [ 7.618 s]
[INFO] appng Formtags ..................................... SUCCESS [ 6.842 s]
[INFO] appNG XML-API ...................................... SUCCESS [ 11.895 s]
[INFO] appNG public API ................................... SUCCESS [ 19.060 s]
[INFO] appNG testsupport .................................. SUCCESS [ 10.559 s]
[INFO] appNG REST API ..................................... SUCCESS [ 17.931 s]
[INFO] appNG Persistence-Tools ............................ SUCCESS [ 34.569 s]
[INFO] appNG Search ....................................... SUCCESS [ 21.378 s]
[INFO] appNG Core Library ................................. FAILURE [02:01 min]
[INFO] appNG Command Line Interface ....................... SKIPPED
[INFO] appNG Tag Library .................................. SKIPPED
[INFO] appNGizer JAXB API ................................. SKIPPED
[INFO] appNGizer .......................................... SKIPPED
[INFO] appNG Application .................................. SKIPPED
[INFO] appNG application BOM .............................. SKIPPED
[INFO] appNG Documentation ................................ SKIPPED
[INFO] appNG Template Assembly Descriptors ................ SKIPPED
[INFO] appNG Template Parent .............................. SKIPPED
[INFO] appng-application-assembly ......................... SKIPPED
[INFO] appNG application parent ........................... SKIPPED
[INFO] Archetype - appng-archetype-application ............ SKIPPED
[INFO] appNG appNGizer Maven Plugin ....................... SKIPPED
[INFO] appNG camunda ...................................... SKIPPED
[INFO] appNG Application, including Camunda BPM process engine libraries SKIPPED
[INFO] appNG Maven Plugin ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 04:47 min
[INFO] Finished at: 2022-01-10T18:01:02+01:00
[INFO] Final Memory: 116M/1389M
[INFO] ------------------------------------------------------------------------
[WARNING] The requested profile "dummy" could not be activated because it does not exist.
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:3.0.0-M5:test (default-test) on project appng-core: There are test failures.
[ERROR]
[ERROR] Please refer to /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/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] The forked VM terminated without properly saying goodbye. VM crash or System.exit called?
[ERROR] Command was /bin/sh -c cd /var/lib/jenkins/workspace/appNG_1.24.x/appng-core && /usr/lib/jvm/jdk-8-oracle-x64/jre/bin/java -Xms512m -Xmx1g -XX:MaxMetaspaceSize=256m -Dfile.encoding=UTF-8 -jar /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/surefire/surefirebooter5272071651053524877.jar /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/surefire 2022-01-10T17-56-28_777-jvmRun1 surefire8552048741778468999tmp surefire_101733666544791478862tmp
[ERROR] Error occurred in starting fork, check output in log
[ERROR] Process Exit Code: 1
[ERROR] Crashed tests:
[ERROR] org.appng.core.controller.ControllerTest
[ERROR] org.apache.maven.surefire.booter.SurefireBooterForkException: The forked VM terminated without properly saying goodbye. VM crash or System.exit called?
[ERROR] Command was /bin/sh -c cd /var/lib/jenkins/workspace/appNG_1.24.x/appng-core && /usr/lib/jvm/jdk-8-oracle-x64/jre/bin/java -Xms512m -Xmx1g -XX:MaxMetaspaceSize=256m -Dfile.encoding=UTF-8 -jar /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/surefire/surefirebooter5272071651053524877.jar /var/lib/jenkins/workspace/appNG_1.24.x/appng-core/target/surefire 2022-01-10T17-56-28_777-jvmRun1 surefire8552048741778468999tmp surefire_101733666544791478862tmp
[ERROR] Error occurred in starting fork, check output in log
[ERROR] Process Exit Code: 1
[ERROR] Crashed tests:
[ERROR] org.appng.core.controller.ControllerTest
[ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.fork(ForkStarter.java:748)
[ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:305)
[ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:265)
[ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:1314)
[ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:1159)
[ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:932)
[ERROR] at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:134)
[ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:208)
[ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:154)
[ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:146)
[ERROR] at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:117)
[ERROR] at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:81)
[ERROR] at org.apache.maven.lifecycle.internal.builder.singlethreaded.SingleThreadedBuilder.build(SingleThreadedBuilder.java:51)
[ERROR] at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:128)
[ERROR] at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:309)
[ERROR] at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:194)
[ERROR] at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:107)
[ERROR] at org.apache.maven.cli.MavenCli.execute(MavenCli.java:993)
[ERROR] at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:345)
[ERROR] at org.apache.maven.cli.MavenCli.main(MavenCli.java:191)
[ERROR] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[ERROR] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[ERROR] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[ERROR] at java.lang.reflect.Method.invoke(Method.java:498)
[ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:289)
[ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:229)
[ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:415)
[ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:356)
[ERROR]
[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/MojoExecutionException
[ERROR]
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR] mvn <goals> -rf :appng-core
![[Jenkins]](/jenkins/static/0ac95dd5/images/svgs/logo.svg){kind=logo}
