hi,
below is the trace from right after server restart , 2 updates after each other ...
2016-08-23 21:35:42,498 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) getting persistence provider list (null) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear.veri95web.war:main" from Service Module Loader)
2016-08-23 21:35:42,498 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) saving new persistence provider list ([]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear.veri95web.war:main" from Service Module Loader)
2016-08-23 21:35:42,498 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:42,498 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) getting persistence provider list (null) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear.veri95ejb.jar:main" from Service Module Loader)
2016-08-23 21:35:42,498 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) saving new persistence provider list ([]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear.veri95ejb.jar:main" from Service Module Loader)
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) getting persistence provider list (null) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear:main" from Service Module Loader)
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) saving new persistence provider list ([]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear:main" from Service Module Loader)
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) install persistence unit definitions for ear veri95app.ear
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) install persistence unit definitions for ear veri95jpa.jar
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) adding 'vfs:/C:/wildfly/wildfly-10.0.0.Final/standalone/deployments/veri95app.ear/lib/veri95jpa.jar/' to annotation index map
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) adding 'vfs:/C:/wildfly/wildfly-10.0.0.Final/standalone/deployments/veri95app.ear/veri95ejb.jar/' to annotation index map
2016-08-23 21:35:42,499 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) deployment veri95app.ear is using org.eclipse.persistence.jpa.PersistenceProvider
2016-08-23 21:35:42,499 INFO [org.jboss.weld.deployer] (MSC service thread 1-8) WFLYWELD0006: Starting Services for CDI deployment: veri95app.ear
2016-08-23 21:35:42,526 INFO [org.jboss.weld.Version] (MSC service thread 1-8) WELD-000900: 2.3.2 (Final)
2016-08-23 21:35:42,533 INFO [org.jboss.weld.deployer] (MSC service thread 1-1) WFLYWELD0009: Starting weld service for deployment veri95app.ear
2016-08-23 21:35:43,462 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) getting persistence provider list ([class org.eclipse.persistence.jpa.PersistenceProvider]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear.veri95web.war:main" from Service Module Loader)
2016-08-23 21:35:43,462 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:43,462 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) getting persistence provider list ([class org.eclipse.persistence.jpa.PersistenceProvider]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear.veri95ejb.jar:main" from Service Module Loader)
2016-08-23 21:35:43,463 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:43,463 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) getting persistence provider list ([class org.eclipse.persistence.jpa.PersistenceProvider]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear:main" from Service Module Loader)
2016-08-23 21:35:43,463 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:43,463 TRACE [org.jboss.as.jpa] (MSC service thread 1-8) install persistence unit definitions for war null
2016-08-23 21:35:43,476 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (ServerService Thread Pool -- 58) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
2016-08-23 21:35:43,477 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (ServerService Thread Pool -- 58) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
2016-08-23 21:35:43,477 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (ServerService Thread Pool -- 58) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
2016-08-23 21:35:43,478 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (ServerService Thread Pool -- 58) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
2016-08-23 21:35:44,073 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 58) WFLYCLINF0002: Started client-mappings cache from ejb container
2016-08-23 21:35:44,519 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) getting persistence provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear.veri95web.war:main" from Service Module Loader)
2016-08-23 21:35:44,519 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:44,519 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) getting persistence provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear.veri95ejb.jar:main" from Service Module Loader)
2016-08-23 21:35:44,519 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:44,519 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) getting persistence provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider]) for deployment (ModuleClassLoader for Module "deployment.veri95app.ear:main" from Service Module Loader)
2016-08-23 21:35:44,519 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) added new persistence provider (org.eclipse.persistence.jpa.PersistenceProvider) to provider list ([class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider, class org.eclipse.persistence.jpa.PersistenceProvider])
2016-08-23 21:35:46,094 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery second pass at Tue, 23 Aug 2016 21:35:46
2016-08-23 21:35:46,095 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState()
2016-08-23 21:35:46,095 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction, InputObjectState Uid : 0:0:0:0:0
InputObjectState Type : null
InputObjectState Size : 0
InputObjectState Buffer: , -1)
2016-08-23 21:35:46,095 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState()
2016-08-23 21:35:46,095 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicActionConnectable, InputObjectState Uid : 0:0:0:0:0
InputObjectState Type : null
InputObjectState Size : 40
InputObjectState Buffer: , -1)
2016-08-23 21:35:46,095 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState()
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery)
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule second pass
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery)
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - second pass
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery)
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) XARecoveryModule state change BETWEEN_PASSES->SECOND_PASS
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - second pass
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.transactionInitiatedRecovery completed
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.resourceInitiatedRecovery completed
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) XARecoveryModule state change SECOND_PASS->IDLE
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery)
2016-08-23 21:35:46,096 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== INACTIVE
2016-08-23 21:35:46,097 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread backing off
2016-08-23 21:35:46,538 WARN [org.jboss.resteasy.resteasy_jaxrs.i18n] (ServerService Thread Pool -- 64) RESTEASY002155: Provider class inxites.be.veri95.ejb.service.util.jersey.providers.exception.GenericExceptionMapper is already registered. 2nd registration is being ignored.
2016-08-23 21:35:47,342 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 64) WFLYUT0021: Registered web context: /veri95web
2016-08-23 21:35:47,361 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0010: Deployed "mongo-java-driver-3.0.4.jar" (runtime-name : "mongo-java-driver-3.0.4.jar")
2016-08-23 21:35:47,361 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0010: Deployed "commons-lang3-3.4.jar" (runtime-name : "commons-lang3-3.4.jar")
2016-08-23 21:35:47,361 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0010: Deployed "sqljdbc4.jar" (runtime-name : "sqljdbc4.jar")
2016-08-23 21:35:47,361 INFO [org.jboss.as.server] (ServerService Thread Pool -- 34) WFLYSRV0010: Deployed "veri95app.ear" (runtime-name : "veri95app.ear")
2016-08-23 21:35:47,361 INFO [org.jboss.as.server] (ServerService Thread Pool -- 34) WFLYSRV0010: Deployed "Mongo" (runtime-name : "Mongo")
2016-08-23 21:35:47,472 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management interface listening on http://127.0.0.1:9990/management
2016-08-23 21:35:47,472 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console listening on http://127.0.0.1:9990
2016-08-23 21:35:47,472 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Full 10.0.0.Final (WildFly Core 2.0.10.Final) started in 13218ms - Started 4847 of 5151 services (431 services are lazy, passive or on-demand)
2016-08-23 21:36:33,554 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (default task-2) RESTEASY002225: Deploying javax.ws.rs.core.Application: class inxites.be.veri95.ejb.service.util.jersey.application.V2Application
2016-08-23 21:36:33,555 INFO [org.jboss.resteasy.resteasy_jaxrs.i18n] (default task-2) RESTEASY002215: Adding singleton provider org.jboss.resteasy.plugins.interceptors.CorsFilter from Application class inxites.be.veri95.ejb.service.util.jersey.application.V2Application
2016-08-23 21:36:33,856 INFO [stdout] (default task-2)
2016-08-23 21:36:33,856 INFO [stdout] (default task-2) EVENT
2016-08-23 21:36:33,856 INFO [stdout] (default task-2) SEVERITY: 2
2016-08-23 21:36:33,856 INFO [stdout] (default task-2) MESSAGE: cvc-complex-type.2.1: Element '' must have no character or element information item [children], because the type's content type is empty.
2016-08-23 21:36:33,856 INFO [stdout] (default task-2) LINKED EXCEPTION: null
2016-08-23 21:36:33,856 INFO [stdout] (default task-2) LOCATOR
2016-08-23 21:36:33,856 INFO [stdout] (default task-2) LINE NUMBER: -1
2016-08-23 21:36:33,856 INFO [stdout] (default task-2) COLUMN NUMBER: -1
2016-08-23 21:36:33,857 INFO [stdout] (default task-2) OFFSET: -1
2016-08-23 21:36:33,857 INFO [stdout] (default task-2) OBJECT: null
2016-08-23 21:36:33,857 INFO [stdout] (default task-2) NODE: null
2016-08-23 21:36:33,857 INFO [stdout] (default task-2) URL: null
2016-08-23 21:36:33,902 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:33,903 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:33,903 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:33,903 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:e
2016-08-23 21:36:33,903 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:e
2016-08-23 21:36:33,903 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:33,904 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:e, 1)
2016-08-23 21:36:33,904 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e adding Thread[default task-2,5,main]
2016-08-23 21:36:33,905 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:33,905 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:e status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,905 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:e status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,905 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) ReaperThread.run ()
2016-08-23 21:36:33,906 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check ()
2016-08-23 21:36:33,906 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check - comparing 1471981593905
2016-08-23 21:36:33,906 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,main] sleeping for 599999
2016-08-23 21:36:33,929 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.suspend
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e removing TSThread:1
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e removing TSThread:1 result = true
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:f
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:f
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:f, 1)
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:f adding Thread[default task-2,5,main]
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:f adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:f status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,930 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:f status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,934 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,934 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:33,934 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:33,934 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:f
2016-08-23 21:36:33,934 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:f) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:33,934 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:f removing TSThread:1
2016-08-23 21:36:33,935 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:f removing TSThread:1 result = true
2016-08-23 21:36:33,935 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:f status: ActionStatus.COMMITTED )
2016-08-23 21:36:33,935 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.resume
2016-08-23 21:36:33,935 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e adding Thread[default task-2,5,main]
2016-08-23 21:36:33,935 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:33,942 DEBUG [org.jboss.as.jpa] (default task-2) created new TransactionScopedEntityManager for unit name=veri95app.ear#VERI95
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.suspend
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e removing TSThread:1
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e removing TSThread:1 result = true
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:10
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:10
2016-08-23 21:36:33,942 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:10, 1)
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:10 adding Thread[default task-2,5,main]
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:10 adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:10 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:10 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.suspend
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:10 removing TSThread:1
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:10 removing TSThread:1 result = true
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:11
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:11
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:11, 1)
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:11 adding Thread[default task-2,5,main]
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:11 adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:11 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:11 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,943 DEBUG [org.jboss.as.jpa] (default task-2) created new TransactionScopedEntityManager for unit name=veri95app.ear#VERI95
2016-08-23 21:36:33,943 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:11
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:11) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:11 removing TSThread:1
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:11 removing TSThread:1 result = true
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:11 status: ActionStatus.COMMITTED )
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.resume
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:10 adding Thread[default task-2,5,main]
2016-08-23 21:36:33,944 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:10 adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:33,946 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,946 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,946 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,955 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 420999812 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@1917f284
2016-08-23 21:36:33,957 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: created entity manager session TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:10 status: ActionStatus.RUNNING >
2016-08-23 21:36:33,958 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.registerInterposedSynchronization - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 410119716 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@1871ee24
2016-08-23 21:36:33,958 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,959 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,959 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.equals
2016-08-23 21:36:33,959 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:33,959 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,966 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,966 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'GeneralCustomizing.getCustomizingValueByGeneralCustomizingId' took 22ms
2016-08-23 21:36:33,974 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,974 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1755441964 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@68a1eb2c
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,976 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,977 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:33,977 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,977 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,977 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,977 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,977 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:33,977 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,977 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,978 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,979 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.enlistResource ( LocalXAResourceImpl@578bf676[connectionListener=4ea62691 connectionManager=a1ecf04 warned=false currentXid=null productName=Microsoft SQL Server productVersion=11.00.3513 jndiName=java:/veri95] )
2016-08-23 21:36:33,979 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,979 TRACE [com.arjuna.ats.arjuna] (default task-2) InputObjectState::InputObjectState()
2016-08-23 21:36:33,979 TRACE [com.arjuna.ats.arjuna] (default task-2) FileSystemStore.allObjUids(EISNAME, InputObjectState Uid : 0:0:0:0:0
InputObjectState Type : null
InputObjectState Size : 0
InputObjectState Buffer: , -1)
2016-08-23 21:36:33,979 TRACE [com.arjuna.ats.arjuna] (default task-2) OutputObjectState::OutputObjectState()
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) FileSystemStore.read_committed(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME)
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) ShadowingStore.read_state(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME, StateType.OS_ORIGINAL)
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) ShadowingStore.genPathName(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME, StateType.OS_SHADOW)
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) FileSystemStore.genPathName(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME, 10)
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) ShadowingStore.genPathName(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME, StateType.OS_ORIGINAL)
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) FileSystemStore.genPathName(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME, 11)
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) ShadowingStore.currentState(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME) - returning StateStatus.OS_COMMITTED
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) ShadowingStore.genPathName(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME, StateType.OS_ORIGINAL)
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) FileSystemStore.genPathName(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME, 11)
2016-08-23 21:36:33,980 TRACE [com.arjuna.ats.arjuna] (default task-2) FileSystemStore.openAndLock(C:\wildfly\wildfly-10.0.0.Final\standalone\data\tx-object-store\ShadowNoFileLockStore\defaultStore\EISNAME\0_ffffc0a80165_12b43ed_57965544_c0, FileLock.F_RDLCK, false)
2016-08-23 21:36:33,981 TRACE [com.arjuna.ats.arjuna] (default task-2) InputObjectState::InputObjectState(0:ffffc0a80165:12b43ed:57965544:c0, EISNAME)
2016-08-23 21:36:33,981 TRACE [com.arjuna.ats.arjuna] (default task-2) FileSystemStore.closeAndUnlock(C:\wildfly\wildfly-10.0.0.Final\standalone\data\tx-object-store\ShadowNoFileLockStore\defaultStore\EISNAME\0_ffffc0a80165_12b43ed_57965544_c0, java.io.FileInputStream@2ee2b121, null)
2016-08-23 21:36:33,984 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 0:0:0:0:1 )
2016-08-23 21:36:33,984 TRACE [com.arjuna.ats.arjuna] (default task-2) AbstractRecord::AbstractRecord (0:0:0:0:1)
2016-08-23 21:36:33,984 TRACE [com.arjuna.ats.arjuna] (default task-2) LastResourceRecord()
2016-08-23 21:36:33,985 TRACE [com.arjuna.ats.arjuna] (default task-2) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/LastResourceRecord for 0:0:0:0:1
2016-08-23 21:36:33,986 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,989 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,991 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,991 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:33,991 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:33,991 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 420999812 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@1917f284
2016-08-23 21:36:33,991 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1755441964 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@68a1eb2c
2016-08-23 21:36:33,991 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.beforeCompletion - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 410119716 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@1871ee24
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.delistResource ( LocalXAResourceImpl@578bf676[connectionListener=4ea62691 connectionManager=a1ecf04 warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a80165:3d9ea16:57bca588:10, node_name=1, branch_uid=0:ffffc0a80165:3d9ea16:57bca588:16, subordinatenodename=null, eis_name=java:/veri95 > productName=Microsoft SQL Server productVersion=11.00.3513 jndiName=java:/veri95], 67108864 )
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:10
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::onePhaseCommit() for action-id 0:ffffc0a80165:3d9ea16:57bca588:10
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.arjuna] (default task-2) LastResourceRecord::topLevelPrepare() for 0:0:0:0:1
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.arjuna] (default task-2) LastResourceRecord::topLevelCommit() for 0:0:0:0:1
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:10) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:33,992 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.afterCompletion - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 410119716 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@1871ee24
2016-08-23 21:36:33,992 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: closing entity managersession
2016-08-23 21:36:33,993 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1755441964 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@68a1eb2c
2016-08-23 21:36:33,993 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 420999812 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@1917f284
2016-08-23 21:36:33,993 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:10 removing TSThread:1
2016-08-23 21:36:33,993 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:10 removing TSThread:1 result = true
2016-08-23 21:36:33,993 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:10 status: ActionStatus.COMMITTED )
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:17
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:17
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:17, 1)
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main]
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,995 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 569373618 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@21eff3b2
2016-08-23 21:36:33,996 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: created entity manager session TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.registerInterposedSynchronization - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 995219079 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@3b51d687
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.equals
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:33,996 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateCreate' took 1ms
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1665555961 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@63465df9
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.enlistResource ( LocalXAResourceImpl@578bf676[connectionListener=4ea62691 connectionManager=a1ecf04 warned=false currentXid=null productName=Microsoft SQL Server productVersion=11.00.3513 jndiName=java:/veri95] )
2016-08-23 21:36:33,997 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,998 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 0:0:0:0:1 )
2016-08-23 21:36:33,998 TRACE [com.arjuna.ats.arjuna] (default task-2) AbstractRecord::AbstractRecord (0:0:0:0:1)
2016-08-23 21:36:33,998 TRACE [com.arjuna.ats.arjuna] (default task-2) LastResourceRecord()
2016-08-23 21:36:33,998 TRACE [com.arjuna.ats.arjuna] (default task-2) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/LastResourceRecord for 0:0:0:0:1
2016-08-23 21:36:33,998 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:33,998 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,002 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,002 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,002 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,002 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,003 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,003 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateReplace' took 1ms
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,004 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.suspend
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1 result = true
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1c
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1c
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:34,012 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:1c, 1)
2016-08-23 21:36:34,013 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1c adding Thread[default task-2,5,main]
2016-08-23 21:36:34,013 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1c adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,013 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1c status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:34,013 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1c status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:34,014 DEBUG [org.jboss.as.jpa] (default task-2) created new TransactionScopedEntityManager for unit name=veri95app.ear#VERI95
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1c
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:1c) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1c removing TSThread:1
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1c removing TSThread:1 result = true
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1c status: ActionStatus.COMMITTED )
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.resume
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main]
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.suspend
2016-08-23 21:36:34,014 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1 result = true
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1d
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1d
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:1d, 1)
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1d adding Thread[default task-2,5,main]
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1d adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1d status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1d status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:34,015 DEBUG [org.jboss.as.jpa] (default task-2) created new TransactionScopedEntityManager for unit name=veri95app.ear#VERI95
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1d
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:1d) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1d removing TSThread:1
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1d removing TSThread:1 result = true
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1d status: ActionStatus.COMMITTED )
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.resume
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main]
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,015 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,015 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,016 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,016 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 1ms
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,017 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,019 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,019 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,019 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,019 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,023 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'Person.getPersonByPersonID' took 4ms
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,023 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,027 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,027 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,027 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,027 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,027 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,027 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateCreate' took 0ms
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,028 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,029 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,030 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,030 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateReplace' took 1ms
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,030 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,032 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,038 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,038 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,039 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,039 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateCreate' took 1ms
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,039 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,041 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,041 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateReplace' took 0ms
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,041 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,043 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,043 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,043 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,043 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,043 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,043 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 0ms
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,044 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,046 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,046 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'Person.getPersonByPersonID' took 0ms
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,046 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,047 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,048 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,048 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,048 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,048 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,048 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,048 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 0ms
2016-08-23 21:36:34,048 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,048 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,048 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,048 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,049 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,049 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,049 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,049 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,049 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,049 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,049 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,049 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,049 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,050 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'Person.getPersonByPersonID' took 1ms
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,050 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.suspend
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1 result = true
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1e
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1e
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:1e, 1)
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1e adding Thread[default task-2,5,main]
2016-08-23 21:36:34,051 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1e adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,052 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1e status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:34,052 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1e status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:34,052 DEBUG [org.jboss.as.jpa] (default task-2) created new TransactionScopedEntityManager for unit name=veri95app.ear#VERI95
2016-08-23 21:36:34,052 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1e
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:1e) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1e removing TSThread:1
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1e removing TSThread:1 result = true
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1e status: ActionStatus.COMMITTED )
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.resume
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main]
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,053 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,053 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,053 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'HierarchyScope.getHierarchyScopeByPath' took 0ms
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,054 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,057 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,057 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,057 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,057 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,057 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,060 TRACE [org.jboss.as.jpa] (default task-2) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.Person' took 3ms
2016-08-23 21:36:34,060 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,060 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,060 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,060 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,061 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,061 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'GeneralCustomizing.getGeneralCustomizingByGeneralCustomizingIdByCustomizingValue' took 1ms
2016-08-23 21:36:34,062 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,063 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,064 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,064 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:34,064 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,064 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,064 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,064 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,064 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,064 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,069 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,069 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,070 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,070 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,072 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,072 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.suspend
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1 result = true
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.begin
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::BasicAction()
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1f
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1f
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:1f, 1)
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1f adding Thread[default task-2,5,main]
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1f adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1f status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:34,075 TRACE [com.arjuna.ats.arjuna] (default task-2) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1f status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:34,075 DEBUG [org.jboss.as.jpa] (default task-2) created new TransactionScopedEntityManager for unit name=veri95app.ear#VERI95
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:1f
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:1f) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1f removing TSThread:1
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:1f removing TSThread:1 result = true
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:1f status: ActionStatus.COMMITTED )
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.resume
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main]
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,076 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,076 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,076 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 0ms
2016-08-23 21:36:34,077 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,077 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,077 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,077 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,077 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,077 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,079 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,079 TRACE [org.jboss.as.jpa] (default task-2) createNamedQuery name 'PersonnelClass.getPersonnelClassByPersonnelClassID' took 2ms
2016-08-23 21:36:34,079 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,079 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,080 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,080 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,080 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,080 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,080 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,081 TRACE [org.jboss.as.jpa] (default task-2) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.Person' took 1ms
2016-08-23 21:36:34,082 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,082 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,082 TRACE [com.arjuna.ats.jta] (default task-2) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:34,082 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,082 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.RUNNING >
2016-08-23 21:36:34,082 TRACE [org.jboss.as.jpa] (default task-2) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.PersonProperty' took 0ms
2016-08-23 21:36:34,083 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,083 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,084 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,084 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,085 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:34,085 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:34,085 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 569373618 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@21eff3b2
2016-08-23 21:36:34,085 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1665555961 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@63465df9
2016-08-23 21:36:34,085 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,086 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,086 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,086 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,086 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,088 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.beforeCompletion - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 995219079 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@3b51d687
2016-08-23 21:36:34,088 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,088 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,088 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.delistResource ( LocalXAResourceImpl@578bf676[connectionListener=4ea62691 connectionManager=a1ecf04 warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a80165:3d9ea16:57bca588:17, node_name=1, branch_uid=0:ffffc0a80165:3d9ea16:57bca588:1b, subordinatenodename=null, eis_name=java:/veri95 > productName=Microsoft SQL Server productVersion=11.00.3513 jndiName=java:/veri95], 67108864 )
2016-08-23 21:36:34,088 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,088 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:17
2016-08-23 21:36:34,088 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::onePhaseCommit() for action-id 0:ffffc0a80165:3d9ea16:57bca588:17
2016-08-23 21:36:34,088 TRACE [com.arjuna.ats.arjuna] (default task-2) LastResourceRecord::topLevelPrepare() for 0:0:0:0:1
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.arjuna] (default task-2) LastResourceRecord::topLevelCommit() for 0:0:0:0:1
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:17) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.afterCompletion - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 995219079 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@3b51d687
2016-08-23 21:36:34,089 DEBUG [org.jboss.as.jpa] (default task-2) default task-2:transaction scoped EntityManager [veri95app.ear#VERI95]: closing entity managersession
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1665555961 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@63465df9
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.jta] (default task-2) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 569373618 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@21eff3b2
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:17 removing TSThread:1 result = true
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:17 status: ActionStatus.COMMITTED )
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.suspend
2016-08-23 21:36:34,089 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImpleManager.resume
2016-08-23 21:36:34,090 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e adding Thread[default task-2,5,main]
2016-08-23 21:36:34,090 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e adding Thread[default task-2,5,main] result = true
2016-08-23 21:36:34,093 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:34,093 TRACE [com.arjuna.ats.jta] (default task-2) BaseTransaction.commit
2016-08-23 21:36:34,093 TRACE [com.arjuna.ats.jta] (default task-2) TransactionImple.commitAndDisassociate
2016-08-23 21:36:34,093 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:e
2016-08-23 21:36:34,093 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:e) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:34,093 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e removing TSThread:1
2016-08-23 21:36:34,093 TRACE [com.arjuna.ats.arjuna] (default task-2) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:e removing TSThread:1 result = true
2016-08-23 21:36:34,093 TRACE [com.arjuna.ats.arjuna] (default task-2) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:e status: ActionStatus.COMMITTED )
2016-08-23 21:36:39,845 INFO [stdout] (default task-3)
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) EVENT
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) SEVERITY: 2
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) MESSAGE: cvc-complex-type.2.1: Element '' must have no character or element information item [children], because the type's content type is empty.
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) LINKED EXCEPTION: null
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) LOCATOR
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) LINE NUMBER: -1
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) COLUMN NUMBER: -1
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) OFFSET: -1
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) OBJECT: null
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) NODE: null
2016-08-23 21:36:39,846 INFO [stdout] (default task-3) URL: null
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.jta] (default task-3) BaseTransaction.begin
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::BasicAction()
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:20
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:20
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:20, 1)
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:20 adding Thread[default task-3,5,main]
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:20 adding Thread[default task-3,5,main] result = true
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:20 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:20 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImpleManager.suspend
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:20 removing TSThread:2
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:20 removing TSThread:2 result = true
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.jta] (default task-3) BaseTransaction.begin
2016-08-23 21:36:39,847 TRACE [com.arjuna.ats.arjuna] (default task-3) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::BasicAction()
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:21
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:21
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:21, 1)
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:21 adding Thread[default task-3,5,main]
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:21 adding Thread[default task-3,5,main] result = true
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:21 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.arjuna] (default task-3) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:21 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 915093247 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@368b36ff
2016-08-23 21:36:39,848 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: created entity manager session TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:21 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.registerInterposedSynchronization - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 944561758 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@384cde5e
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.equals
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,848 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'GeneralCustomizing.getCustomizingValueByGeneralCustomizingId' took 0ms
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,848 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1014193551 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@3c735d8f
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.enlistResource ( LocalXAResourceImpl@578bf676[connectionListener=4ea62691 connectionManager=a1ecf04 warned=false currentXid=null productName=Microsoft SQL Server productVersion=11.00.3513 jndiName=java:/veri95] )
2016-08-23 21:36:39,849 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,850 TRACE [com.arjuna.ats.arjuna] (default task-3) StateManager::StateManager( 0:0:0:0:1 )
2016-08-23 21:36:39,850 TRACE [com.arjuna.ats.arjuna] (default task-3) AbstractRecord::AbstractRecord (0:0:0:0:1)
2016-08-23 21:36:39,850 TRACE [com.arjuna.ats.arjuna] (default task-3) LastResourceRecord()
2016-08-23 21:36:39,850 TRACE [com.arjuna.ats.arjuna] (default task-3) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/LastResourceRecord for 0:0:0:0:1
2016-08-23 21:36:39,850 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,850 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) BaseTransaction.commit
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.commitAndDisassociate
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 915093247 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@368b36ff
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1014193551 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@3c735d8f
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.beforeCompletion - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 944561758 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@384cde5e
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.delistResource ( LocalXAResourceImpl@578bf676[connectionListener=4ea62691 connectionManager=a1ecf04 warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a80165:3d9ea16:57bca588:21, node_name=1, branch_uid=0:ffffc0a80165:3d9ea16:57bca588:25, subordinatenodename=null, eis_name=java:/veri95 > productName=Microsoft SQL Server productVersion=11.00.3513 jndiName=java:/veri95], 67108864 )
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:21
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::onePhaseCommit() for action-id 0:ffffc0a80165:3d9ea16:57bca588:21
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) LastResourceRecord::topLevelPrepare() for 0:0:0:0:1
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) LastResourceRecord::topLevelCommit() for 0:0:0:0:1
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:21) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.afterCompletion - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 944561758 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@384cde5e
2016-08-23 21:36:39,851 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: closing entity managersession
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1014193551 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@3c735d8f
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 915093247 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@368b36ff
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:21 removing TSThread:2
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:21 removing TSThread:2 result = true
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:21 status: ActionStatus.COMMITTED )
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.jta] (default task-3) BaseTransaction.begin
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) StateManager::StateManager( 2, 0 )
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::BasicAction()
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::Begin() for action-id 0:ffffc0a80165:3d9ea16:57bca588:26
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::actionInitialise() for action-id 0:ffffc0a80165:3d9ea16:57bca588:26
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) ActionHierarchy::ActionHierarchy(1)
2016-08-23 21:36:39,851 TRACE [com.arjuna.ats.arjuna] (default task-3) ActionHierarchy::add(0:ffffc0a80165:3d9ea16:57bca588:26, 1)
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:26 adding Thread[default task-3,5,main]
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:26 adding Thread[default task-3,5,main] result = true
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.arjuna] (default task-3) TransactionReaper::insert ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.arjuna] (default task-3) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING, 600 )
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 506472578 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@1e302882
2016-08-23 21:36:39,852 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: created entity manager session TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.registerInterposedSynchronization - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 1139895489 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@43f16cc1
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.equals
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateCreate' took 0ms
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1971299371 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@757fa42b
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.putResource
2016-08-23 21:36:39,852 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.enlistResource ( LocalXAResourceImpl@578bf676[connectionListener=4ea62691 connectionManager=a1ecf04 warned=false currentXid=null productName=Microsoft SQL Server productVersion=11.00.3513 jndiName=java:/veri95] )
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.arjuna] (default task-3) StateManager::StateManager( 0:0:0:0:1 )
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.arjuna] (default task-3) AbstractRecord::AbstractRecord (0:0:0:0:1)
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.arjuna] (default task-3) LastResourceRecord()
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.arjuna] (default task-3) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/LastResourceRecord for 0:0:0:0:1
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,853 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,854 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,854 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,854 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,854 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,854 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,854 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateReplace' took 0ms
2016-08-23 21:36:39,854 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,854 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,855 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,855 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,855 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,855 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,855 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,855 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,855 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,857 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,857 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 0ms
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,857 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,858 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,858 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,858 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,858 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,858 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,858 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,858 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'Person.getPersonByPersonID' took 0ms
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,859 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,861 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,861 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateCreate' took 0ms
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,861 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,863 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,863 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateReplace' took 0ms
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,863 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,867 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,867 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateCreate' took 0ms
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,867 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,868 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,869 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,869 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'ObjectBehaviorOnUpdate.getGlobalBehaviorOnUpdateReplace' took 0ms
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,869 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,870 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,870 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 0ms
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,870 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'Person.getPersonByPersonID' took 0ms
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,871 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,872 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,872 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 0ms
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,872 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'Person.getPersonByPersonID' took 0ms
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,873 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,874 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,874 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'HierarchyScope.getHierarchyScopeByPath' took 0ms
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,874 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,875 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,875 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,875 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,875 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,875 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,876 TRACE [org.jboss.as.jpa] (default task-3) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.Person' took 1ms
2016-08-23 21:36:39,876 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,876 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,876 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,876 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,876 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,876 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'GeneralCustomizing.getGeneralCustomizingByGeneralCustomizingIdByCustomizingValue' took 0ms
2016-08-23 21:36:39,876 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getTransactionKey
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,877 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,878 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,879 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,879 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,879 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,879 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,879 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 1ms
2016-08-23 21:36:39,879 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,879 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,880 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,880 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,880 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,880 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,880 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,880 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'PersonnelClass.getPersonnelClassByPersonnelClassID' took 0ms
2016-08-23 21:36:39,880 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,880 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,881 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,881 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,881 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,881 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,881 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,882 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.registerSynchronization - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 2088048218 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@7c75165a
2016-08-23 21:36:39,882 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,883 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,884 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,884 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,886 TRACE [org.jboss.as.jpa] (default task-3) persist entityClass 'inxites.be.veri95.ejb.entity.personnel.common.LinkPersonnelClassPersonDefinedBy' took 5ms
2016-08-23 21:36:39,887 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,887 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,887 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,887 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,887 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,887 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'DatabaseQueue.countByObjectNameByObjectID' took 0ms
2016-08-23 21:36:39,889 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,889 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,890 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,890 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,891 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,891 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,891 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,891 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,891 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,891 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'PersonnelClass.getPersonnelClassByPersonnelClassID' took 0ms
2016-08-23 21:36:39,891 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,891 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,892 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,892 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,892 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,892 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,892 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,892 TRACE [org.jboss.as.jpa] (default task-3) createNamedQuery name 'HierarchyScope.getHierarchyScopeByPath' took 0ms
2016-08-23 21:36:39,892 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,892 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,893 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,893 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,893 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,893 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,893 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,893 TRACE [org.jboss.as.jpa] (default task-3) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.personnelClass.PersonnelClass' took 0ms
2016-08-23 21:36:39,893 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,893 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,894 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,894 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,894 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,894 TRACE [org.jboss.as.jpa] (default task-3) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.Person' took 1ms
2016-08-23 21:36:39,895 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,895 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,897 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,897 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,897 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,897 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,897 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,897 TRACE [org.jboss.as.jpa] (default task-3) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.PersonProperty' took 0ms
2016-08-23 21:36:39,897 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,897 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,897 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,897 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,897 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,898 TRACE [org.jboss.as.jpa] (default task-3) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.PersonProperty' took 1ms
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,898 TRACE [org.jboss.as.jpa] (default task-3) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.PersonProperty' took 0ms
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,898 TRACE [org.jboss.as.jpa] (default task-3) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.PersonProperty' took 0ms
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionSynchronizationRegistryImple.getResource
2016-08-23 21:36:39,898 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,898 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.RUNNING >
2016-08-23 21:36:39,898 TRACE [org.jboss.as.jpa] (default task-3) merge entityClass 'inxites.be.veri95.ejb.entity.personnel.person.PersonProperty' took 0ms
2016-08-23 21:36:39,899 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,899 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,900 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,900 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,900 TRACE [com.arjuna.ats.jta] (default task-3) BaseTransaction.commit
2016-08-23 21:36:39,900 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.commitAndDisassociate
2016-08-23 21:36:39,900 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 506472578 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@1e302882
2016-08-23 21:36:39,900 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1971299371 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@757fa42b
2016-08-23 21:36:39,900 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,901 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,901 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,901 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,901 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,902 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,902 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,902 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,902 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,903 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,903 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,903 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,903 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,904 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,904 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,904 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.beforeCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 2088048218 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@7c75165a
2016-08-23 21:36:39,904 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.beforeCompletion - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 1139895489 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@43f16cc1
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.delistResource ( LocalXAResourceImpl@578bf676[connectionListener=4ea62691 connectionManager=a1ecf04 warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a80165:3d9ea16:57bca588:26, node_name=1, branch_uid=0:ffffc0a80165:3d9ea16:57bca588:2a, subordinatenodename=null, eis_name=java:/veri95 > productName=Microsoft SQL Server productVersion=11.00.3513 jndiName=java:/veri95], 67108864 )
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:26
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::onePhaseCommit() for action-id 0:ffffc0a80165:3d9ea16:57bca588:26
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.arjuna] (default task-3) LastResourceRecord::topLevelPrepare() for 0:0:0:0:1
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.arjuna] (default task-3) LastResourceRecord::topLevelCommit() for 0:0:0:0:1
2016-08-23 21:36:39,905 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:26) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:39,906 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.afterCompletion - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 1139895489 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@43f16cc1
2016-08-23 21:36:39,906 DEBUG [org.jboss.as.jpa] (default task-3) default task-3:transaction scoped EntityManager [veri95app.ear#VERI95]: closing entity managersession
2016-08-23 21:36:39,906 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 2088048218 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@7c75165a
2016-08-23 21:36:39,906 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.transaction.JTASynchronizationListener HashCode: 1971299371 toString: org.eclipse.persistence.transaction.JTASynchronizationListener@757fa42b
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.jta] (default task-3) SynchronizationImple.afterCompletion - Class: class org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1 HashCode: 506472578 toString: org.eclipse.persistence.internal.jpa.transaction.JTATransactionWrapper$1@1e302882
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:26 removing TSThread:2
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:26 removing TSThread:2 result = true
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:26 status: ActionStatus.COMMITTED )
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImpleManager.suspend
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImpleManager.resume
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:20 adding Thread[default task-3,5,main]
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::addChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:20 adding Thread[default task-3,5,main] result = true
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.jta] (default task-3) BaseTransaction.commit
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.jta] (default task-3) TransactionImple.commitAndDisassociate
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::End() for action-id 0:ffffc0a80165:3d9ea16:57bca588:20
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::End() result for action-id (0:ffffc0a80165:3d9ea16:57bca588:20) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:20 removing TSThread:2
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) BasicAction::removeChildThread () action 0:ffffc0a80165:3d9ea16:57bca588:20 removing TSThread:2 result = true
2016-08-23 21:36:39,908 TRACE [com.arjuna.ats.arjuna] (default task-3) TransactionReaper::remove ( BasicAction: 0:ffffc0a80165:3d9ea16:57bca588:20 status: ActionStatus.COMMITTED )