Attachment 631397 Details for Bug 868873 – server log

server log

server.log (text/x-log), 50.03 KB, created by Radovan Synek on 2012-10-22 10:45:09 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Radovan Synek

Created: 2012-10-22 10:45:09 UTC

Size: 50.03 KB

patch

obsolete

>2012-10-22 11:11:05,949 INFO  [org.jboss.web.WebService] (main) Using RMI server codebase: http://127.0.0.1:8083/
>2012-10-22 11:11:08,014 INFO  [org.jboss.wsf.stack.jbws.NativeServerConfig] (main) JBoss Web Services - Stack Native Core
>2012-10-22 11:11:08,014 INFO  [org.jboss.wsf.stack.jbws.NativeServerConfig] (main) 3.1.2.SP11
>2012-10-22 11:11:08,493 INFO  [org.jboss.logbridge.LogNotificationListener] (main) Adding notification listener for logging mbean "jboss.system:service=Logging,type=Log4jService" to server org.jboss.mx.server.MBeanServerImpl@283aa791[ defaultDomain='jboss' ]
>2012-10-22 11:11:13,138 INFO  [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] (main) Encountered deployment AbstractVFSDeploymentContext@1622906073{vfszip:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar/}
>2012-10-22 11:11:13,138 INFO  [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] (main) Encountered deployment AbstractVFSDeploymentContext@1622906073{vfszip:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar/}
>2012-10-22 11:11:13,138 INFO  [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] (main) Encountered deployment AbstractVFSDeploymentContext@1622906073{vfszip:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar/}
>2012-10-22 11:11:13,138 INFO  [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] (main) Encountered deployment AbstractVFSDeploymentContext@1622906073{vfszip:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar/}
>2012-10-22 11:11:13,601 ERROR [STDERR] (main) SLF4J: Class path contains multiple SLF4J bindings.
>2012-10-22 11:11:13,601 ERROR [STDERR] (main) SLF4J: Found binding in [vfszip:/home/rsynek/programy/jboss-eap-5.1/jboss-as/common/lib/slf4j-jboss-logging.jar/org/slf4j/impl/StaticLoggerBinder.class]
>2012-10-22 11:11:13,601 ERROR [STDERR] (main) SLF4J: Found binding in [vfszip:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/bz833609-ear-1.0.ear/lib/slf4j-log4j12-1.6.0.jar/org/slf4j/impl/StaticLoggerBinder.class]
>2012-10-22 11:11:13,601 ERROR [STDERR] (main) SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
>2012-10-22 11:11:13,606 WARN  [jacorb.config] (main) jacorb.home unset! Will use '.'
>2012-10-22 11:11:13,607 WARN  [jacorb.config] (main) File ./jacorb.properties for configuration jacorb not found
>2012-10-22 11:11:13,666 WARN  [jacorb.config] (main) jacorb.home unset! Will use '.'
>2012-10-22 11:11:13,666 WARN  [jacorb.config] (main) File ./jacorb.properties for configuration jacorb not found
>2012-10-22 11:11:13,856 INFO  [org.jboss.iiop.CorbaNamingService] (main) CORBA Naming Started
>2012-10-22 11:11:14,646 INFO  [org.jboss.mail.MailService] (main) Mail Service bound to java:/Mail
>2012-10-22 11:11:14,967 INFO  [org.jboss.jmx.adaptor.snmp.agent.SnmpAgentService] (main) SNMP agent going active
>2012-10-22 11:11:16,124 INFO  [org.jboss.ha.framework.interfaces.HAPartition.DefaultPartition] (main) Initializing partition DefaultPartition
>2012-10-22 11:11:16,165 INFO  [STDOUT] (JBoss System Threads(1)-3) 
>---------------------------------------------------------
>GMS: address is 127.0.0.1:55200 (cluster=DefaultPartition)
>---------------------------------------------------------
>2012-10-22 11:11:16,312 INFO  [org.jboss.cache.jmx.PlatformMBeanServerRegistration] (main) JBossCache MBeans were successfully registered to the platform mbean server.
>2012-10-22 11:11:16,358 INFO  [STDOUT] (main) 
>---------------------------------------------------------
>GMS: address is 127.0.0.1:55200 (cluster=DefaultPartition-HAPartitionCache)
>---------------------------------------------------------
>2012-10-22 11:11:18,245 INFO  [org.jboss.ha.framework.interfaces.HAPartition.DefaultPartition] (JBoss System Threads(1)-3) Number of cluster members: 1
>2012-10-22 11:11:18,245 INFO  [org.jboss.ha.framework.interfaces.HAPartition.DefaultPartition] (JBoss System Threads(1)-3) Other members: 0
>2012-10-22 11:11:18,360 INFO  [org.jboss.cache.RPCManagerImpl] (main) Received new cluster view: [127.0.0.1:55200|0] [127.0.0.1:55200]
>2012-10-22 11:11:18,361 INFO  [org.jboss.cache.RPCManagerImpl] (main) Cache local address is 127.0.0.1:55200
>2012-10-22 11:11:18,362 INFO  [org.jboss.cache.RPCManagerImpl] (main) state was retrieved successfully (in 2 seconds)
>2012-10-22 11:11:18,373 INFO  [org.jboss.cache.factories.ComponentRegistry] (main) JBoss Cache version: JBossCache 'Malagueta' 3.2.8.GA
>2012-10-22 11:11:18,373 INFO  [org.jboss.ha.framework.interfaces.HAPartition.DefaultPartition] (main) Fetching serviceState (will wait for 30000 milliseconds):
>2012-10-22 11:11:18,374 INFO  [org.jboss.ha.framework.interfaces.HAPartition.DefaultPartition] (main) State could not be retrieved (we are the first member in group)
>2012-10-22 11:11:18,433 INFO  [org.jboss.ha.jndi.HANamingService] (main) Started HAJNDI bootstrap; jnpPort=1100, backlog=50, bindAddress=/127.0.0.1
>2012-10-22 11:11:18,435 INFO  [org.jboss.ha.jndi.DetachedHANamingService$AutomaticDiscovery] (main) Listening on /127.0.0.1:1102, group=230.0.0.4, HA-JNDI address=127.0.0.1:1100
>2012-10-22 11:11:18,620 WARN  [org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore] (main) WARNING! POTENTIAL SECURITY RISK. It has been detected that the MessageSucker component which sucks messages from one node to another has not had its password changed from the installation default. Please see the JBoss Messaging user guide for instructions on how to do this.
>2012-10-22 11:11:18,970 INFO  [org.jboss.invocation.unified.server.UnifiedInvokerHA] (main) Service name is jboss:service=invoker,type=unifiedha
>2012-10-22 11:11:19,343 INFO  [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) JBossTS Transaction Service (JTA version - tag:JBOSSTS_4_6_1_GA_CP12) - JBoss Inc.
>2012-10-22 11:11:19,343 INFO  [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Setting up property manager MBean and JMX layer
>2012-10-22 11:11:19,542 INFO  [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Initializing recovery manager
>2012-10-22 11:11:19,601 INFO  [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Recovery manager configured
>2012-10-22 11:11:19,601 INFO  [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Binding TransactionManager JNDI Reference
>2012-10-22 11:11:19,620 INFO  [com.arjuna.ats.jbossatx.jta.TransactionManagerService] (main) Starting transaction recovery manager
>2012-10-22 11:11:19,837 INFO  [org.apache.catalina.core.AprLifecycleListener] (main) The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/lib/amd64/server:/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/lib/amd64:/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
>2012-10-22 11:11:19,865 INFO  [org.apache.coyote.http11.Http11Protocol] (main) Initializing Coyote HTTP/1.1 on http-127.0.0.1-8080
>2012-10-22 11:11:19,866 INFO  [org.apache.coyote.ajp.AjpProtocol] (main) Initializing Coyote AJP/1.3 on ajp-127.0.0.1-8009
>2012-10-22 11:11:19,875 INFO  [org.apache.catalina.core.StandardService] (main) Starting service jboss.web
>2012-10-22 11:11:19,876 INFO  [org.apache.catalina.core.StandardEngine] (main) Starting Servlet Engine: JBoss Web/2.1.12.GA-patch-01
>2012-10-22 11:11:19,902 INFO  [org.apache.catalina.startup.Catalina] (main) Server startup in 36 ms
>2012-10-22 11:11:19,912 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/invoker
>2012-10-22 11:11:20,232 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/jbossws
>2012-10-22 11:11:20,248 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/juddi
>2012-10-22 11:11:20,267 INFO  [org.apache.juddi.registry.RegistryServlet] (main) Loading jUDDI configuration.
>2012-10-22 11:11:20,267 INFO  [org.apache.juddi.registry.RegistryServlet] (main) Resources loaded from: /WEB-INF/juddi.properties
>2012-10-22 11:11:20,267 INFO  [org.apache.juddi.registry.RegistryServlet] (main) Initializing jUDDI components.
>2012-10-22 11:11:20,340 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/web-console
>2012-10-22 11:11:20,491 INFO  [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfsfile:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/jboss-local-jdbc.rar/META-INF/ra.xml
>2012-10-22 11:11:20,498 INFO  [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfsfile:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml
>2012-10-22 11:11:20,501 INFO  [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfsfile:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/jms-ra.rar/META-INF/ra.xml
>2012-10-22 11:11:20,507 INFO  [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfsfile:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/mail-ra.rar/META-INF/ra.xml
>2012-10-22 11:11:20,516 INFO  [org.jboss.resource.deployers.RARDeployment] (main) Required license terms exist, view vfsfile:/home/rsynek/programy/jboss-eap-5.1/jboss-as/server/production/deploy/quartz-ra.rar/META-INF/ra.xml
>2012-10-22 11:11:20,549 INFO  [org.quartz.simpl.SimpleThreadPool] (main) Job execution threads will use class loader of thread: main
>2012-10-22 11:11:20,561 INFO  [org.quartz.core.QuartzScheduler] (main) Quartz Scheduler v.1.5.2 created.
>2012-10-22 11:11:20,562 INFO  [org.quartz.simpl.RAMJobStore] (main) RAMJobStore initialized.
>2012-10-22 11:11:20,562 INFO  [org.quartz.impl.StdSchedulerFactory] (main) Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
>2012-10-22 11:11:20,562 INFO  [org.quartz.impl.StdSchedulerFactory] (main) Quartz scheduler version: 1.5.2
>2012-10-22 11:11:20,563 INFO  [org.quartz.core.QuartzScheduler] (main) Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
>2012-10-22 11:11:21,016 INFO  [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
>2012-10-22 11:11:21,271 INFO  [org.jboss.jms.server.ServerPeer] (main) JBoss Messaging 1.4.8.SP5 server [0] started
>2012-10-22 11:11:21,328 INFO  [STDOUT] (main) 
>---------------------------------------------------------
>GMS: address is 127.0.0.1:55200 (cluster=DefaultPartition-JMS-CTRL)
>---------------------------------------------------------
>2012-10-22 11:11:21,358 INFO  [STDOUT] (pool-12-thread-1) 
>---------------------------------------------------------
>GMS: address is 127.0.0.1:7900 (cluster=DefaultPartition-JMS-DATA)
>---------------------------------------------------------
>2012-10-22 11:11:23,333 INFO  [org.jboss.messaging.core.impl.postoffice.GroupMember] (main) org.jboss.messaging.core.impl.postoffice.GroupMember$ControlMembershipListener@2b090350 got new view [127.0.0.1:55200|0] [127.0.0.1:55200], old view is null
>2012-10-22 11:11:23,333 INFO  [org.jboss.messaging.core.impl.postoffice.GroupMember] (main) I am (127.0.0.1:55200)
>2012-10-22 11:11:23,334 INFO  [org.jboss.messaging.core.impl.postoffice.GroupMember] (main) New Members : 1 ([127.0.0.1:55200])
>2012-10-22 11:11:23,334 INFO  [org.jboss.messaging.core.impl.postoffice.GroupMember] (main) All Members : 1 ([127.0.0.1:55200])
>2012-10-22 11:11:26,418 INFO  [org.jboss.jms.server.destination.QueueService] (main) Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
>2012-10-22 11:11:26,465 INFO  [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
>2012-10-22 11:11:26,465 INFO  [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) org.jboss.jms.server.connectionfactory.ConnectionFactory@68f7b23c started
>2012-10-22 11:11:26,466 INFO  [org.jboss.jms.server.destination.QueueService] (main) Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
>2012-10-22 11:11:26,486 INFO  [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
>2012-10-22 11:11:26,486 INFO  [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) org.jboss.jms.server.connectionfactory.ConnectionFactory@25eef81 started
>2012-10-22 11:11:26,699 WARN  [org.jboss.jms.server.jbosssx.JBossASSecurityMetadataStore] (main) WARNING! POTENTIAL SECURITY RISK. It has been detected that the MessageSucker component which sucks messages from one node to another has not had its password changed from the installation default. Please see the JBoss Messaging user guide for instructions on how to do this.
>2012-10-22 11:11:26,903 INFO  [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
>2012-10-22 11:11:26,903 INFO  [org.jboss.jms.server.connectionfactory.ConnectionFactory] (main) org.jboss.jms.server.connectionfactory.ConnectionFactory@3d2b9b3 started
>2012-10-22 11:11:26,956 INFO  [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
>2012-10-22 11:11:26,983 INFO  [org.jboss.resource.adapter.jdbc.remote.WrapperDataSourceService] (main) Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=local-oracle' to JNDI name 'local-oracle'
>2012-10-22 11:11:27,000 INFO  [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] (main) Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=mysql-local-ds' to JNDI name 'java:mysql-local-ds'
>2012-10-22 11:11:27,108 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/admin-console
>2012-10-22 11:11:27,252 INFO  [javax.enterprise.resource.webcontainer.jsf.config] (main) Initializing Mojarra (1.2_13-b01-FCS) for context '/admin-console'
>2012-10-22 11:11:28,279 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/
>2012-10-22 11:11:28,305 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/jmx-console
>2012-10-22 11:11:28,597 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) Created KernelDeployment for: bz833609-ejb-1.0.jar
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) installing bean: jboss.j2ee:ear=bz833609-ear-1.0.ear,jar=bz833609-ejb-1.0.jar,name=launcher,service=EJB3
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main)   with dependencies:
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main)   and demands:
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) 	jboss.ejb:service=EJBTimerService
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) 	persistence.unit:unitName=bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar#jboss.jpa
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main)   and supplies:
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) 	jndi:bz833609-ear-1.0/launcher/local
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) 	jndi:bz833609-ear-1.0/launcher/local-org.jboss.qa.brms.ProcessLauncherLocal
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) 	jndi:bz833609-ear-1.0/launcher/remote
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) 	Class:org.jboss.qa.brms.ProcessLauncherLocal
>2012-10-22 11:11:28,599 INFO  [org.jboss.ejb3.deployers.JBossASKernel] (main) Added bean(jboss.j2ee:ear=bz833609-ear-1.0.ear,jar=bz833609-ejb-1.0.jar,name=launcher,service=EJB3) to KernelDeployment of: bz833609-ejb-1.0.jar
>2012-10-22 11:11:28,651 INFO  [org.jboss.jpa.deployment.PersistenceUnitDeployment] (main) Starting persistence unit persistence.unit:unitName=bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar#jboss.jpa
>2012-10-22 11:11:28,695 INFO  [org.hibernate.cfg.annotations.Version] (main) Hibernate Annotations 3.4.0.GA_CP01
>2012-10-22 11:11:28,706 INFO  [org.hibernate.cfg.Environment] (main) Hibernate 3.3.2.GA_CP04
>2012-10-22 11:11:28,708 INFO  [org.hibernate.cfg.Environment] (main) hibernate.properties not found
>2012-10-22 11:11:28,712 INFO  [org.hibernate.cfg.Environment] (main) Bytecode provider name : javassist
>2012-10-22 11:11:28,716 INFO  [org.hibernate.cfg.Environment] (main) using JDK 1.4 java.sql.Timestamp handling
>2012-10-22 11:11:28,794 INFO  [org.hibernate.annotations.common.Version] (main) Hibernate Commons Annotations 3.1.0.GA
>2012-10-22 11:11:28,798 INFO  [org.hibernate.ejb.Version] (main) Hibernate EntityManager [WORKING]
>2012-10-22 11:11:28,817 INFO  [org.hibernate.ejb.Ejb3Configuration] (main) Processing PersistenceUnitInfo [
>	name: jboss.jpa
>	...]
>2012-10-22 11:11:28,830 WARN  [org.hibernate.ejb.Ejb3Configuration] (main) Persistence provider caller does not implement the EJB3 spec correctly. PersistenceUnitInfo.getNewTempClassLoader() is null.
>2012-10-22 11:11:28,851 INFO  [org.hibernate.cfg.Configuration] (main) Reading mappings from resource : META-INF/ProcessInstanceInfo.hbm.xml
>2012-10-22 11:11:28,871 INFO  [org.hibernate.cfg.Configuration] (main) Reading mappings from resource : META-INF/JBPMorm.xml
>2012-10-22 11:11:28,944 INFO  [org.hibernate.cfg.annotations.QueryBinder] (main) Binding Named query: ProcessInstancesWaitingForEvent => select processInstanceInfo.processInstanceId from ProcessInstanceInfo processInstanceInfo join processInstanceInfo.eventTypes eventTypes where eventTypes = :type
>2012-10-22 11:11:28,959 INFO  [org.hibernate.cfg.HbmBinder] (main) Mapping class: org.jbpm.persistence.processinstance.ProcessInstanceInfo -> ProcessInstanceInfo
>2012-10-22 11:11:29,001 INFO  [org.hibernate.cfg.HbmBinder] (main) Mapping collection: org.jbpm.persistence.processinstance.ProcessInstanceInfo.eventTypes -> EventTypes
>2012-10-22 11:11:29,008 INFO  [org.hibernate.cfg.AnnotationBinder] (main) Binding entity from annotated class: org.jbpm.process.audit.NodeInstanceLog
>2012-10-22 11:11:29,030 INFO  [org.hibernate.cfg.annotations.EntityBinder] (main) Bind entity org.jbpm.process.audit.NodeInstanceLog on table NodeInstanceLog
>2012-10-22 11:11:29,068 INFO  [org.hibernate.cfg.AnnotationBinder] (main) Binding entity from annotated class: org.drools.persistence.info.WorkItemInfo
>2012-10-22 11:11:29,068 INFO  [org.hibernate.cfg.annotations.EntityBinder] (main) Bind entity org.drools.persistence.info.WorkItemInfo on table WorkItemInfo
>2012-10-22 11:11:29,080 INFO  [org.hibernate.cfg.AnnotationBinder] (main) Binding entity from annotated class: org.jbpm.process.audit.VariableInstanceLog
>2012-10-22 11:11:29,081 INFO  [org.hibernate.cfg.annotations.EntityBinder] (main) Bind entity org.jbpm.process.audit.VariableInstanceLog on table VariableInstanceLog
>2012-10-22 11:11:29,082 INFO  [org.hibernate.cfg.AnnotationBinder] (main) Binding entity from annotated class: org.drools.persistence.info.SessionInfo
>2012-10-22 11:11:29,082 INFO  [org.hibernate.cfg.annotations.EntityBinder] (main) Bind entity org.drools.persistence.info.SessionInfo on table SessionInfo
>2012-10-22 11:11:29,084 INFO  [org.hibernate.cfg.AnnotationBinder] (main) Binding entity from annotated class: org.jbpm.process.audit.ProcessInstanceLog
>2012-10-22 11:11:29,084 INFO  [org.hibernate.cfg.annotations.EntityBinder] (main) Bind entity org.jbpm.process.audit.ProcessInstanceLog on table ProcessInstanceLog
>2012-10-22 11:11:29,097 INFO  [org.hibernate.validator.Version] (main) Hibernate Validator 3.1.0.GA
>2012-10-22 11:11:29,136 INFO  [org.hibernate.cfg.search.HibernateSearchEventListenerRegister] (main) Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
>2012-10-22 11:11:29,186 INFO  [org.hibernate.connection.ConnectionProviderFactory] (main) Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
>2012-10-22 11:11:29,188 INFO  [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider] (main) Using provided datasource
>2012-10-22 11:11:30,629 INFO  [org.hibernate.cfg.SettingsFactory] (main) RDBMS: Oracle, version: Oracle Database 11g Enterprise Edition Release 11.2.0.3.0 - 64bit Production
>With the Partitioning, OLAP, Data Mining and Real Application Testing options
>2012-10-22 11:11:30,629 INFO  [org.hibernate.cfg.SettingsFactory] (main) JDBC driver: Oracle JDBC driver, version: 11.2.0.2.0
>2012-10-22 11:11:30,654 INFO  [org.hibernate.dialect.Dialect] (main) Using dialect: org.hibernate.dialect.Oracle10gDialect
>2012-10-22 11:11:30,889 INFO  [org.hibernate.transaction.TransactionFactoryFactory] (main) Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory
>2012-10-22 11:11:30,895 INFO  [org.hibernate.transaction.TransactionManagerLookupFactory] (main) instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
>2012-10-22 11:11:30,896 INFO  [org.hibernate.transaction.TransactionManagerLookupFactory] (main) instantiated TransactionManagerLookup
>2012-10-22 11:11:30,896 INFO  [org.hibernate.cfg.SettingsFactory] (main) Automatic flush during beforeCompletion(): disabled
>2012-10-22 11:11:30,896 INFO  [org.hibernate.cfg.SettingsFactory] (main) Automatic session close at end of transaction: disabled
>2012-10-22 11:11:30,897 INFO  [org.hibernate.cfg.SettingsFactory] (main) Scrollable result sets: enabled
>2012-10-22 11:11:30,897 INFO  [org.hibernate.cfg.SettingsFactory] (main) JDBC3 getGeneratedKeys(): disabled
>2012-10-22 11:11:30,897 INFO  [org.hibernate.cfg.SettingsFactory] (main) Connection release mode: auto
>2012-10-22 11:11:30,898 INFO  [org.hibernate.cfg.SettingsFactory] (main) Default batch fetch size: 1
>2012-10-22 11:11:30,899 INFO  [org.hibernate.cfg.SettingsFactory] (main) Generate SQL with comments: disabled
>2012-10-22 11:11:30,899 INFO  [org.hibernate.cfg.SettingsFactory] (main) Order SQL updates by primary key: disabled
>2012-10-22 11:11:30,899 INFO  [org.hibernate.cfg.SettingsFactory] (main) Order SQL inserts for batching: disabled
>2012-10-22 11:11:30,899 INFO  [org.hibernate.cfg.SettingsFactory] (main) Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
>2012-10-22 11:11:30,900 INFO  [org.hibernate.hql.ast.ASTQueryTranslatorFactory] (main) Using ASTQueryTranslatorFactory
>2012-10-22 11:11:30,901 INFO  [org.hibernate.cfg.SettingsFactory] (main) Query language substitutions: {}
>2012-10-22 11:11:30,901 INFO  [org.hibernate.cfg.SettingsFactory] (main) JPA-QL strict compliance: enabled
>2012-10-22 11:11:30,901 INFO  [org.hibernate.cfg.SettingsFactory] (main) Second-level cache: enabled
>2012-10-22 11:11:30,901 INFO  [org.hibernate.cfg.SettingsFactory] (main) Query cache: disabled
>2012-10-22 11:11:30,901 INFO  [org.hibernate.cfg.SettingsFactory] (main) Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge
>2012-10-22 11:11:30,906 INFO  [org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge] (main) Cache provider: org.hibernate.cache.HashtableCacheProvider
>2012-10-22 11:11:30,907 INFO  [org.hibernate.cfg.SettingsFactory] (main) Optimize cache for minimal puts: disabled
>2012-10-22 11:11:30,907 INFO  [org.hibernate.cfg.SettingsFactory] (main) Cache region prefix: persistence.unit:unitName=bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar#jboss.jpa
>2012-10-22 11:11:30,907 INFO  [org.hibernate.cfg.SettingsFactory] (main) Structured second-level cache entries: disabled
>2012-10-22 11:11:30,910 INFO  [org.hibernate.cfg.SettingsFactory] (main) Echoing all SQL to stdout
>2012-10-22 11:11:30,911 INFO  [org.hibernate.cfg.SettingsFactory] (main) Statistics: disabled
>2012-10-22 11:11:30,911 INFO  [org.hibernate.cfg.SettingsFactory] (main) Deleted entity synthetic identifier rollback: disabled
>2012-10-22 11:11:30,911 INFO  [org.hibernate.cfg.SettingsFactory] (main) Default entity-mode: pojo
>2012-10-22 11:11:30,911 INFO  [org.hibernate.cfg.SettingsFactory] (main) Named query checking : enabled
>2012-10-22 11:11:30,929 INFO  [org.hibernate.impl.SessionFactoryImpl] (main) building session factory
>2012-10-22 11:11:31,081 INFO  [org.hibernate.impl.SessionFactoryObjectFactory] (main) Factory name: persistence.unit:unitName=bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar#jboss.jpa
>2012-10-22 11:11:31,083 INFO  [org.hibernate.util.NamingHelper] (main) JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
>2012-10-22 11:11:31,086 INFO  [org.hibernate.util.NamingHelper] (main) Creating subcontext: persistence.unit:unitName=bz833609-ear-1.0.ear
>2012-10-22 11:11:31,086 INFO  [org.hibernate.impl.SessionFactoryObjectFactory] (main) Bound factory to JNDI name: persistence.unit:unitName=bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar#jboss.jpa
>2012-10-22 11:11:31,086 WARN  [org.hibernate.impl.SessionFactoryObjectFactory] (main) InitialContext did not implement EventContext
>2012-10-22 11:11:31,092 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (main) Running hbm2ddl schema update
>2012-10-22 11:11:31,092 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (main) fetching database metadata
>2012-10-22 11:11:33,463 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (main) updating schema
>2012-10-22 11:11:35,027 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) table found: DBALLO01.EVENTTYPES
>2012-10-22 11:11:35,027 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) columns: [element, instanceid]
>2012-10-22 11:11:35,027 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) foreign keys: [fkb0e5621f7665489a]
>2012-10-22 11:11:35,027 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) indexes: []
>2012-10-22 11:11:36,506 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) table found: DBALLO01.NODEINSTANCELOG
>2012-10-22 11:11:36,506 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) columns: [id, nodename, log_date, nodeinstanceid, type, processinstanceid, processid, nodeid]
>2012-10-22 11:11:36,506 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) foreign keys: []
>2012-10-22 11:11:36,506 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) indexes: [sys_c009052346]
>2012-10-22 11:11:37,946 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) table found: DBALLO01.PROCESSINSTANCEINFO
>2012-10-22 11:11:37,946 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) columns: [startdate, lastreaddate, state, processinstancebytearray, optlock, instanceid, lastmodificationdate, processid]
>2012-10-22 11:11:37,946 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) foreign keys: []
>2012-10-22 11:11:37,946 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) indexes: [sys_c009052349]
>2012-10-22 11:11:39,122 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) table found: DBALLO01.PROCESSINSTANCELOG
>2012-10-22 11:11:39,122 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) columns: [id, end_date, start_date, processinstanceid, processid]
>2012-10-22 11:11:39,122 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) foreign keys: []
>2012-10-22 11:11:39,122 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) indexes: [sys_c009052352]
>2012-10-22 11:11:40,276 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) table found: DBALLO01.SESSIONINFO
>2012-10-22 11:11:40,276 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) columns: [rulesbytearray, id, startdate, optlock, lastmodificationdate]
>2012-10-22 11:11:40,276 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) foreign keys: []
>2012-10-22 11:11:40,276 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) indexes: [sys_c009052354]
>2012-10-22 11:11:41,627 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) table found: DBALLO01.VARIABLEINSTANCELOG
>2012-10-22 11:11:41,627 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) columns: [id, variableinstanceid, variableid, value, log_date, processinstanceid, processid]
>2012-10-22 11:11:41,627 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) foreign keys: []
>2012-10-22 11:11:41,627 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) indexes: [sys_c009052357]
>2012-10-22 11:11:42,969 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) table found: DBALLO01.WORKITEMINFO
>2012-10-22 11:11:42,969 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) columns: [workitembytearray, name, workitemid, state, optlock, creationdate, processinstanceid]
>2012-10-22 11:11:42,969 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) foreign keys: []
>2012-10-22 11:11:42,969 INFO  [org.hibernate.tool.hbm2ddl.TableMetadata] (main) indexes: [sys_c009052361]
>2012-10-22 11:11:42,970 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (main) schema update complete
>2012-10-22 11:11:42,984 INFO  [org.hibernate.util.NamingHelper] (main) JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
>2012-10-22 11:11:43,174 INFO  [org.jboss.ejb3.session.SessionSpecContainer] (main) Starting jboss.j2ee:ear=bz833609-ear-1.0.ear,jar=bz833609-ejb-1.0.jar,name=launcher,service=EJB3
>2012-10-22 11:11:43,191 INFO  [org.jboss.ejb3.EJBContainer] (main) STARTED EJB: org.jboss.qa.brms.ProcessLauncher ejbName: launcher
>2012-10-22 11:11:43,244 INFO  [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] (main) Binding the following Entries in Global JNDI:
>
>	bz833609-ear-1.0/launcher/local - EJB3.x Default Local Business Interface
>	bz833609-ear-1.0/launcher/local-org.jboss.qa.brms.ProcessLauncherLocal - EJB3.x Local Business Interface
>
>2012-10-22 11:11:43,309 INFO  [org.jboss.web.tomcat.service.deployers.TomcatDeployment] (main) deploy, ctxPath=/process
>2012-10-22 11:11:43,457 INFO  [org.jboss.system.server.profileservice.ProfileServiceBootstrap] (main) Loading profile: ProfileKey@371daf60[domain=default, server=default, name=production]
>2012-10-22 11:11:43,458 ERROR [org.jboss.system.server.profileservice.ProfileServiceBootstrap] (main) Failed to load profile: Summary of incomplete deployments (SEE PREVIOUS ERRORS FOR DETAILS):
>
>DEPLOYMENTS MISSING DEPENDENCIES:
>  Deployment "persistence.unit:unitName=bz833609-ear-1.0.ear/bz833609-ejb-1.0.jar#was.jpa" is missing the following dependencies:
>    Dependency "jboss.jca:name=brms-ds-oracle11gR2,service=DataSourceBinding" (should be in state "Create", but is actually in state "** NOT FOUND Depends on 'jboss.jca:name=brms-ds-oracle11gR2,service=DataSourceBinding' **")
>  Deployment "persistence.unit:unitName=bz833609-ear-1.0.ear/lib/jbpm-test-5.3.1.BRMS-ER3.jar#org.jbpm.persistence.jpa" is missing the following dependencies:
>    Dependency "jboss.jca:name=jdbc/jbpm-ds,service=DataSourceBinding" (should be in state "Create", but is actually in state "** NOT FOUND Depends on 'jboss.jca:name=jdbc/jbpm-ds,service=DataSourceBinding' **")
>
>DEPLOYMENTS IN ERROR:
>  Deployment "jboss.jca:name=jdbc/jbpm-ds,service=DataSourceBinding" is in error due to the following reason(s): ** NOT FOUND Depends on 'jboss.jca:name=jdbc/jbpm-ds,service=DataSourceBinding' **
>  Deployment "jboss.jca:name=brms-ds-oracle11gR2,service=DataSourceBinding" is in error due to the following reason(s): ** NOT FOUND Depends on 'jboss.jca:name=brms-ds-oracle11gR2,service=DataSourceBinding' **
>
>2012-10-22 11:11:43,463 INFO  [org.apache.coyote.http11.Http11Protocol] (main) Starting Coyote HTTP/1.1 on http-127.0.0.1-8080
>2012-10-22 11:11:43,473 INFO  [org.apache.coyote.ajp.AjpProtocol] (main) Starting Coyote AJP/1.3 on ajp-127.0.0.1-8009
>2012-10-22 11:11:43,477 INFO  [org.jboss.bootstrap.microcontainer.ServerImpl] (main) JBoss (Microcontainer) [5.1.2 (build: SVNTag=JBPAPP_5_1_2 date=201111102209)] Started in 41s:883ms
>2012-10-22 11:12:27,282 INFO  [STDOUT] (http-127.0.0.1-8080-1) Hibernate: 
>    select
>        SESSIONINFO_ID_SEQ.nextval 
>    from
>        dual
>2012-10-22 11:12:27,419 INFO  [org.jboss.qa.brms.ProcessLauncher] (http-127.0.0.1-8080-1) Session created
>2012-10-22 11:12:27,421 INFO  [org.jboss.qa.brms.ProcessLauncher] (http-127.0.0.1-8080-1) Added process event listener
>2012-10-22 11:12:27,423 INFO  [org.jboss.qa.brms.ProcessLauncher] (http-127.0.0.1-8080-1) Registered work item handler
>2012-10-22 11:12:27,438 INFO  [STDOUT] (http-127.0.0.1-8080-1) Hibernate: 
>    select
>        hibernate_sequence.nextval 
>    from
>        dual
>2012-10-22 11:12:27,580 INFO  [STDOUT] (http-127.0.0.1-8080-1) Hibernate: 
>    select
>        WORKITEMINFO_ID_SEQ.nextval 
>    from
>        dual
>2012-10-22 11:12:27,726 INFO  [org.jboss.qa.brms.ProcessLauncher] (http-127.0.0.1-8080-1) Process started
>2012-10-22 11:12:27,729 INFO  [STDOUT] (http-127.0.0.1-8080-1) Hibernate: 
>    select
>        WORKITEMINFO_ID_SEQ.nextval 
>    from
>        dual
>2012-10-22 11:12:27,874 INFO  [org.jboss.qa.brms.ProcessLauncher] (http-127.0.0.1-8080-1) Completed the first work item
>2012-10-22 11:12:27,887 INFO  [STDOUT] (http-127.0.0.1-8080-1) Hibernate: 
>    select
>        processins0_.InstanceId as col_0_0_ 
>    from
>        ProcessInstanceInfo processins0_ 
>    inner join
>        EventTypes eventtypes1_ 
>            on processins0_.InstanceId=eventtypes1_.InstanceId 
>    where
>        eventtypes1_.element=?
>2012-10-22 11:12:28,021 INFO  [org.jboss.qa.brms.ProcessLauncher] (http-127.0.0.1-8080-1) Completed the second work item
>2012-10-22 11:12:28,021 INFO  [org.jboss.qa.brms.ProcessLauncher] (http-127.0.0.1-8080-1) Successfully completed process
>2012-10-22 11:12:28,021 INFO  [org.jboss.qa.brms.ProcessLauncher] (http-127.0.0.1-8080-1) process defaultPackage.mytest successfully completed
>2012-10-22 11:12:28,028 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (http-127.0.0.1-8080-1) [com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator_2] TwoPhaseCoordinator.beforeCompletion - failed for com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple@3e60766d Drive beforeCompletion participants.
>javax.persistence.PersistenceException: java.lang.IllegalStateException: Illegal method call. This session was previously disposed.
>	at org.hibernate.ejb.AbstractEntityManagerImpl$1.beforeCompletion(AbstractEntityManagerImpl.java:516)
>	at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:101)
>	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:272)
>	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:89)
>	at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:160)
>	at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1433)
>	at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:137)
>	at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75)
>	at org.jboss.aspects.tx.TxPolicy.endTransaction(TxPolicy.java:170)
>	at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:87)
>	at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:190)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:76)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.tx.NullInterceptor.invoke(NullInterceptor.java:42)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptorv2.invoke(RoleBasedAuthorizationInterceptorv2.java:201)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:182)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:41)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.BlockContainerShutdownInterceptor.invoke(BlockContainerShutdownInterceptor.java:67)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.core.context.CurrentInvocationContextInterceptor.invoke(CurrentInvocationContextInterceptor.java:47)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.aspects.currentinvocation.CurrentInvocationInterceptor.invoke(CurrentInvocationInterceptor.java:67)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.interceptor.EJB3TCCLInterceptor.invoke(EJB3TCCLInterceptor.java:86)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.session.SessionSpecContainer.invoke(SessionSpecContainer.java:193)
>	at org.jboss.ejb3.session.SessionSpecContainer.invoke(SessionSpecContainer.java:250)
>	at org.jboss.ejb3.proxy.impl.handler.session.SessionProxyInvocationHandlerBase.invoke(SessionProxyInvocationHandlerBase.java:188)
>	at $Proxy173.launchProcess(Unknown Source)
>	at org.jboss.qa.brms.DefaultServlet.processRequest(DefaultServlet.java:51)
>	at org.jboss.qa.brms.DefaultServlet.doGet(DefaultServlet.java:76)
>	at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
>	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
>	at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96)
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
>	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:235)
>	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
>	at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:183)
>	at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:95)
>	at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.process(SecurityContextEstablishmentValve.java:126)
>	at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.invoke(SecurityContextEstablishmentValve.java:70)
>	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
>	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>	at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:158)
>	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
>	at org.jboss.web.tomcat.service.request.ActiveRequestResponseCacheValve.internalProcess(ActiveRequestResponseCacheValve.java:74)
>	at org.jboss.web.tomcat.service.request.ActiveRequestResponseCacheValve.invoke(ActiveRequestResponseCacheValve.java:47)
>	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:330)
>	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:829)
>	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:599)
>	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:451)
>	at java.lang.Thread.run(Thread.java:679)
>Caused by: java.lang.IllegalStateException: Illegal method call. This session was previously disposed.
>	at org.drools.reteoo.DisposedReteooWorkingMemory.getSessionClock(DisposedReteooWorkingMemory.java:398)
>	at org.drools.impl.StatefulKnowledgeSessionImpl.getSessionClock(StatefulKnowledgeSessionImpl.java:254)
>	at org.drools.marshalling.impl.ProtobufMarshaller.marshall(ProtobufMarshaller.java:151)
>	at org.drools.persistence.SessionMarshallingHelper.getSnapshot(SessionMarshallingHelper.java:72)
>	at org.drools.persistence.info.SessionInfo.update(SessionInfo.java:85)
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>	at java.lang.reflect.Method.invoke(Method.java:616)
>	at org.hibernate.ejb.event.BeanCallback.invoke(BeanCallback.java:23)
>	at org.hibernate.ejb.event.EntityCallbackHandler.callback(EntityCallbackHandler.java:81)
>	at org.hibernate.ejb.event.EntityCallbackHandler.preUpdate(EntityCallbackHandler.java:66)
>	at org.hibernate.ejb.event.EJB3FlushEntityEventListener.invokeInterceptor(EJB3FlushEntityEventListener.java:41)
>	at org.hibernate.event.def.DefaultFlushEntityEventListener.handleInterception(DefaultFlushEntityEventListener.java:330)
>	at org.hibernate.event.def.DefaultFlushEntityEventListener.scheduleUpdate(DefaultFlushEntityEventListener.java:270)
>	at org.hibernate.event.def.DefaultFlushEntityEventListener.onFlushEntity(DefaultFlushEntityEventListener.java:151)
>	at org.hibernate.event.def.AbstractFlushingEventListener.flushEntities(AbstractFlushingEventListener.java:219)
>	at org.hibernate.event.def.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:99)
>	at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:49)
>	at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1030)
>	at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:367)
>	at org.hibernate.ejb.AbstractEntityManagerImpl$1.beforeCompletion(AbstractEntityManagerImpl.java:504)
>	... 59 more
>2012-10-22 11:12:28,147 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (http-127.0.0.1-8080-1) [com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator_4] TwoPhaseCoordinator.afterCompletion - returned failure for com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple@3ad35341
>2012-10-22 11:12:28,154 ERROR [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/process].[DefaultServlet]] (http-127.0.0.1-8080-1) Servlet.service() for servlet DefaultServlet threw exception
>javax.ejb.EJBTransactionRolledbackException: Transaction rolled back
>	at org.jboss.ejb3.tx.Ejb3TxPolicy.handleEndTransactionException(Ejb3TxPolicy.java:54)
>	at org.jboss.aspects.tx.TxPolicy.endTransaction(TxPolicy.java:175)
>	at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:87)
>	at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:190)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:76)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.tx.NullInterceptor.invoke(NullInterceptor.java:42)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.security.RoleBasedAuthorizationInterceptorv2.invoke(RoleBasedAuthorizationInterceptorv2.java:201)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:182)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:41)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.BlockContainerShutdownInterceptor.invoke(BlockContainerShutdownInterceptor.java:67)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.core.context.CurrentInvocationContextInterceptor.invoke(CurrentInvocationContextInterceptor.java:47)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.aspects.currentinvocation.CurrentInvocationInterceptor.invoke(CurrentInvocationInterceptor.java:67)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.interceptor.EJB3TCCLInterceptor.invoke(EJB3TCCLInterceptor.java:86)
>	at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:102)
>	at org.jboss.ejb3.session.SessionSpecContainer.invoke(SessionSpecContainer.java:193)
>	at org.jboss.ejb3.session.SessionSpecContainer.invoke(SessionSpecContainer.java:250)
>	at org.jboss.ejb3.proxy.impl.handler.session.SessionProxyInvocationHandlerBase.invoke(SessionProxyInvocationHandlerBase.java:188)
>	at $Proxy173.launchProcess(Unknown Source)
>	at org.jboss.qa.brms.DefaultServlet.processRequest(DefaultServlet.java:51)
>	at org.jboss.qa.brms.DefaultServlet.doGet(DefaultServlet.java:76)
>	at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
>	at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
>	at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96)
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
>	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:235)
>	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
>	at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:183)
>	at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:95)
>	at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.process(SecurityContextEstablishmentValve.java:126)
>	at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.invoke(SecurityContextEstablishmentValve.java:70)
>	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
>	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>	at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:158)
>	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
>	at org.jboss.web.tomcat.service.request.ActiveRequestResponseCacheValve.internalProcess(ActiveRequestResponseCacheValve.java:74)
>	at org.jboss.web.tomcat.service.request.ActiveRequestResponseCacheValve.invoke(ActiveRequestResponseCacheValve.java:47)
>	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:330)
>	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:829)
>	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:599)
>	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:451)
>	at java.lang.Thread.run(Thread.java:679)
>Caused by: javax.transaction.RollbackException: [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] Could not commit transaction.
>	at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1445)
>	at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:137)
>	at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75)
>	at org.jboss.aspects.tx.TxPolicy.endTransaction(TxPolicy.java:170)
>	... 51 more
>Caused by: javax.persistence.PersistenceException: java.lang.IllegalStateException: Illegal method call. This session was previously disposed.
>	at org.hibernate.ejb.AbstractEntityManagerImpl$1.beforeCompletion(AbstractEntityManagerImpl.java:516)
>	at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:101)
>	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:272)
>	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:89)
>	at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:160)
>	at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1433)
>	... 54 more
>Caused by: java.lang.IllegalStateException: Illegal method call. This session was previously disposed.
>	at org.drools.reteoo.DisposedReteooWorkingMemory.getSessionClock(DisposedReteooWorkingMemory.java:398)
>	at org.drools.impl.StatefulKnowledgeSessionImpl.getSessionClock(StatefulKnowledgeSessionImpl.java:254)
>	at org.drools.marshalling.impl.ProtobufMarshaller.marshall(ProtobufMarshaller.java:151)
>	at org.drools.persistence.SessionMarshallingHelper.getSnapshot(SessionMarshallingHelper.java:72)
>	at org.drools.persistence.info.SessionInfo.update(SessionInfo.java:85)
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>	at java.lang.reflect.Method.invoke(Method.java:616)
>	at org.hibernate.ejb.event.BeanCallback.invoke(BeanCallback.java:23)
>	at org.hibernate.ejb.event.EntityCallbackHandler.callback(EntityCallbackHandler.java:81)
>	at org.hibernate.ejb.event.EntityCallbackHandler.preUpdate(EntityCallbackHandler.java:66)
>	at org.hibernate.ejb.event.EJB3FlushEntityEventListener.invokeInterceptor(EJB3FlushEntityEventListener.java:41)
>	at org.hibernate.event.def.DefaultFlushEntityEventListener.handleInterception(DefaultFlushEntityEventListener.java:330)
>	at org.hibernate.event.def.DefaultFlushEntityEventListener.scheduleUpdate(DefaultFlushEntityEventListener.java:270)
>	at org.hibernate.event.def.DefaultFlushEntityEventListener.onFlushEntity(DefaultFlushEntityEventListener.java:151)
>	at org.hibernate.event.def.AbstractFlushingEventListener.flushEntities(AbstractFlushingEventListener.java:219)
>	at org.hibernate.event.def.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:99)
>	at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:49)
>	at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1030)
>	at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:367)
>	at org.hibernate.ejb.AbstractEntityManagerImpl$1.beforeCompletion(AbstractEntityManagerImpl.java:504)
>	... 59 more

Actions: View

Attachments on bug 868873: 631397 | 631406 | 631407