See <http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Quickstart... Changes: [tm.igarashi] SWITCHYARD-586 reproducer ------------------------------------------ [...truncated 2842 lines...] [INFO] [INFO] --- maven-compiler-plugin:2.3.2:testCompile (default-testCompile) @ switchyard-quickstart-camel-jpa-binding --- [INFO] Compiling 3 source files to <http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Quickstart... [INFO] [INFO] --- maven-surefire-plugin:2.6:test (default-test) @ switchyard-quickstart-camel-jpa-binding --- [INFO] Surefire report directory: <http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Quickstart... ------------------------------------------------------- T E S T S ------------------------------------------------------- Running org.switchyard.quickstarts.camel.jpa.binding.CamelJpaBindingStoreTest 21:22:12,076 INFO main [org.jboss.weld.Version] WELD-000900 1.1.5 (AS71) 21:22:15,517 INFO main [org.apache.camel.management.ManagementStrategyFactory] JMX enabled. 21:22:15,572 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Apache Camel 2.10.0 (CamelContext: camel-1) is starting 21:22:15,578 INFO main [org.apache.camel.management.DefaultManagementLifecycleStrategy] StatisticsLevel at All so enabling load performance statistics 21:22:16,347 INFO main [org.apache.camel.impl.converter.DefaultTypeConverter] Loaded 185 type converters 21:22:16,457 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Total 0 routes, of which 0 is started. 21:22:16,461 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Apache Camel 2.10.0 (CamelContext: camel-1) started in 0.886 seconds 21:22:18,560 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference started and consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DStoreReference] 21:22:19,325 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}GreetingService started and consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DGreetingService] 21:22:19,583 INFO main [org.springframework.transaction.jta.JtaTransactionManager] Using JTA UserTransaction: Transaction: unknown 21:22:19,584 INFO main [org.springframework.transaction.jta.JtaTransactionManager] Using JTA TransactionManager: Transaction: unknown 21:22:19,585 INFO main [org.springframework.transaction.jta.JtaTransactionManager] Using JTA TransactionSynchronizationRegistry: com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionSynchronizationRegistryImple@468034b6 21:22:19,691 INFO main [org.apache.camel.component.jpa.JpaComponent] Using TransactionManager found in registry with id [jtaTransactionManager] org.springframework.transaction.jta.JtaTransactionManager@3ea763a 21:22:19,692 WARN main [org.apache.camel.component.jpa.JpaComponent] No EntityManagerFactory has been configured on this JpaComponent. Each JpaEndpoint will auto create their own EntityManagerFactory. 21:22:20,264 INFO main [org.hibernate.annotations.common.Version] HCANN000001: Hibernate Commons Annotations {4.0.1.Final} 21:22:20,288 INFO main [org.hibernate.Version] HHH000412: Hibernate Core {4.0.1.Final} 21:22:20,296 INFO main [org.hibernate.cfg.Environment] HHH000206: hibernate.properties not found 21:22:20,308 INFO main [org.hibernate.cfg.Environment] HHH000021: Bytecode provider name : javassist 21:22:21,100 INFO main [org.hibernate.dialect.Dialect] HHH000400: Using dialect: org.hibernate.dialect.H2Dialect 21:22:21,131 INFO main [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] HHH000423: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 21:22:21,172 INFO main [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory 21:22:21,188 INFO main [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] HHH000397: Using ASTQueryTranslatorFactory 21:22:21,349 INFO main [org.hibernate.validator.util.Version] Hibernate Validator 4.2.0.Final 21:22:22,072 INFO main [org.hibernate.tool.hbm2ddl.SchemaExport] HHH000227: Running hbm2ddl schema export 21:22:22,096 INFO main [org.hibernate.tool.hbm2ddl.SchemaExport] HHH000230: Schema export complete 21:22:22,606 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelJpaBindingModel/GreetingService@-816104649 started and consuming from: Endpoint[jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet?consumeDelete=true&consumeLockEntity=false&consumer.transacted=true&delay=500&initialDelay=1000&persistenceUnit=JpaEvents&transactionManager=%23jtaTransactionManager] 21:22:23,039 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}PeriodicService started and consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DPeriodicService] 21:22:23,184 INFO main [org.quartz.simpl.SimpleThreadPool] Job execution threads will use class loader of thread: main 21:22:23,219 INFO main [org.quartz.core.SchedulerSignalerImpl] Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 21:22:23,219 INFO main [org.quartz.core.QuartzScheduler] Quartz Scheduler v.1.8.5 created. 21:22:23,225 INFO main [org.quartz.simpl.RAMJobStore] RAMJobStore initialized. 21:22:23,229 INFO main [org.quartz.core.QuartzScheduler] Scheduler meta-data: Quartz Scheduler (v1.8.5) 'DefaultQuartzScheduler-camel-1' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads. Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered. 21:22:23,231 INFO main [org.quartz.impl.StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler-camel-1' initialized from an externally provided properties instance. 21:22:23,231 INFO main [org.quartz.impl.StdSchedulerFactory] Quartz scheduler version: 1.8.5 21:22:23,232 INFO main [org.apache.camel.component.quartz.QuartzComponent] Starting Quartz scheduler: DefaultQuartzScheduler-camel-1 21:22:23,232 INFO main [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler-camel-1_$_NON_CLUSTERED started. 21:22:23,370 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelQuartzBindingModel/PeriodicService@494946266 started and consuming from: Endpoint[quartz://EveryFiveSecondsJob?cron=*%2F5+*+*+*+*+%3F] 21:22:23,449 INFO main [org.switchyard.bus.camel.CamelExchangeBus] Removing route direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference 21:22:23,452 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Starting to graceful shutdown 1 routes (timeout 300 seconds) 21:22:23,463 INFO Camel (camel-1) thread #2 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference shutdown complete, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DStoreReference] 21:22:23,464 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Graceful shutdown of 1 routes completed in 0 seconds 21:22:23,474 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference stopped, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DStoreReference] 21:22:23,481 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference shutdown and removed, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DStoreReference] 21:22:23,953 INFO Camel (camel-1) thread #1 - jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet [com.arjuna.ats.arjuna] ARJUNA012163: Starting service com.arjuna.ats.arjuna.recovery.ActionStatusService on port 56794 21:22:24,402 INFO Camel (camel-1) thread #1 - jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet [com.arjuna.ats.arjuna] ARJUNA012337: TransactionStatusManagerItem host: 127.0.0.1 port: 56794 21:22:24,444 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference started and consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DStoreReference] 21:22:24,488 INFO Camel (camel-1) thread #1 - jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet [com.arjuna.ats.arjuna] ARJUNA012170: TransactionStatusManager started on port 56794 and host 127.0.0.1 with service com.arjuna.ats.arjuna.recovery.ActionStatusService 21:22:24,870 INFO main [org.hibernate.dialect.Dialect] HHH000400: Using dialect: org.hibernate.dialect.H2Dialect 21:22:24,889 INFO main [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] HHH000423: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 21:22:24,910 INFO main [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory 21:22:24,928 INFO main [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] HHH000397: Using ASTQueryTranslatorFactory 21:22:25,035 INFO main [org.hibernate.tool.hbm2ddl.SchemaExport] HHH000227: Running hbm2ddl schema export 21:22:25,052 INFO main [org.hibernate.tool.hbm2ddl.SchemaExport] HHH000230: Schema export complete 21:22:25,055 WARN main [org.hibernate.ejb.internal.EntityManagerFactoryRegistry] HHH000436: Entity manager factory name (JpaEvents) is already registered. If entity manager will be clustered or passivated, specify a unique value for property 'hibernate.ejb.entitymanager_factory_name' 21:22:25,939 INFO DefaultQuartzScheduler-camel-1_Worker-1 [org.switchyard.quickstarts.camel.jpa.binding.PeriodicServiceImpl] Sending Greet [null, from Tomo, to Lukasz, created on Mon Jan 14 21:22:25 EST 2013] to JPA _store 21:22:26,419 INFO Camel (camel-1) thread #1 - jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet [org.switchyard.quickstarts.camel.jpa.binding.GreetingServiceBean] Hey Lukasz please receive greetings from Tomo sent at 21:22:25:926 21:22:26,485 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Starting to graceful shutdown 1 routes (timeout 300 seconds) 21:22:26,487 INFO Camel (camel-1) thread #2 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Waiting as there are still 1 inflight and pending exchanges to complete, timeout in 300 seconds. 21:22:27,494 INFO Camel (camel-1) thread #2 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: V1CamelJpaBindingModel/GreetingService@-816104649 shutdown complete, was consuming from: Endpoint[jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet?consumeDelete=true&consumeLockEntity=false&consumer.transacted=true&delay=500&initialDelay=1000&persistenceUnit=JpaEvents&transactionManager=%23jtaTransactionManager] 21:22:27,494 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Graceful shutdown of 1 routes completed in 1 seconds 21:22:27,499 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelJpaBindingModel/GreetingService@-816104649 stopped, was consuming from: Endpoint[jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet?consumeDelete=true&consumeLockEntity=false&consumer.transacted=true&delay=500&initialDelay=1000&persistenceUnit=JpaEvents&transactionManager=%23jtaTransactionManager] 21:22:27,509 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelJpaBindingModel/GreetingService@-816104649 shutdown and removed, was consuming from: Endpoint[jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet?consumeDelete=true&consumeLockEntity=false&consumer.transacted=true&delay=500&initialDelay=1000&persistenceUnit=JpaEvents&transactionManager=%23jtaTransactionManager] 21:22:27,511 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Starting to graceful shutdown 1 routes (timeout 300 seconds) 21:22:27,514 INFO Camel (camel-1) thread #2 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: V1CamelQuartzBindingModel/PeriodicService@494946266 shutdown complete, was consuming from: Endpoint[quartz://EveryFiveSecondsJob?cron=*%2F5+*+*+*+*+%3F] 21:22:27,515 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Graceful shutdown of 1 routes completed in 0 seconds 21:22:27,517 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelQuartzBindingModel/PeriodicService@494946266 stopped, was consuming from: Endpoint[quartz://EveryFiveSecondsJob?cron=*%2F5+*+*+*+*+%3F] 21:22:27,522 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelQuartzBindingModel/PeriodicService@494946266 shutdown and removed, was consuming from: Endpoint[quartz://EveryFiveSecondsJob?cron=*%2F5+*+*+*+*+%3F] 21:22:27,526 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Apache Camel 2.10.0 (CamelContext: camel-1) is shutting down 21:22:27,527 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Starting to graceful shutdown 3 routes (timeout 300 seconds) 21:22:27,528 INFO Camel (camel-1) thread #2 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference shutdown complete, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DStoreReference] 21:22:27,530 INFO Camel (camel-1) thread #2 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}PeriodicService shutdown complete, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DPeriodicService] 21:22:27,530 INFO Camel (camel-1) thread #2 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}GreetingService shutdown complete, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DGreetingService] 21:22:27,533 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Graceful shutdown of 3 routes completed in 0 seconds 21:22:27,560 INFO main [org.apache.camel.component.quartz.QuartzComponent] There are no more jobs registered, so shutting down Quartz scheduler: DefaultQuartzScheduler-camel-1 21:22:27,561 INFO main [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler-camel-1_$_NON_CLUSTERED shutting down. 21:22:27,562 INFO main [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler-camel-1_$_NON_CLUSTERED paused. 21:22:27,563 INFO main [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler-camel-1_$_NON_CLUSTERED shutdown complete. 21:22:27,567 INFO main [org.apache.camel.impl.converter.DefaultTypeConverter] TypeConverterRegistry utilization[attempts=12, hits=11, misses=1, failures=0] mappings[total=188, misses=0] 21:22:27,573 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Apache Camel 2.10.0 (CamelContext: camel-1) is shutdown in 0.047 seconds. Uptime 12.003 seconds. Tests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 20.13 sec <<< FAILURE! Running org.switchyard.quickstarts.camel.jpa.binding.CamelJpaBindingReceiveTest 21:22:31,631 INFO main [org.apache.camel.management.ManagementStrategyFactory] JMX enabled. 21:22:32,049 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Apache Camel 2.10.0 (CamelContext: camel-2) is starting 21:22:32,049 INFO main [org.apache.camel.management.DefaultManagementLifecycleStrategy] StatisticsLevel at All so enabling load performance statistics 21:22:32,654 INFO main [org.apache.camel.impl.converter.DefaultTypeConverter] Loaded 185 type converters 21:22:32,676 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Total 0 routes, of which 0 is started. 21:22:32,678 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Apache Camel 2.10.0 (CamelContext: camel-2) started in 1.043 seconds 21:22:33,336 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference started and consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DStoreReference] 21:22:33,777 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}GreetingService started and consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DGreetingService] 21:22:33,797 INFO main [org.springframework.transaction.jta.JtaTransactionManager] Using JTA UserTransaction: Transaction: unknown 21:22:33,798 INFO main [org.springframework.transaction.jta.JtaTransactionManager] Using JTA TransactionManager: Transaction: unknown 21:22:33,800 INFO main [org.springframework.transaction.jta.JtaTransactionManager] Using JTA TransactionSynchronizationRegistry: com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionSynchronizationRegistryImple@625db8ff 21:22:33,819 INFO main [org.apache.camel.component.jpa.JpaComponent] Using TransactionManager found in registry with id [jtaTransactionManager] org.springframework.transaction.jta.JtaTransactionManager@783f472b 21:22:33,820 WARN main [org.apache.camel.component.jpa.JpaComponent] No EntityManagerFactory has been configured on this JpaComponent. Each JpaEndpoint will auto create their own EntityManagerFactory. 21:22:34,026 INFO main [org.hibernate.dialect.Dialect] HHH000400: Using dialect: org.hibernate.dialect.H2Dialect 21:22:34,029 INFO main [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] HHH000423: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 21:22:34,030 INFO main [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory 21:22:34,031 INFO main [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] HHH000397: Using ASTQueryTranslatorFactory 21:22:34,076 INFO main [org.hibernate.tool.hbm2ddl.SchemaExport] HHH000227: Running hbm2ddl schema export 21:22:34,083 INFO main [org.hibernate.tool.hbm2ddl.SchemaExport] HHH000230: Schema export complete 21:22:34,087 WARN main [org.hibernate.ejb.internal.EntityManagerFactoryRegistry] HHH000436: Entity manager factory name (JpaEvents) is already registered. If entity manager will be clustered or passivated, specify a unique value for property 'hibernate.ejb.entitymanager_factory_name' 21:22:34,130 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelJpaBindingModel/GreetingService@-816104649 started and consuming from: Endpoint[jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet?consumeDelete=true&consumeLockEntity=false&consumer.transacted=true&delay=500&initialDelay=1000&persistenceUnit=JpaEvents&transactionManager=%23jtaTransactionManager] 21:22:34,393 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}PeriodicService started and consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DPeriodicService] 21:22:34,411 INFO main [org.quartz.simpl.SimpleThreadPool] Job execution threads will use class loader of thread: main 21:22:34,420 INFO main [org.quartz.core.SchedulerSignalerImpl] Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl 21:22:34,420 INFO main [org.quartz.core.QuartzScheduler] Quartz Scheduler v.1.8.5 created. 21:22:34,420 INFO main [org.quartz.simpl.RAMJobStore] RAMJobStore initialized. 21:22:34,421 INFO main [org.quartz.core.QuartzScheduler] Scheduler meta-data: Quartz Scheduler (v1.8.5) 'DefaultQuartzScheduler-camel-2' with instanceId 'NON_CLUSTERED' Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. NOT STARTED. Currently in standby mode. Number of jobs executed: 0 Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads. Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered. 21:22:34,421 INFO main [org.quartz.impl.StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler-camel-2' initialized from an externally provided properties instance. 21:22:34,421 INFO main [org.quartz.impl.StdSchedulerFactory] Quartz scheduler version: 1.8.5 21:22:34,421 INFO main [org.apache.camel.component.quartz.QuartzComponent] Starting Quartz scheduler: DefaultQuartzScheduler-camel-2 21:22:34,421 INFO main [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler-camel-2_$_NON_CLUSTERED started. 21:22:34,580 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelQuartzBindingModel/PeriodicService@494946266 started and consuming from: Endpoint[quartz://EveryFiveSecondsJob?cron=*%2F5+*+*+*+*+%3F] 21:22:35,021 INFO DefaultQuartzScheduler-camel-2_Worker-1 [org.switchyard.quickstarts.camel.jpa.binding.PeriodicServiceImpl] Sending Greet [null, from Tomo, to Brian, created on Mon Jan 14 21:22:35 EST 2013] to JPA _store 21:22:35,033 WARN DefaultQuartzScheduler-camel-2_Worker-1 [org.switchyard.internal.ExchangeImpl] Fault generated during exchange without a handler: org.switchyard.HandlerException: org.switchyard.exception.SwitchYardException: No registered service found for {urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference --- Caused by org.switchyard.exception.SwitchYardException: No registered service found for {urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference 21:22:35,201 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Starting to graceful shutdown 1 routes (timeout 300 seconds) 21:22:35,206 INFO Camel (camel-2) thread #5 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: V1CamelJpaBindingModel/GreetingService@-816104649 shutdown complete, was consuming from: Endpoint[jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet?consumeDelete=true&consumeLockEntity=false&consumer.transacted=true&delay=500&initialDelay=1000&persistenceUnit=JpaEvents&transactionManager=%23jtaTransactionManager] 21:22:35,208 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Graceful shutdown of 1 routes completed in 0 seconds 21:22:35,212 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelJpaBindingModel/GreetingService@-816104649 stopped, was consuming from: Endpoint[jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet?consumeDelete=true&consumeLockEntity=false&consumer.transacted=true&delay=500&initialDelay=1000&persistenceUnit=JpaEvents&transactionManager=%23jtaTransactionManager] 21:22:35,224 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelJpaBindingModel/GreetingService@-816104649 shutdown and removed, was consuming from: Endpoint[jpa://org.switchyard.quickstarts.camel.jpa.binding.domain.Greet?consumeDelete=true&consumeLockEntity=false&consumer.transacted=true&delay=500&initialDelay=1000&persistenceUnit=JpaEvents&transactionManager=%23jtaTransactionManager] 21:22:35,225 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Starting to graceful shutdown 1 routes (timeout 300 seconds) 21:22:35,228 INFO Camel (camel-2) thread #5 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: V1CamelQuartzBindingModel/PeriodicService@494946266 shutdown complete, was consuming from: Endpoint[quartz://EveryFiveSecondsJob?cron=*%2F5+*+*+*+*+%3F] 21:22:35,228 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Graceful shutdown of 1 routes completed in 0 seconds 21:22:35,230 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelQuartzBindingModel/PeriodicService@494946266 stopped, was consuming from: Endpoint[quartz://EveryFiveSecondsJob?cron=*%2F5+*+*+*+*+%3F] 21:22:35,235 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Route: V1CamelQuartzBindingModel/PeriodicService@494946266 shutdown and removed, was consuming from: Endpoint[quartz://EveryFiveSecondsJob?cron=*%2F5+*+*+*+*+%3F] 21:22:35,238 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Apache Camel 2.10.0 (CamelContext: camel-2) is shutting down 21:22:35,239 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Starting to graceful shutdown 3 routes (timeout 300 seconds) 21:22:35,241 INFO Camel (camel-2) thread #5 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}PeriodicService shutdown complete, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DPeriodicService] 21:22:35,242 INFO Camel (camel-2) thread #5 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}GreetingService shutdown complete, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DGreetingService] 21:22:35,243 INFO Camel (camel-2) thread #5 - ShutdownTask [org.apache.camel.impl.DefaultShutdownStrategy] Route: direct:{urn:switchyard-quickstart:camel-jpa-binding:0.1.0}StoreReference shutdown complete, was consuming from: Endpoint[direct://%7Burn:switchyard-quickstart:camel-jpa-binding:0.1.0%7DStoreReference] 21:22:35,244 INFO main [org.apache.camel.impl.DefaultShutdownStrategy] Graceful shutdown of 3 routes completed in 0 seconds 21:22:35,269 INFO main [org.apache.camel.component.quartz.QuartzComponent] There are no more jobs registered, so shutting down Quartz scheduler: DefaultQuartzScheduler-camel-2 21:22:35,270 INFO main [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler-camel-2_$_NON_CLUSTERED shutting down. 21:22:35,270 INFO main [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler-camel-2_$_NON_CLUSTERED paused. 21:22:35,272 INFO main [org.quartz.core.QuartzScheduler] Scheduler DefaultQuartzScheduler-camel-2_$_NON_CLUSTERED shutdown complete. 21:22:35,274 INFO main [org.apache.camel.impl.converter.DefaultTypeConverter] TypeConverterRegistry utilization[attempts=11, hits=10, misses=1, failures=0] mappings[total=188, misses=0] 21:22:35,281 INFO main [org.switchyard.common.camel.SwitchYardCamelContext] Apache Camel 2.10.0 (CamelContext: camel-2) is shutdown in 0.042 seconds. Uptime 3.648 seconds. Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.67 sec Results : Failed tests: storeEntity(org.switchyard.quickstarts.camel.jpa.binding.CamelJpaBindingStoreTest) Tests run: 2, Failures: 1, Errors: 0, Skipped: 0 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] SwitchYard: Quickstarts ........................... SUCCESS [4.123s] [INFO] Quickstart : Bean Service ......................... SUCCESS [1:12.301s] [INFO] Quickstart : BPEL Service ......................... SUCCESS [9.388s] [INFO] Quickstart : BPEL Service : Say Hello ............. SUCCESS [41.636s] [INFO] Quickstart : BPEL Service : JMS Binding ........... SUCCESS [41.852s] [INFO] Quickstart : BPEL Service : Loan Approval ......... SUCCESS [46.908s] [INFO] Quickstart : BPEL Service : Simple Correlation .... SUCCESS [38.538s] [INFO] Quickstart : BPEL Service : XTS Subordinate WSBA .. SUCCESS [1.137s] [INFO] Quickstart : BPEL Service : XTS Subordinate WSBA : Business Travel Process SUCCESS [3.016s] [INFO] Quickstart : BPEL Service : XTS Subordinate WSBA : Airport Web Service SUCCESS [7.340s] [INFO] Quickstart : BPEL Service : XTS WSAT .............. SUCCESS [1.075s] [INFO] Quickstart : BPEL Service : XTS WSAT : Business Travel Process SUCCESS [5.287s] [INFO] Quickstart : BPEL Service : XTS WSAT : Airport Web Service SUCCESS [5.815s] [INFO] Quickstart : BPM Service .......................... SUCCESS [1:07.489s] [INFO] Quickstart : Camel Binding ........................ SUCCESS [19.246s] [INFO] Quickstart : Camel AMQP Binding ................... SUCCESS [27.514s] [INFO] Quickstart : Camel FTP Binding .................... SUCCESS [24.639s] [INFO] Quickstart : Camel JMS Binding .................... SUCCESS [19.571s] [INFO] Quickstart : Camel Netty Binding .................. SUCCESS [32.420s] [INFO] Quickstart : Camel Quartz Binding ................. SUCCESS [19.287s] [INFO] Quickstart : Camel JPA Binding .................... FAILURE [41.581s] [INFO] Quickstart : Camel SQL Binding .................... SKIPPED [INFO] Quickstart : Camel Mail Binding ................... SKIPPED [INFO] Quickstart : Camel Service ........................ SKIPPED [INFO] Quickstart : Camel SOAP Proxy ..................... SKIPPED [INFO] Quickstart : Camel Exchange Bus CDI ............... SKIPPED [INFO] Quickstart : HornetQ Binding ...................... SKIPPED [INFO] Quickstart : HTTP Binding ......................... SKIPPED [INFO] Quickstart : RESTEasy Binding ..................... SKIPPED [INFO] Quickstart : Rules+Camel Content-Based Router ..... SKIPPED [INFO] Quickstart : Rules Interview ...................... SKIPPED [INFO] Quickstart : Rules Interview Container ............ SKIPPED [INFO] Quickstart : JCA Inflow Binding for HornetQ ....... SKIPPED [INFO] Quickstart : JCA Outbound Binding for HornetQ ..... SKIPPED [INFO] Quickstart : JAXB Transformation .................. SKIPPED [INFO] Quickstart : JSON Transformation .................. SKIPPED [INFO] Quickstart : Smooks Transformation ................ SKIPPED [INFO] Quickstart : XSLT Transformation .................. SKIPPED [INFO] Quickstart : XML Message Validation ............... SKIPPED [INFO] Quickstart Demo : Help Desk ....................... SKIPPED [INFO] Quickstart Demo : Help Desk - Web App ............. SKIPPED [INFO] Quickstart Demo : Orders .......................... SKIPPED [INFO] Quickstart Demo : JEE Webapp Deployment ........... SKIPPED [INFO] Quickstart Demo: Multiple Applications - Service Artifacts SKIPPED [INFO] Quickstart Demo: Multiple Applications - Order Service SKIPPED [INFO] Quickstart Demo: Multiple Applications - Order Consumer SKIPPED [INFO] Quickstart Demo: Multiple Applications - Web App .. SKIPPED [INFO] Quickstart Demo: Multiple Applications ............ SKIPPED [INFO] Quickstart Demo : Security Policy : Basic Authentication (+SSL) SKIPPED [INFO] Quickstart Demo : Security Policy : Certificate Authentication (+SSL) SKIPPED [INFO] Quickstart Demo : Security Policy : SAML Assertion (+SSL) SKIPPED [INFO] Quickstart Demo : Transaction Policy .............. SKIPPED [INFO] SwitchYard : Quickstarts Distribution Assembly .... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 9:01.479s [INFO] Finished at: Mon Jan 14 21:22:35 EST 2013 [INFO] Final Memory: 117M/553M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.6:test (default-test) on project switchyard-quickstart-camel-jpa-binding: There are test failures. [ERROR] [ERROR] Please refer to <http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Quickstart... for the individual test results. [ERROR] -> [Help 1] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException [ERROR] [ERROR] After correcting the problems, you can resume the build with the command [ERROR] mvn <goals> -rf :switchyard-quickstart-camel-jpa-binding Build step 'Invoke top-level Maven targets' marked build as failure