See <https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/260/>
------------------------------------------ [...truncated 2800 lines...] Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.partialbean.impl.PartialBeanBindingExtension activated=true Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.message.MessageBundleExtension activated=true Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.config.ConfigurationExtension activated=true Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.jmx.MBeanExtension activated=true Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.exclude.extension.ExcludeExtension activated=true Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.exclude.CustomProjectStageBeanFilter activated=true Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.exclude.GlobalAlternative activated=true Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.exception.control.extension.ExceptionControlExtension activated=true Oct 16, 2013 12:52:40 AM org.apache.deltaspike.core.util.ProjectStageProducer initProjectStage INFO: Computed the following DeltaSpike ProjectStage: Production Oct 16, 2013 12:52:41 AM org.apache.openejb.cdi.BeansDeployer validateInjectionPoints INFO: All injection points are validated successfully. Oct 16, 2013 12:52:41 AM org.apache.openejb.cdi.OpenEJBLifecycle startApplication INFO: OpenWebBeans Container has started, it took 357 ms. Oct 16, 2013 12:52:41 AM org.apache.openejb.assembler.classic.Assembler createApplication INFO: Deployed Application(path=<https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/8/test)> Running org.apache.deltaspike.data.impl.property.PropertyFromFieldTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 sec 1193 test TRACE [http-bio-8080-exec-31] openjpa.jdbc.SQL - <t 3793959, conn 5067815> executing prepstmnt 21710187 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?] 1193 test TRACE [http-bio-8080-exec-31] openjpa.jdbc.SQL - <t 3793959, conn 5067815> [0 ms] spent 1194 test TRACE [http-bio-8080-exec-31] openjpa.jdbc.SQL - <t 3793959, conn 5067815> executing prepstmnt 18388602 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?] 1194 test TRACE [http-bio-8080-exec-31] openjpa.jdbc.SQL - <t 3793959, conn 5067815> [0 ms] spent 1196 test TRACE [http-bio-8080-exec-31] openjpa.jdbc.SQL - <t 3793959, conn 16484583> executing prepstmnt 7529281 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1196 test TRACE [http-bio-8080-exec-31] openjpa.jdbc.SQL - <t 3793959, conn 16484583> [0 ms] spent 1198 test TRACE [http-bio-8080-exec-31] openjpa.jdbc.SQL - <t 3793959, conn 16484583> executing prepstmnt 27025949 SELECT t0.camelCase, t0.counter, t0.id, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE t0.id = ? [params=?] 1198 test TRACE [http-bio-8080-exec-31] openjpa.jdbc.SQL - <t 3793959, conn 16484583> [0 ms] spent Oct 16, 2013 12:52:41 AM org.apache.openejb.assembler.classic.Assembler destroyApplication INFO: Undeploying app: <https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/8/test> Oct 16, 2013 12:52:43 AM org.apache.openejb.util.JarExtractor extract INFO: Extracting jar: <https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/9/test.war> Oct 16, 2013 12:52:43 AM org.apache.openejb.util.JarExtractor extract INFO: Extracted path: <https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/9/test> Oct 16, 2013 12:52:43 AM org.apache.tomee.catalina.TomcatWebAppBuilder deployWebApps INFO: using default host: localhost Oct 16, 2013 12:52:43 AM org.apache.tomee.catalina.TomcatWebAppBuilder init INFO: ------------------------- localhost -> /test Oct 16, 2013 12:52:43 AM org.apache.openejb.util.OptionsLog info INFO: Using 'openejb.session.manager=org.apache.tomee.catalina.session.QuickSessionManager' Oct 16, 2013 12:52:43 AM org.apache.openejb.config.ConfigurationFactory configureApplication INFO: Configuring enterprise application: <https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/9/test> Oct 16, 2013 12:52:44 AM org.apache.openejb.config.AutoConfig deploy INFO: Configuring PersistenceUnit(name=test, provider=org.apache.openjpa.persistence.PersistenceProviderImpl) Oct 16, 2013 12:52:44 AM org.apache.openejb.config.AutoConfig setNonJtaDataSource INFO: Adjusting PersistenceUnit test <non-jta-data-source> to Resource ID 'testDatabaseNonJta' from 'null' Oct 16, 2013 12:52:44 AM org.apache.openejb.config.AppInfoBuilder build INFO: Enterprise application "<https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/9/test";> loaded. Oct 16, 2013 12:52:44 AM org.apache.openejb.assembler.classic.Assembler createApplication INFO: Assembling app: <https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/9/test> 55 test WARN [http-bio-8080-exec-25] openjpa.Runtime - Found no persistent property in "org.apache.deltaspike.data.test.domain.OneToOne" 114 test WARN [http-bio-8080-exec-25] openjpa.Runtime - Found no persistent property in "org.apache.deltaspike.data.test.domain.OneToMany" 197 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 32727525 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 198 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 216 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 32268077 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 216 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 235 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 26427947 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 235 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 254 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 14803144 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 254 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 272 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 17107141 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 273 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [1 ms] spent 291 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 8536886 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 291 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 310 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 14915048 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 311 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [1 ms] spent 329 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 27537279 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 329 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 348 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 12289417 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 349 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 367 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 17653988 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 367 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 387 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 15322094 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 387 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 406 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 28277168 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 407 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent 427 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> executing prepstmnt 11061848 SELECT SEQUENCE_SCHEMA, SEQUENCE_NAME FROM INFORMATION_SCHEMA.SYSTEM_SEQUENCES 427 test TRACE [http-bio-8080-exec-25] openjpa.jdbc.SQL - <t 3413521, conn 28293511> [0 ms] spent Oct 16, 2013 12:52:44 AM org.apache.openejb.cdi.CdiBuilder initSingleton INFO: Existing thread singleton service in SystemInstance(): org.apache.openejb.cdi.ThreadSingletonServiceImpl@1d4d493 Oct 16, 2013 12:52:44 AM org.apache.openejb.cdi.OpenEJBLifecycle startApplication INFO: OpenWebBeans Container is starting... Oct 16, 2013 12:52:44 AM org.apache.webbeans.plugins.PluginLoader startUp INFO: Adding OpenWebBeansPlugin : [CdiPlugin] Oct 16, 2013 12:52:44 AM org.apache.webbeans.plugins.PluginLoader startUp INFO: Adding OpenWebBeansPlugin : [OpenWebBeansJsfPlugin] Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.partialbean.impl.PartialBeanBindingExtension activated=true Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.exclude.extension.ExcludeExtension activated=true Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.exclude.CustomProjectStageBeanFilter activated=true Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.exclude.GlobalAlternative activated=true Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.message.MessageBundleExtension activated=true Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.exception.control.extension.ExceptionControlExtension activated=true Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.jmx.MBeanExtension activated=true Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ClassDeactivationUtils cacheResult INFO: class: org.apache.deltaspike.core.impl.config.ConfigurationExtension activated=true Oct 16, 2013 12:52:44 AM org.apache.deltaspike.core.util.ProjectStageProducer initProjectStage INFO: Computed the following DeltaSpike ProjectStage: Production Oct 16, 2013 12:52:45 AM org.apache.openejb.cdi.BeansDeployer validateInjectionPoints INFO: All injection points are validated successfully. Oct 16, 2013 12:52:45 AM org.apache.openejb.cdi.OpenEJBLifecycle startApplication INFO: OpenWebBeans Container has started, it took 382 ms. Oct 16, 2013 12:52:45 AM org.apache.openejb.assembler.classic.Assembler createApplication INFO: Deployed Application(path=<https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/9/test)> Running org.apache.deltaspike.data.impl.spi.CdiQuerySpiTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.18 sec 1198 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 5700960> executing prepstmnt 29326640 SELECT SEQUENCE_VALUE FROM OPENJPA_SEQUENCE_TABLE WHERE ID = ? [params=?] 1198 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 5700960> [0 ms] spent 1198 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 5700960> executing prepstmnt 27109758 UPDATE OPENJPA_SEQUENCE_TABLE SET SEQUENCE_VALUE = ? WHERE ID = ? AND SEQUENCE_VALUE = ? [params=?, ?, ?] 1198 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 5700960> [0 ms] spent 1200 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> executing prepstmnt 31749884 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1200 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> [0 ms] spent 1200 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> executing prepstmnt 17870480 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1200 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> [0 ms] spent 1201 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> executing prepstmnt 17957211 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1201 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> [0 ms] spent 1202 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> executing prepstmnt 3555213 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1202 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> [0 ms] spent 1202 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> executing prepstmnt 9978562 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1202 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> [0 ms] spent 1203 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> executing prepstmnt 30276894 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1203 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> [0 ms] spent 1215 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> executing prepstmnt 10131641 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) ORDER BY t0.counter DESC, t0.id ASC [params=?] 1215 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 29348312> [0 ms] spent 1235 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> executing prepstmnt 17130143 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1235 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> [0 ms] spent 1236 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> executing prepstmnt 31254441 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1236 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> [0 ms] spent 1236 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> executing prepstmnt 29980259 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1236 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> [0 ms] spent 1244 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> executing prepstmnt 25926207 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) ORDER BY t0.counter ASC [params=?] 1245 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> [1 ms] spent 1252 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> executing prepstmnt 11837737 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) ORDER BY t0.counter DESC [params=?] 1252 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 30555124> [0 ms] spent 1267 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> executing prepstmnt 7789388 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1267 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> [0 ms] spent 1268 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> executing prepstmnt 32004196 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1268 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> [0 ms] spent 1269 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> executing prepstmnt 7482512 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1269 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> [0 ms] spent 1270 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> executing prepstmnt 2420252 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) ORDER BY t0.counter ASC [params=?] 1270 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> [0 ms] spent 1276 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> executing prepstmnt 21262395 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) [params=?] 1276 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 11101984> [0 ms] spent 1293 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> executing prepstmnt 18046010 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1293 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> [0 ms] spent 1294 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> executing prepstmnt 13600975 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1294 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> [0 ms] spent 1294 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> executing prepstmnt 18353644 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1294 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> [0 ms] spent 1295 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> executing prepstmnt 32572894 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1295 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> [0 ms] spent 1296 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> executing prepstmnt 16565681 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1296 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> [0 ms] spent 1296 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> executing prepstmnt 33371460 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1297 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> [1 ms] spent 1314 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> executing prepstmnt 18922513 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 1314 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 13998054> [0 ms] spent 1334 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 33112159 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1334 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1335 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 8160083 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1335 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1336 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 6576755 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1336 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1336 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 22839480 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1336 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1337 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 18139095 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1337 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1338 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 17010177 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1338 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1338 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 8453659 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1339 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [1 ms] spent 1340 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 22173502 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) [params=?] 1340 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1341 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 11698171 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) [params=?] 1341 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1341 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 23865274 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) [params=?] 1341 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1347 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> executing prepstmnt 22726101 SELECT COUNT(t0.id) FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) [params=?] 1347 test TRACE [http-bio-8080-exec-33] openjpa.jdbc.SQL - <t 24281983, conn 14080342> [0 ms] spent 1368 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> executing prepstmnt 1404191 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1368 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> [0 ms] spent 1368 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> executing prepstmnt 14021702 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1368 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> [0 ms] spent 1369 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> executing prepstmnt 27893826 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1369 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> [0 ms] spent 1370 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> executing prepstmnt 23947007 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1370 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> [0 ms] spent 1387 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> executing prepstmnt 27233132 SELECT t0.id, t0.camelCase, t0.counter, t0.embedd, t0.enabled, t0.name, t0.temporal FROM SIMPLE_TABLE t0 WHERE (t0.name LIKE ? ESCAPE '\') ORDER BY t0.name DESC [params=?] 1387 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 10774631> [0 ms] spent 1408 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 20139391> executing prepstmnt 1125298 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1409 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 20139391> [1 ms] spent 1409 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 20139391> executing prepstmnt 10218629 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1409 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 20139391> [0 ms] spent 1411 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 20139391> executing prepstmnt 18017607 SELECT COUNT(t0.id) FROM SIMPLE_TABLE t0 WHERE (t0.name = ?) [params=?] 1411 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 20139391> [0 ms] spent 1431 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 8094727> executing prepstmnt 5235084 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1431 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 8094727> [0 ms] spent 1433 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 8094727> executing prepstmnt 20761985 INSERT INTO SIMPLE_TABLE (id, camelCase, counter, enabled, name, temporal, embedd) VALUES (?, ?, ?, ?, ?, ?, ?) [params=?, ?, ?, ?, ?, ?, ?] 1433 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 8094727> [0 ms] spent 1441 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 8094727> executing prepstmnt 25484448 SELECT COUNT(t0.id) FROM SIMPLE_TABLE t0 WHERE (t0.name LIKE ? ESCAPE '\') [params=?] 1441 test TRACE [http-bio-8080-exec-34] openjpa.jdbc.SQL - <t 33285880, conn 8094727> [0 ms] spent Oct 16, 2013 12:52:45 AM org.apache.openejb.assembler.classic.Assembler destroyApplication INFO: Undeploying app: <https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/arquillian-test-working-dir/9/test> Running org.apache.deltaspike.data.impl.QueryResultTest Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.407 sec Oct 16, 2013 12:52:46 AM org.apache.catalina.core.StandardServer await INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance. Oct 16, 2013 12:52:46 AM org.apache.coyote.AbstractProtocol pause INFO: Pausing ProtocolHandler ["http-bio-8080"] Oct 16, 2013 12:52:46 AM org.apache.coyote.AbstractProtocol pause INFO: Pausing ProtocolHandler ["ajp-bio-8009"] Oct 16, 2013 12:52:46 AM org.apache.catalina.core.StandardService stopInternal INFO: Stopping service Catalina Oct 16, 2013 12:52:46 AM org.apache.openejb.assembler.classic.Assembler destroyApplication INFO: Undeploying app: <https://builds.apache.org/job/DeltaSpike_TomEE/org.apache.deltaspike.modules$deltaspike-data-module-impl/ws/target/tomee/apache-tomee-webprofile-1.5.2/webapps/tomee> Oct 16, 2013 12:52:46 AM org.apache.coyote.AbstractProtocol stop INFO: Stopping ProtocolHandler ["http-bio-8080"] Oct 16, 2013 12:52:46 AM org.apache.coyote.AbstractProtocol stop INFO: Stopping ProtocolHandler ["ajp-bio-8009"] Oct 16, 2013 12:52:46 AM org.apache.openejb.server.SimpleServiceManager stop INFO: Stopping server services Oct 16, 2013 12:52:46 AM org.apache.openejb.assembler.classic.Assembler destroyApplication INFO: Undeploying app: openejb Oct 16, 2013 12:52:47 AM org.apache.openejb.assembler.classic.Assembler destroyResource INFO: Closing DataSource: testDatabase Oct 16, 2013 12:52:47 AM org.apache.openejb.assembler.classic.Assembler destroyResource INFO: Closing DataSource: testDatabaseNonJta Oct 16, 2013 12:52:47 AM org.apache.coyote.AbstractProtocol destroy INFO: Destroying ProtocolHandler ["http-bio-8080"] Oct 16, 2013 12:52:47 AM org.apache.coyote.AbstractProtocol destroy INFO: Destroying ProtocolHandler ["ajp-bio-8009"] Results : Tests run: 99, Failures: 0, Errors: 0, Skipped: 0
