Attachment 889817 Details for Bug 1091458 – server log node one

server log node one

server.log (text/x-log), 166.90 KB, created by Radovan Synek on 2014-04-25 15:45:07 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Radovan Synek

Created: 2014-04-25 15:45:07 UTC

Size: 166.90 KB

patch

obsolete

>09:55:09,016 INFO  [org.jboss.modules] (main) JBoss Modules version 1.2.2.Final-redhat-1
>09:55:09,264 INFO  [org.jboss.msc] (main) JBoss MSC version 1.0.4.GA-redhat-1
>09:55:09,335 INFO  [org.jboss.as] (MSC service thread 1-7) JBAS015899: JBoss BPM Suite 6.0.2.GA (AS 7.2.1.Final-redhat-10) starting
>09:55:09,342 DEBUG [org.jboss.as.config] (MSC service thread 1-7) Configured system properties:
>	[Host Controller] = true
>	[Server:server-one] = 
>	awt.toolkit = sun.awt.X11.XToolkit
>	designerdataobjects = ${designerdataobjects:false}
>	file.encoding = UTF-8
>	file.encoding.pkg = sun.io
>	file.separator = /
>	java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment
>	java.awt.printerjob = sun.print.PSPrinterJob
>	java.class.path = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/jboss-modules.jar
>	java.class.version = 51.0
>	java.endorsed.dirs = /qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/endorsed
>	java.ext.dirs = /qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/ext:/usr/java/packages/lib/ext
>	java.home = /qa/tools/opt/x86_64/jdk1.7.0_51/jre
>	java.io.tmpdir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/tmp
>	java.library.path = /qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/amd64/server:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/amd64:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/../lib/amd64:/qa/tools/opt/x86_64/jdk1.6.0_45/jre/lib/amd64/server:/qa/tools/opt/x86_64/jdk1.6.0_45/jre/lib/amd64:/qa/tools/opt/x86_64/jdk1.6.0_45/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
>	java.net.preferIPv4Stack = true
>	java.runtime.name = Java(TM) SE Runtime Environment
>	java.runtime.version = 1.7.0_51-b13
>	java.specification.name = Java Platform API Specification
>	java.specification.vendor = Oracle Corporation
>	java.specification.version = 1.7
>	java.util.logging.manager = org.jboss.logmanager.LogManager
>	java.vendor = Oracle Corporation
>	java.vendor.url = http://java.oracle.com/
>	java.vendor.url.bug = http://bugreport.sun.com/bugreport/
>	java.version = 1.7.0_51
>	java.vm.info = mixed mode
>	java.vm.name = Java HotSpot(TM) 64-Bit Server VM
>	java.vm.specification.name = Java Virtual Machine Specification
>	java.vm.specification.vendor = Oracle Corporation
>	java.vm.specification.version = 1.7
>	java.vm.vendor = Oracle Corporation
>	java.vm.version = 24.51-b03
>	javax.management.builder.initial = org.jboss.as.jmx.PluggableMBeanServerBuilder
>	javax.xml.datatype.DatatypeFactory = __redirected.__DatatypeFactory
>	javax.xml.parsers.DocumentBuilderFactory = __redirected.__DocumentBuilderFactory
>	javax.xml.parsers.SAXParserFactory = __redirected.__SAXParserFactory
>	javax.xml.stream.XMLEventFactory = __redirected.__XMLEventFactory
>	javax.xml.stream.XMLInputFactory = __redirected.__XMLInputFactory
>	javax.xml.stream.XMLOutputFactory = __redirected.__XMLOutputFactory
>	javax.xml.transform.TransformerFactory = __redirected.__TransformerFactory
>	javax.xml.validation.SchemaFactory:http://www.w3.org/2001/XMLSchema = __redirected.__SchemaFactory
>	javax.xml.xpath.XPathFactory:http://java.sun.com/jaxp/xpath/dom = __redirected.__XPathFactory
>	jboss.domain.base.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain
>	jboss.domain.config.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/configuration
>	jboss.home.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1
>	jboss.host.name = brmsperf05
>	jboss.modules.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/modules
>	jboss.node.name = master:server-one
>	jboss.qualified.host.name = brmsperf05
>	jboss.server.base.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one
>	jboss.server.config.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/configuration
>	jboss.server.data.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/data
>	jboss.server.deploy.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/data/content
>	jboss.server.log.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/log
>	jboss.server.name = server-one
>	jboss.server.persist.config = true
>	jboss.server.temp.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/tmp
>	kie.maven.settings.custom = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/resources/brms/maven/kie-maven-settings.xml
>	line.separator = 
>
>	logging.configuration = file:/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/configuration/default-server-logging.properties
>	module.path = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/modules
>	org.jboss.boot.log.file = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/log/server.log
>	org.jboss.resolver.warning = true
>	org.jbpm.designer.perspective = ${org.jbpm.designer.perspective:full}
>	org.kie.example = ${org.kie.example:true}
>	org.xml.sax.driver = __redirected.__XMLReaderFactory
>	os.arch = amd64
>	os.name = Linux
>	os.version = 2.6.32-358.23.2.el6.x86_64
>	path.separator = :
>	sun.arch.data.model = 64
>	sun.boot.class.path = /qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/resources.jar:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/rt.jar:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/sunrsasign.jar:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/jsse.jar:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/jce.jar:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/charsets.jar:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/jfr.jar:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/classes
>	sun.boot.library.path = /qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/amd64
>	sun.cpu.endian = little
>	sun.cpu.isalist = 
>	sun.io.unicode.encoding = UnicodeLittle
>	sun.java.command = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/jboss-modules.jar -mp /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/modules -jaxpmodule javax.xml.jaxp-provider org.jboss.as.server
>	sun.java.launcher = SUN_STANDARD
>	sun.jnu.encoding = UTF-8
>	sun.management.compiler = HotSpot 64-Bit Tiered Compilers
>	sun.os.patch.level = unknown
>	user.country = US
>	user.dir = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1
>	user.home = /home/hudson
>	user.language = en
>	user.name = hudson
>	user.timezone = America/New_York
>09:55:09,345 DEBUG [org.jboss.as.config] (MSC service thread 1-7) VM Arguments: -D[Server:server-one] -XX:PermSize=256m -XX:MaxPermSize=1024m -Xms1503m -Xmx1503m -D[Host Controller]=true -Djava.io.tmpdir=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/tmp -Dkie.maven.settings.custom=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/resources/brms/maven/kie-maven-settings.xml -Ddesignerdataobjects=${designerdataobjects:false} -Dorg.kie.example=${org.kie.example:true} -Djboss.home.dir=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1 -Djava.net.preferIPv4Stack=true -Dorg.jbpm.designer.perspective=${org.jbpm.designer.perspective:full} -Djboss.server.log.dir=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/log -Djboss.server.temp.dir=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/tmp -Djboss.server.data.dir=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/data -Dorg.jboss.boot.log.file=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/log/server.log -Dlogging.configuration=file:/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/configuration/default-server-logging.properties 
>09:55:09,431 INFO  [org.xnio] (MSC service thread 1-3) XNIO Version 3.0.7.GA-redhat-1
>09:55:09,437 INFO  [org.xnio.nio] (MSC service thread 1-3) XNIO NIO Implementation Version 3.0.7.GA-redhat-1
>09:55:09,445 INFO  [org.jboss.remoting] (MSC service thread 1-3) JBoss Remoting version 3.2.16.GA-redhat-1
>09:55:10,693 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 37) JBAS013171: Activating Security Subsystem
>09:55:10,694 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 33) JBAS015537: Activating WebServices Extension
>09:55:10,696 INFO  [org.jboss.as.security] (MSC service thread 1-6) JBAS013170: Current PicketBox version=4.0.17.SP2-redhat-2
>09:55:10,728 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 42) JBAS011800: Activating Naming Subsystem
>09:55:10,730 INFO  [org.jboss.as.jsf] (ServerService Thread Pool -- 47) JBAS012605: Activated the following JSF Implementations: [main, 1.2]
>09:55:10,737 INFO  [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 50) JBAS010260: Activating JGroups subsystem.
>09:55:10,748 INFO  [org.jboss.as.jacorb] (ServerService Thread Pool -- 55) JBAS016300: Activating JacORB Subsystem
>09:55:10,750 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 56) JBAS010280: Activating Infinispan subsystem.
>09:55:10,756 INFO  [org.jboss.as.configadmin] (ServerService Thread Pool -- 60) JBAS016200: Activating ConfigAdmin Subsystem
>09:55:10,781 INFO  [org.jboss.as.connector.logging] (MSC service thread 1-1) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.19.Final-redhat-2)
>09:55:10,792 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 59) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
>09:55:10,804 INFO  [org.jboss.as.naming] (MSC service thread 1-9) JBAS011802: Starting Naming Service
>09:55:10,806 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 59) JBAS010404: Deploying non-JDBC-compliant driver class org.postgresql.Driver (version 9.2)
>09:55:10,806 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-5) JBAS015400: Bound mail session [java:/mail/jbpmMailSession]
>09:55:10,808 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-6) JBAS015400: Bound mail session [java:jboss/mail/Default]
>09:55:10,828 INFO  [org.jboss.jaxr] (MSC service thread 1-9) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory
>09:55:10,834 INFO  [org.jboss.ws.common.management] (MSC service thread 1-14) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.1.4.Final-redhat-7
>09:55:10,923 INFO  [org.apache.coyote.http11] (MSC service thread 1-2) JBWEB003001: Coyote HTTP/1.1 initializing on : http-localhost/127.0.0.1:8080
>09:55:10,929 INFO  [org.apache.coyote.http11] (MSC service thread 1-2) JBWEB003000: Coyote HTTP/1.1 starting on: http-localhost/127.0.0.1:8080
>09:55:10,931 INFO  [org.apache.coyote.ajp] (MSC service thread 1-16) JBWEB003046: Starting Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8009
>09:55:10,984 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000001: Initializing mod_cluster ${project.version}
>09:55:11,008 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000032: Listening to proxy advertisements on /224.0.1.105:23364
>09:55:11,085 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=true,backup=false,sharedStore=true,journalDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/data/messagingjournal,bindingsDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/data/messagingbindings,largeMessagesDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/data/messaginglargemessages,pagingDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/data/messagingpaging)
>09:55:11,087 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221006: Waiting to obtain live lock
>09:55:11,122 INFO  [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (ServerService Thread Pool -- 56) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
>09:55:11,128 INFO  [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (ServerService Thread Pool -- 56) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
>09:55:11,134 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221013: Using NIO Journal
>09:55:11,136 INFO  [org.jboss.as.jacorb] (MSC service thread 1-13) JBAS016330: CORBA ORB Service started
>09:55:11,195 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
>09:55:11,196 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/datasources/brms-jdbc-ds]
>09:55:11,196 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/datasources/brms-jdbc-dsQuartz]
>09:55:11,236 INFO  [org.jboss.as.jacorb] (MSC service thread 1-3) JBAS016328: CORBA Naming Service started
>09:55:11,247 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221034: Waiting to obtain live lock
>09:55:11,248 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221035: Live Server Obtained live lock
>09:55:11,307 INFO  [org.jboss.as.remoting] (MSC service thread 1-7) JBAS017100: Listening on 127.0.0.1:4447
>09:55:11,889 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221020: Started Netty Acceptor version 3.6.6.Final-redhat-1-fd3c6b7 127.0.0.1:5445 for CORE protocol
>09:55:11,891 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221020: Started Netty Acceptor version 3.6.6.Final-redhat-1-fd3c6b7 127.0.0.1:5455 for CORE protocol
>09:55:11,901 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221007: Server is now live
>09:55:11,901 INFO  [org.hornetq.core.server] (MSC service thread 1-4) HQ221001: HornetQ Server version 2.3.5.Final-redhat-2 (Monster Bee, 123) [37d218fc-cc81-11e3-ac85-ebcda1137edb] 
>09:55:11,915 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory
>09:55:11,916 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 63) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
>09:55:11,971 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-10) JBAS010406: Registered connection factory java:/JmsXA
>09:55:12,000 INFO  [org.hornetq.ra] (MSC service thread 1-10) HornetQ resource adaptor started
>09:55:12,001 INFO  [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-10) IJ020002: Deployed: file://RaActivatorhornetq-ra
>09:55:12,003 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-11) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA]
>09:55:12,055 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss BPM Suite 6.0.2.GA (AS 7.2.1.Final-redhat-10) started in 3511ms - Started 158 of 279 services (118 services are passive or on-demand)
>09:55:13,698 INFO  [org.hornetq.core.server] (Thread-5 (HornetQ-server-HornetQServerImpl::serverUUID=37d218fc-cc81-11e3-ac85-ebcda1137edb-791162793)) HQ221027: Bridge ClusterConnectionBridge@a9acf21 [name=sf.my-cluster.38d04edd-cc81-11e3-bde4-69d5bf946063, queue=QueueImpl[name=sf.my-cluster.38d04edd-cc81-11e3-bde4-69d5bf946063, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=37d218fc-cc81-11e3-ac85-ebcda1137edb]]@399484f7 targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@a9acf21 [name=sf.my-cluster.38d04edd-cc81-11e3-bde4-69d5bf946063, queue=QueueImpl[name=sf.my-cluster.38d04edd-cc81-11e3-bde4-69d5bf946063, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=37d218fc-cc81-11e3-ac85-ebcda1137edb]]@399484f7 targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@50804542[nodeUUID=37d218fc-cc81-11e3-ac85-ebcda1137edb, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost, address=jms, server=HornetQServerImpl::serverUUID=37d218fc-cc81-11e3-ac85-ebcda1137edb])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost], discoveryGroupConfiguration=null]] is connected
>09:56:01,044 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-14) JBAS015876: Starting deployment of "business-central.war" (runtime-name: "business-central.war")
>09:56:05,881 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015960: Class Path entry xalan-2.7.1-redhat-3.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.13-redhat-2.jar  does not point to a valid jar for a Class-Path reference.
>09:56:05,881 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015960: Class Path entry serializer-2.7.1-redhat-3.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.13-redhat-2.jar  does not point to a valid jar for a Class-Path reference.
>09:56:05,881 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015960: Class Path entry xercesImpl-2.9.1-redhat-4.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.13-redhat-2.jar  does not point to a valid jar for a Class-Path reference.
>09:56:05,881 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015960: Class Path entry xml-apis-1.3.04.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.13-redhat-2.jar  does not point to a valid jar for a Class-Path reference.
>09:56:05,881 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015960: Class Path entry commons-lang-2.6-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.13-redhat-2.jar  does not point to a valid jar for a Class-Path reference.
>09:56:05,882 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015960: Class Path entry commons-io-2.1-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.13-redhat-2.jar  does not point to a valid jar for a Class-Path reference.
>09:56:05,882 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015960: Class Path entry log4j-1.2.16-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.13-redhat-2.jar  does not point to a valid jar for a Class-Path reference.
>09:56:06,293 INFO  [org.jboss.as.jpa] (MSC service thread 1-6) JBAS011401: Read persistence.xml for org.jbpm.domain
>09:56:06,777 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("com.google.guava:main") which may be changed or removed in future versions without notice.
>09:56:06,777 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("com.google.guava:main") which may be changed or removed in future versions without notice.
>09:56:06,778 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("com.h2database.h2:main") which may be changed or removed in future versions without notice.
>09:56:06,778 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("com.h2database.h2:main") which may be changed or removed in future versions without notice.
>09:56:06,781 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("com.sun.xml.bind:main") which may be changed or removed in future versions without notice.
>09:56:06,781 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("com.sun.xml.bind:main") which may be changed or removed in future versions without notice.
>09:56:06,783 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.antlr:main") which may be changed or removed in future versions without notice.
>09:56:06,783 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.antlr:main") which may be changed or removed in future versions without notice.
>09:56:06,786 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.cli:main") which may be changed or removed in future versions without notice.
>09:56:06,787 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.cli:main") which may be changed or removed in future versions without notice.
>09:56:06,787 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.codec:main") which may be changed or removed in future versions without notice.
>09:56:06,787 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.codec:main") which may be changed or removed in future versions without notice.
>09:56:06,787 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.collections:main") which may be changed or removed in future versions without notice.
>09:56:06,787 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.collections:main") which may be changed or removed in future versions without notice.
>09:56:06,790 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.io:main") which may be changed or removed in future versions without notice.
>09:56:06,790 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.io:main") which may be changed or removed in future versions without notice.
>09:56:06,791 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.lang:main") which may be changed or removed in future versions without notice.
>09:56:06,791 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.lang:main") which may be changed or removed in future versions without notice.
>09:56:06,801 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.cxf.impl:main") which may be changed or removed in future versions without notice.
>09:56:06,801 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.cxf.impl:main") which may be changed or removed in future versions without notice.
>09:56:06,803 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.httpcomponents:main") which may be changed or removed in future versions without notice.
>09:56:06,803 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.httpcomponents:main") which may be changed or removed in future versions without notice.
>09:56:06,804 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.james.mime4j:main") which may be changed or removed in future versions without notice.
>09:56:06,804 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.james.mime4j:main") which may be changed or removed in future versions without notice.
>09:56:06,807 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.neethi:main") which may be changed or removed in future versions without notice.
>09:56:06,807 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.neethi:main") which may be changed or removed in future versions without notice.
>09:56:06,808 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.velocity:main") which may be changed or removed in future versions without notice.
>09:56:06,808 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.velocity:main") which may be changed or removed in future versions without notice.
>09:56:06,808 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.xml-resolver:main") which may be changed or removed in future versions without notice.
>09:56:06,808 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.xml-resolver:main") which may be changed or removed in future versions without notice.
>09:56:06,809 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-core-asl:main") which may be changed or removed in future versions without notice.
>09:56:06,810 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-core-asl:main") which may be changed or removed in future versions without notice.
>09:56:06,810 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-jaxrs:main") which may be changed or removed in future versions without notice.
>09:56:06,810 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-jaxrs:main") which may be changed or removed in future versions without notice.
>09:56:06,811 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-mapper-asl:main") which may be changed or removed in future versions without notice.
>09:56:06,811 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-mapper-asl:main") which may be changed or removed in future versions without notice.
>09:56:06,811 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-xc:main") which may be changed or removed in future versions without notice.
>09:56:06,812 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-xc:main") which may be changed or removed in future versions without notice.
>09:56:06,812 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jettison:main") which may be changed or removed in future versions without notice.
>09:56:06,812 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jettison:main") which may be changed or removed in future versions without notice.
>09:56:06,812 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.woodstox:main") which may be changed or removed in future versions without notice.
>09:56:06,812 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.woodstox:main") which may be changed or removed in future versions without notice.
>09:56:06,815 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.dom4j:main") which may be changed or removed in future versions without notice.
>09:56:06,815 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.dom4j:main") which may be changed or removed in future versions without notice.
>09:56:06,821 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.javassist:main") which may be changed or removed in future versions without notice.
>09:56:06,821 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.javassist:main") which may be changed or removed in future versions without notice.
>09:56:06,821 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.jboss.as.web:main") which may be changed or removed in future versions without notice.
>09:56:06,822 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.jboss.as.web:main") which may be changed or removed in future versions without notice.
>09:56:06,822 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.jboss.netty:main") which may be changed or removed in future versions without notice.
>09:56:06,822 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.jboss.netty:main") which may be changed or removed in future versions without notice.
>09:56:06,830 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.jdom:main") which may be changed or removed in future versions without notice.
>09:56:06,830 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.jdom:main") which may be changed or removed in future versions without notice.
>09:56:06,831 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.jgroups:main") which may be changed or removed in future versions without notice.
>09:56:06,831 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.jgroups:main") which may be changed or removed in future versions without notice.
>09:56:06,831 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.joda.time:main") which may be changed or removed in future versions without notice.
>09:56:06,831 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.joda.time:main") which may be changed or removed in future versions without notice.
>09:56:06,841 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.yaml.snakeyaml:main") which may be changed or removed in future versions without notice.
>09:56:06,841 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.yaml.snakeyaml:main") which may be changed or removed in future versions without notice.
>09:56:07,049 INFO  [org.jboss.weld.deployer] (MSC service thread 1-8) JBAS016002: Processing weld deployment business-central.war
>09:56:07,189 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-8) JNDI bindings for session bean named RetryTrackerSingleton in deployment unit deployment "business-central.war" are as follows:
>
>	java:global/business-central/RetryTrackerSingleton!org.kie.services.remote.jms.RetryTrackerSingleton
>	java:app/business-central/RetryTrackerSingleton!org.kie.services.remote.jms.RetryTrackerSingleton
>	java:module/RetryTrackerSingleton!org.kie.services.remote.jms.RetryTrackerSingleton
>	java:global/business-central/RetryTrackerSingleton
>	java:app/business-central/RetryTrackerSingleton
>	java:module/RetryTrackerSingleton
>
>09:56:07,436 INFO  [org.jboss.weld.deployer] (MSC service thread 1-5) JBAS016005: Starting Services for CDI deployment: business-central.war
>09:56:07,486 INFO  [org.jboss.weld.Version] (MSC service thread 1-5) WELD-000900 1.1.13 (redhat)
>09:56:07,552 INFO  [org.jboss.as.jpa] (ServerService Thread Pool -- 64) JBAS011402: Starting Persistence Unit Service 'business-central.war#org.jbpm.domain'
>09:56:07,555 INFO  [org.jboss.weld.deployer] (MSC service thread 1-3) JBAS016008: Starting weld service for deployment business-central.war
>09:56:07,557 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 67) HQ221003: trying to deploy queue jms.queue.KIE.SESSION
>09:56:07,575 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 67) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.SESSION
>09:56:07,576 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 67) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.SESSION.ALL
>09:56:07,576 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 65) HQ221003: trying to deploy queue jms.queue.KIE.TASK
>09:56:07,578 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.TASK
>09:56:07,578 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.TASK.ALL
>09:56:07,578 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 68) HQ221003: trying to deploy queue jms.queue.KIE.AUDIT
>09:56:07,595 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.AUDIT
>09:56:07,596 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.AUDIT.ALL
>09:56:07,603 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 66) HQ221003: trying to deploy queue jms.queue.KIE.RESPONSE
>09:56:07,605 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.RESPONSE
>09:56:07,605 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.RESPONSE.ALL
>09:56:07,626 INFO  [org.jboss.as.ejb3] (MSC service thread 1-7) JBAS014142: Started message driven bean 'JMSAuditProcessor' with 'hornetq-ra' resource adapter
>09:56:07,627 INFO  [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014142: Started message driven bean 'TaskServiceRequesMessageBean' with 'hornetq-ra' resource adapter
>09:56:07,628 INFO  [org.jboss.as.ejb3] (MSC service thread 1-9) JBAS014142: Started message driven bean 'KieSessionRequesMessageBean' with 'hornetq-ra' resource adapter
>09:56:07,707 INFO  [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 64) HCANN000001: Hibernate Commons Annotations {4.0.1.Final-redhat-2}
>09:56:07,712 INFO  [org.hibernate.Version] (ServerService Thread Pool -- 64) HHH000412: Hibernate Core {4.2.0.SP1-redhat-1}
>09:56:07,714 INFO  [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 64) HHH000206: hibernate.properties not found
>09:56:07,716 INFO  [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 64) HHH000021: Bytecode provider name : javassist
>09:56:07,738 INFO  [org.hibernate.ejb.Ejb3Configuration] (ServerService Thread Pool -- 64) HHH000204: Processing PersistenceUnitInfo [
>	name: org.jbpm.domain
>	...]
>09:56:08,767 INFO  [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (ServerService Thread Pool -- 64) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
>09:56:09,289 INFO  [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 64) HHH000400: Using dialect: org.hibernate.dialect.PostgreSQL82Dialect
>09:56:09,367 INFO  [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] (ServerService Thread Pool -- 64) HHH000424: Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException
>09:56:09,379 INFO  [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (ServerService Thread Pool -- 64) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory
>09:56:09,383 INFO  [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (ServerService Thread Pool -- 64) HHH000397: Using ASTQueryTranslatorFactory
>09:56:09,426 INFO  [org.hibernate.validator.internal.util.Version] (ServerService Thread Pool -- 64) HV000001: Hibernate Validator 4.3.1.Final-redhat-1
>09:56:10,038 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000228: Running hbm2ddl schema update
>09:56:10,038 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000102: Fetching database metadata
>09:56:10,048 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000396: Updating schema
>09:56:10,055 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Attachment
>09:56:10,059 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BAMTaskSummary
>09:56:10,062 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BooleanExpression
>09:56:10,067 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Content
>09:56:10,070 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ContextMappingInfo
>09:56:10,074 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationKeyInfo
>09:56:10,078 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationPropertyInfo
>09:56:10,081 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Deadline
>09:56:10,084 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Delegation_delegates
>09:56:10,088 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ErrorInfo
>09:56:10,091 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Escalation
>09:56:10,093 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: EventTypes
>09:56:10,096 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: I18NText
>09:56:10,099 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NodeInstanceLog
>09:56:10,102 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification
>09:56:10,105 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_BAs
>09:56:10,109 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_Recipients
>09:56:10,112 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_email_header
>09:56:10,115 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: OrganizationalEntity
>09:56:10,119 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_BAs
>09:56:10,122 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_ExclOwners
>09:56:10,126 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_PotOwners
>09:56:10,128 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Recipients
>09:56:10,131 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Stakeholders
>09:56:10,133 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceInfo
>09:56:10,135 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceLog
>09:56:10,138 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment
>09:56:10,141 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment_potentialOwners
>09:56:10,143 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: RequestInfo
>09:56:10,145 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: SessionInfo
>09:56:10,148 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Task
>09:56:10,151 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskDef
>09:56:10,153 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskEvent
>09:56:10,156 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: VariableInstanceLog
>09:56:10,159 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: WorkItemInfo
>09:56:10,162 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: email_header
>09:56:10,165 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: task_comment
>09:56:10,168 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Attachment
>09:56:10,170 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BAMTaskSummary
>09:56:10,174 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BooleanExpression
>09:56:10,177 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Content
>09:56:10,180 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ContextMappingInfo
>09:56:10,184 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationKeyInfo
>09:56:10,186 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationPropertyInfo
>09:56:10,189 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Deadline
>09:56:10,191 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Delegation_delegates
>09:56:10,194 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ErrorInfo
>09:56:10,196 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Escalation
>09:56:10,199 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: EventTypes
>09:56:10,201 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: I18NText
>09:56:10,205 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NodeInstanceLog
>09:56:10,209 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification
>09:56:10,212 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_BAs
>09:56:10,215 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_Recipients
>09:56:10,219 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_email_header
>09:56:10,221 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: OrganizationalEntity
>09:56:10,223 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_BAs
>09:56:10,225 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_ExclOwners
>09:56:10,228 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_PotOwners
>09:56:10,231 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Recipients
>09:56:10,233 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Stakeholders
>09:56:10,235 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceInfo
>09:56:10,238 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceLog
>09:56:10,239 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment
>09:56:10,241 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment_potentialOwners
>09:56:10,244 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: RequestInfo
>09:56:10,246 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: SessionInfo
>09:56:10,249 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Task
>09:56:10,251 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskDef
>09:56:10,253 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskEvent
>09:56:10,255 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: VariableInstanceLog
>09:56:10,258 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: WorkItemInfo
>09:56:10,260 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: email_header
>09:56:10,262 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: task_comment
>09:56:10,266 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ATTACHMENT_ID_SEQ
>09:56:10,269 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BAM_TASK_ID_SEQ
>09:56:10,271 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BOOLEANEXPR_ID_SEQ
>09:56:10,273 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: COMMENT_ID_SEQ
>09:56:10,275 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CONTENT_ID_SEQ
>09:56:10,278 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CONTEXT_MAPPING_INFO_ID_SEQ
>09:56:10,280 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CORRELATION_KEY_ID_SEQ
>09:56:10,283 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CORRELATION_PROP_ID_SEQ
>09:56:10,286 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: DEADLINE_ID_SEQ
>09:56:10,289 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: EMAILNOTIFHEAD_ID_SEQ
>09:56:10,292 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ERROR_INFO_ID_SEQ
>09:56:10,295 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ESCALATION_ID_SEQ
>09:56:10,297 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: I18NTEXT_ID_SEQ
>09:56:10,300 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NODE_INST_LOG_ID_SEQ
>09:56:10,303 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NOTIFICATION_ID_SEQ
>09:56:10,306 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PROCESS_INSTANCE_INFO_ID_SEQ
>09:56:10,309 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PROC_INST_LOG_ID_SEQ
>09:56:10,311 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: REASSIGNMENT_ID_SEQ
>09:56:10,314 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: REQUEST_INFO_ID_SEQ
>09:56:10,317 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: SESSIONINFO_ID_SEQ
>09:56:10,320 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TASK_DEF_ID_SEQ
>09:56:10,322 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TASK_EVENT_ID_SEQ
>09:56:10,325 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TASK_ID_SEQ
>09:56:10,328 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: VAR_INST_LOG_ID_SEQ
>09:56:10,330 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: WORKITEMINFO_ID_SEQ
>09:56:11,884 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_ExclOwners add constraint FK_pth28a73rj6bxtlfc69kmqo0a foreign key (entity_id) references OrganizationalEntity
>09:56:11,884 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_pth28a73rj6bxtlfc69kmqo0a" for relation "peopleassignments_exclowners" already exists
>09:56:11,890 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_ExclOwners add constraint FK_b8owuxfrdng050ugpk0pdowa7 foreign key (task_id) references Task
>09:56:11,890 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_b8owuxfrdng050ugpk0pdowa7" for relation "peopleassignments_exclowners" already exists
>09:56:11,896 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_PotOwners add constraint FK_tee3ftir7xs6eo3fdvi3xw026 foreign key (entity_id) references OrganizationalEntity
>09:56:11,896 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_tee3ftir7xs6eo3fdvi3xw026" for relation "peopleassignments_potowners" already exists
>09:56:11,903 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_PotOwners add constraint FK_4dv2oji7pr35ru0w45trix02x foreign key (task_id) references Task
>09:56:11,905 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_4dv2oji7pr35ru0w45trix02x" for relation "peopleassignments_potowners" already exists
>09:56:11,908 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_Recipients add constraint FK_4g7y3wx6gnokf6vycgpxs83d6 foreign key (entity_id) references OrganizationalEntity
>09:56:11,909 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_4g7y3wx6gnokf6vycgpxs83d6" for relation "peopleassignments_recipients" already exists
>09:56:11,914 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_Recipients add constraint FK_enhk831fghf6akjilfn58okl4 foreign key (task_id) references Task
>09:56:11,914 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_enhk831fghf6akjilfn58okl4" for relation "peopleassignments_recipients" already exists
>09:56:11,920 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_Stakeholders add constraint FK_met63inaep6cq4ofb3nnxi4tm foreign key (entity_id) references OrganizationalEntity
>09:56:11,920 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_met63inaep6cq4ofb3nnxi4tm" for relation "peopleassignments_stakeholders" already exists
>09:56:11,926 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_Stakeholders add constraint FK_4bh3ay74x6ql9usunubttfdf1 foreign key (task_id) references Task
>09:56:11,927 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_4bh3ay74x6ql9usunubttfdf1" for relation "peopleassignments_stakeholders" already exists
>09:56:11,932 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Reassignment add constraint FK_pnpeue9hs6kx2ep0sp16b6kfd foreign key (Escalation_Reassignments_Id) references Escalation
>09:56:11,932 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_pnpeue9hs6kx2ep0sp16b6kfd" for relation "reassignment" already exists
>09:56:11,938 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Reassignment_potentialOwners add constraint FK_8frl6la7tgparlnukhp8xmody foreign key (entity_id) references OrganizationalEntity
>09:56:11,938 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_8frl6la7tgparlnukhp8xmody" for relation "reassignment_potentialowners" already exists
>09:56:11,944 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Reassignment_potentialOwners add constraint FK_qbega5ncu6b9yigwlw55aeijn foreign key (task_id) references Reassignment
>09:56:11,945 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_qbega5ncu6b9yigwlw55aeijn" for relation "reassignment_potentialowners" already exists
>09:56:11,951 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Task add constraint FK_dpk0f9ucm14c78bsxthh7h8yh foreign key (taskInitiator_id) references OrganizationalEntity
>09:56:11,951 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_dpk0f9ucm14c78bsxthh7h8yh" for relation "task" already exists
>09:56:11,957 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Task add constraint FK_nh9nnt47f3l61qjlyedqt05rf foreign key (actualOwner_id) references OrganizationalEntity
>09:56:11,957 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_nh9nnt47f3l61qjlyedqt05rf" for relation "task" already exists
>09:56:11,963 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Task add constraint FK_k02og0u71obf1uxgcdjx9rcjc foreign key (createdBy_id) references OrganizationalEntity
>09:56:11,963 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_k02og0u71obf1uxgcdjx9rcjc" for relation "task" already exists
>09:56:11,969 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table task_comment add constraint FK_aax378yjnsmw9kb9vsu994jjv foreign key (addedBy_id) references OrganizationalEntity
>09:56:11,969 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_aax378yjnsmw9kb9vsu994jjv" for relation "task_comment" already exists
>09:56:11,975 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table task_comment add constraint FK_1ws9jdmhtey6mxu7jb0r0ufvs foreign key (TaskData_Comments_Id) references Task
>09:56:11,975 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: constraint "fk_1ws9jdmhtey6mxu7jb0r0ufvs" for relation "task_comment" already exists
>09:56:11,981 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence ATTACHMENT_ID_SEQ
>09:56:11,981 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(attachment_id_seq, 2200) already exists.
>09:56:11,987 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence BAM_TASK_ID_SEQ
>09:56:11,987 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(bam_task_id_seq, 2200) already exists.
>09:56:11,993 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence BOOLEANEXPR_ID_SEQ
>09:56:11,993 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(booleanexpr_id_seq, 2200) already exists.
>09:56:11,999 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence COMMENT_ID_SEQ
>09:56:11,999 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(comment_id_seq, 2200) already exists.
>09:56:12,005 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence CONTENT_ID_SEQ
>09:56:12,005 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(content_id_seq, 2200) already exists.
>09:56:12,011 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence CONTEXT_MAPPING_INFO_ID_SEQ
>09:56:12,011 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(context_mapping_info_id_seq, 2200) already exists.
>09:56:12,017 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence CORRELATION_KEY_ID_SEQ
>09:56:12,017 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(correlation_key_id_seq, 2200) already exists.
>09:56:12,023 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence CORRELATION_PROP_ID_SEQ
>09:56:12,023 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(correlation_prop_id_seq, 2200) already exists.
>09:56:12,029 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence DEADLINE_ID_SEQ
>09:56:12,029 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(deadline_id_seq, 2200) already exists.
>09:56:12,035 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence EMAILNOTIFHEAD_ID_SEQ
>09:56:12,036 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(emailnotifhead_id_seq, 2200) already exists.
>09:56:12,041 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence ERROR_INFO_ID_SEQ
>09:56:12,041 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(error_info_id_seq, 2200) already exists.
>09:56:12,047 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence ESCALATION_ID_SEQ
>09:56:12,048 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(escalation_id_seq, 2200) already exists.
>09:56:12,053 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence I18NTEXT_ID_SEQ
>09:56:12,054 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(i18ntext_id_seq, 2200) already exists.
>09:56:12,060 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence NODE_INST_LOG_ID_SEQ
>09:56:12,060 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(node_inst_log_id_seq, 2200) already exists.
>09:56:12,066 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence NOTIFICATION_ID_SEQ
>09:56:12,066 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(notification_id_seq, 2200) already exists.
>09:56:12,072 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence PROCESS_INSTANCE_INFO_ID_SEQ
>09:56:12,072 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(process_instance_info_id_seq, 2200) already exists.
>09:56:12,078 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence PROC_INST_LOG_ID_SEQ
>09:56:12,078 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(proc_inst_log_id_seq, 2200) already exists.
>09:56:12,084 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence REASSIGNMENT_ID_SEQ
>09:56:12,084 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(reassignment_id_seq, 2200) already exists.
>09:56:12,090 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence REQUEST_INFO_ID_SEQ
>09:56:12,090 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(request_info_id_seq, 2200) already exists.
>09:56:12,096 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence SESSIONINFO_ID_SEQ
>09:56:12,096 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(sessioninfo_id_seq, 2200) already exists.
>09:56:12,102 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence TASK_DEF_ID_SEQ
>09:56:12,102 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(task_def_id_seq, 2200) already exists.
>09:56:12,114 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence TASK_EVENT_ID_SEQ
>09:56:12,114 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(task_event_id_seq, 2200) already exists.
>09:56:12,120 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence TASK_ID_SEQ
>09:56:12,120 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(task_id_seq, 2200) already exists.
>09:56:12,126 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence VAR_INST_LOG_ID_SEQ
>09:56:12,126 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(var_inst_log_id_seq, 2200) already exists.
>09:56:12,132 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create sequence WORKITEMINFO_ID_SEQ
>09:56:12,132 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) ERROR: duplicate key value violates unique constraint "pg_type_typname_nsp_index"
>  Detail: Key (typname, typnamespace)=(workiteminfo_id_seq, 2200) already exists.
>09:56:12,132 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000232: Schema update complete
>09:56:13,512 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-14) Solder Config XML provider starting...
>09:56:13,514 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-14) Loading XmlDocumentProvider: org.jboss.solder.config.xml.bootstrap.ResourceLoaderXmlDocumentProvider
>09:56:13,528 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-14) Reading XML file: jar:file:/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/modules/system/layers/bpms/org/jbpm/main/jbpm-kie-services-6.0.3-redhat-1.jar!/META-INF/beans.xml
>09:56:13,535 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-14) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/uberfire-api-0.3.3-redhat-1.jar/META-INF/beans.xml
>09:56:13,537 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-14) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/drools-wb-rest-defaultapprover-6.0.3-redhat-1.jar/META-INF/beans.xml
>09:56:13,539 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-14) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/uberfire-security-server-0.3.3-redhat-1.jar/META-INF/beans.xml
>09:56:13,540 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) starting errai cdi ...
>09:56:13,551 INFO  [org.jboss.solder.Version] (MSC service thread 1-14) Solder 3.2.1.Final (build id: 3.2.1.Final)
>09:56:13,664 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @SessionScoped class org.jbpm.formModeler.renderer.backend.service.FormRenderingServiceImpl
>09:56:13,672 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.formModeler.renderer.backend.service.FormRendererIncluderServiceImpl
>09:56:13,748 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.testscenario.backend.server.ScenarioTestEditorServiceImpl
>09:56:13,772 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.es.backend.server.ExecutorServiceEntryPointImpl
>09:56:13,821 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.drltext.backend.server.DRLTextEditorServiceImpl
>09:56:13,912 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.projecteditor.backend.server.ProjectScreenServiceImpl
>09:56:14,708 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.globals.backend.server.GlobalsEditorServiceImpl
>09:56:14,726 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.FormModelerProcessStarterEntryPointImpl
>09:56:14,730 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.TypeRoleServiceEntryPointImpl
>09:56:14,742 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.TaskServiceEntryPointImpl
>09:56:14,752 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.FormServiceEntryPointImpl
>09:56:14,756 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.UserServiceEntryPointImpl
>09:56:14,794 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.workitems.backend.server.WorkItemsEditorServiceImpl
>09:56:14,818 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.backend.source.SourceServicesImpl
>09:56:14,834 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.backend.rulename.RuleNameServiceImpl
>09:56:14,838 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.backend.security.KieWorkbenchSecurityServiceImpl
>09:56:14,843 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.backend.validation.ValidationServiceImpl
>09:56:14,868 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.formModeler.panels.modeler.backend.FormModelerServiceImpl
>09:56:15,337 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.bd.backend.server.DataServiceEntryPointImpl
>09:56:15,340 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.bd.backend.server.KieSessionEntryPointImpl
>09:56:15,368 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.bd.backend.server.DeploymentManagerEntryPointImpl
>09:56:15,736 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.search.backend.server.SearchServiceImpl
>09:56:15,763 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.scorecardxls.backend.server.ScoreCardXLSServiceImpl
>09:56:16,000 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.dsltext.backend.server.DSLTextEditorServiceImpl
>09:56:16,011 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.FileNavigatorServiceImpl
>09:56:16,029 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.WorkbenchServicesImpl
>09:56:16,068 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.VFSServicesServerImpl
>09:56:16,098 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.plugin.RuntimePluginsServiceServerImpl
>09:56:16,101 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.repositories.RepositoryServiceEditorImpl
>09:56:16,110 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.organizationalunit.OrganizationalUnitServiceImpl
>09:56:16,114 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.repositories.RepositoryServiceImpl
>09:56:16,227 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.template.server.GuidedRuleTemplateEditorServiceImpl
>09:56:16,545 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.designer.server.service.DefaultDesignerAssetService
>09:56:16,731 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.jbpm.dashboard.renderer.service.impl.DashboardRendererServiceImpl
>09:56:16,895 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.enums.backend.server.EnumServiceImpl
>09:56:16,921 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.builder.BuildServiceImpl
>09:56:16,961 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.project.backend.server.ProjectServiceImpl
>09:56:16,963 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.project.backend.server.KModuleServiceImpl
>09:56:16,970 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.project.backend.server.POMServiceImpl
>09:56:17,008 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.datamodel.backend.server.IncrementalDataModelServiceImpl
>09:56:17,027 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.inbox.backend.server.InboxServiceImpl
>09:56:17,051 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.dtable.backend.server.GuidedDecisionTableEditorServiceImpl
>09:56:17,066 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.scorecard.backend.server.GuidedScoreCardEditorServiceImpl
>09:56:17,112 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.config.ResourceConfigServiceImpl
>09:56:17,119 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.version.VersionServiceImpl
>09:56:17,132 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service class org.guvnor.common.services.backend.file.RenameServiceImpl
>09:56:17,137 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.metadata.MetadataServiceImpl
>09:56:17,140 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service class org.guvnor.common.services.backend.file.CopyServiceImpl
>09:56:17,142 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.config.AppConfigServiceImpl
>09:56:17,149 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service class org.guvnor.common.services.backend.file.DeleteServiceImpl
>09:56:17,161 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.metadata.CategoryServiceImpl
>09:56:17,200 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.datamodeller.backend.server.DataModelerServiceImpl
>09:56:17,240 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.defaulteditor.backend.server.DefaultEditorServiceImpl
>09:56:17,285 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.dtablexls.backend.server.DecisionTableXLSServiceImpl
>09:56:17,393 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.rule.backend.server.GuidedRuleEditorServiceImpl
>09:56:17,405 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.rule.backend.server.EnumDropdownServiceImpl
>09:56:17,508 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.explorer.backend.server.ExplorerServiceImpl
>09:56:17,885 INFO  [org.jboss.errai.common.metadata.MetaDataScanner] (Thread-96) added class scanning extensions: org.jboss.errai.common.metadata.JbossVFsTypeHandler
>09:56:18,863 INFO  [org.jboss.errai.reflections.Reflections] (Thread-96) Reflections took 975 ms to scan 95 urls, producing 2714 keys and 16640 values [using 2 cores]
>09:56:19,421 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-14) discovered errai service: public@Service @ApplicationScoped class org.guvnor.m2repo.backend.server.M2RepoServiceImpl
>09:56:19,547 INFO  [org.drools.compiler.kie.builder.impl.ClasspathKieProject] (MSC service thread 1-14) Found kmodule: vfs:/content/business-central.war/WEB-INF/lib/drools-wb-rest-defaultapprover-6.0.3-redhat-1.jar/META-INF/kmodule.xml
>09:56:19,548 INFO  [org.drools.compiler.kie.builder.impl.ClasspathKieProject] (MSC service thread 1-14) Virtual file physical path = /mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/tmp/vfs/deployment230c79f908e83d71/drools-wb-rest-defaultapprover-6.0.3-redhat-1.jar-bec3f8dc6bb7bf2c/drools-wb-rest-defaultapprover-6.0.3-redhat-1.jar
>09:56:19,591 INFO  [org.drools.compiler.kie.builder.impl.KieRepositoryImpl] (MSC service thread 1-14) KieModule was added:ZipKieModule[ ReleaseId=org.drools:drools-wb-rest-defaultapprover:6.0.3-redhat-1file=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/domain/servers/server-one/tmp/vfs/deployment230c79f908e83d71/drools-wb-rest-defaultapprover-6.0.3-redhat-1.jar-bec3f8dc6bb7bf2c/drools-wb-rest-defaultapprover-6.0.3-redhat-1.jar]
>09:56:21,465 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-14) WELD-001440 Scope type @javax.enterprise.context.RequestScoped() used on injection point [field] @Inject @Named @RequestScoped private org.jbpm.console.ng.bd.backend.server.DeploymentManagerEntryPointImpl.deploymentUnits
>09:56:21,503 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-14) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.uberfire.backend.server.UserServicesImpl.identity
>09:56:21,519 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-14) WELD-001440 Scope type @javax.enterprise.context.RequestScoped() used on injection point [field] @Inject @RequestScoped private org.jbpm.designer.repository.vfs.VFSRepository.descriptor
>09:56:21,558 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-14) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.guvnor.inbox.backend.server.InboxServiceImpl.identity
>09:56:21,633 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-14) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.kie.workbench.common.screens.explorer.backend.server.ExplorerServiceImpl.identity
>09:56:21,633 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-14) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.kie.workbench.common.screens.explorer.backend.server.ExplorerServiceImpl.sessionInfo
>09:56:21,717 INFO  [org.apache.helix.controller.GenericHelixController] (MSC service thread 1-14) createDefaultRegistry
>09:56:21,730 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-14) Create a zk-based cluster manager. zkSvr: localhost:2181, clusterName: bpms-cluster, instanceName: nodeOne_12345, type: PARTICIPANT
>09:56:21,732 INFO  [org.apache.helix.HelixManagerProperties] (MSC service thread 1-14) load helix-manager properties: {minimum_supported_version.batch_message=0.6.1, clustermanager.version=0.6.2-incubating, minimum_supported_version.participant=0.4}
>09:56:21,741 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-14) Added msg-factory for type: TASK_REPLY, threadpool size 40
>09:56:21,775 INFO  [org.apache.sshd.common.util.SecurityUtils] (MSC service thread 1-14) BouncyCastle not registered, using the default JCE provider
>09:56:22,350 INFO  [org.guvnor.m2repo.backend.server.GuvnorM2Repository] (MSC service thread 1-14) Maven Repository root set to: repositories/kie
>09:56:22,350 INFO  [org.guvnor.m2repo.backend.server.GuvnorM2Repository] (MSC service thread 1-14) Creating Maven Repository root: repositories/kie
>09:56:22,521 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'type' to 'kjar'.
>09:56:22,521 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'drools.dateformat' to 'dd-MMM-yyyy'.
>09:56:22,521 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'drools.defaultlanguage' to 'en'.
>09:56:22,521 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'rule-modeller-onlyShowDSLStatements' to 'false'.
>09:56:22,521 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'designer.context' to 'designer'.
>09:56:22,521 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'drools.defaultcountry' to 'US'.
>09:56:22,521 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'drools.datetimeformat' to 'dd-MMM-yyyy hh:mm:ss'.
>09:56:22,521 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'build.enable-incremental' to 'true'.
>09:56:22,522 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-14) Setting preference 'designer.profile' to 'jbpm'.
>09:56:22,528 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-14) ClusterManager.connect()
>09:56:22,540 INFO  [org.I0Itec.zkclient.ZkEventThread] (ZkClient-EventThread-200-localhost:2181) Starting ZkClient event thread.
>09:56:22,545 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:zookeeper.version=3.3.3-1203054, built on 11/17/2011 05:47 GMT
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:host.name=brmsperf05.mw.lab.eng.bos.redhat.com
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:java.version=1.7.0_51
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:java.vendor=Oracle Corporation
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:java.home=/qa/tools/opt/x86_64/jdk1.7.0_51/jre
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:java.class.path=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1/jboss-modules.jar
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:java.library.path=/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/amd64/server:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/lib/amd64:/qa/tools/opt/x86_64/jdk1.7.0_51/jre/../lib/amd64:/qa/tools/opt/x86_64/jdk1.6.0_45/jre/lib/amd64/server:/qa/tools/opt/x86_64/jdk1.6.0_45/jre/lib/amd64:/qa/tools/opt/x86_64/jdk1.6.0_45/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:java.io.tmpdir=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/tmp
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:java.compiler=<NA>
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:os.name=Linux
>09:56:22,546 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:os.arch=amd64
>09:56:22,547 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:os.version=2.6.32-358.23.2.el6.x86_64
>09:56:22,547 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:user.name=hudson
>09:56:22,547 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:user.home=/home/hudson
>09:56:22,547 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Client environment:user.dir=/mnt/hudson_workspace/workspace/bpms-clustering-smoke/jboss-eap-6.1
>09:56:22,548 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-14) Initiating client connection, connectString=localhost:2181 sessionTimeout=30000 watcher=org.apache.helix.manager.zk.ZkClient@58053c9d
>09:56:22,559 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-14-SendThread()) Opening socket connection to server localhost/127.0.0.1:2181
>09:56:22,560 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-14-SendThread(localhost:2181)) Socket connection established to localhost/127.0.0.1:2181, initiating session
>09:56:22,573 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-14-SendThread(localhost:2181)) Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x145992ad1fb0006, negotiated timeout = 30000
>09:56:22,574 INFO  [org.I0Itec.zkclient.ZkClient] (MSC service thread 1-14-EventThread) zookeeper state changed (SyncConnected)
>09:56:22,584 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-14) KeeperState: SyncConnected, zookeeper:State:CONNECTED Timeout:30000 sessionid:0x145992ad1fb0006 local:/127.0.0.1:34258 remoteserver:localhost/127.0.0.1:2181 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0
>09:56:22,584 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-14) Handling new session, session id: 145992ad1fb0006, instance: nodeOne_12345, instanceTye: PARTICIPANT, cluster: bpms-cluster, zkconnection: State:CONNECTED Timeout:30000 sessionid:0x145992ad1fb0006 local:/127.0.0.1:34258 remoteserver:localhost/127.0.0.1:2181 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0
>09:56:22,584 WARN  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-14) ParticipantHealthReportTimerTask already stopped
>09:56:22,646 INFO  [org.apache.helix.manager.zk.ParticipantManagerHelper] (MSC service thread 1-14) instance: nodeOne_12345 auto-joining bpms-cluster is false
>09:56:22,669 INFO  [org.apache.helix.messaging.DefaultMessagingService] (MSC service thread 1-14) registering msg factory for type STATE_TRANSITION
>09:56:22,685 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-14) Added msg-factory for type: STATE_TRANSITION, threadpool size 40
>09:56:22,740 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-14) 24 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,741 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-14) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,751 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-14) Dropping NO-OP message. mid: 49921778-9ded-46fa-82f1-3035b5077c35, from: nodeOne_12345
>09:56:22,758 WARN  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-14) Fail to create msg-handler because cannot find stateModelFactory for model: LeaderStandby using factoryName: DEFAULT for resource: vfs-repo
>09:56:22,758 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-14) 24 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 18ms
>09:56:22,758 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-14) Added listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787 for type: MESSAGES to path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES
>09:56:22,758 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-14) 24 START:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor
>09:56:22,758 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-14) nodeOne_12345 subscribes child-change. path: /bpms-cluster/CONTROLLER, listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@282084c4
>09:56:22,760 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-14) Controller has changed
>09:56:22,765 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-14) _zkPropertyTransferSvcUrl : null Controller brmsperf05.mw.lab.eng.bos.redhat.com-CONTROLLER
>09:56:22,765 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-14) 24 END:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor Took: 7ms
>09:56:22,765 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-14) Added listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@282084c4 for type: CONTROLLER to path: /bpms-cluster/CONTROLLER
>09:56:22,765 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,766 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,767 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-14) Register state model factory for state model SchedulerTaskQueue using factory name DEFAULT with org.apache.helix.participant.statemachine.ScheduledTaskStateModelFactory@18d76539
>09:56:22,780 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-14) Send NO_OP message to nodeOne_12345, msgId: 7934973e-ea52-4c80-92fe-3930b41f711c
>09:56:22,781 WARN  [org.apache.helix.participant.HelixStateMachineEngine] (ZkClient-EventThread-200-localhost:2181) Fail to create msg-handler because cannot find stateModelFactory for model: LeaderStandby using factoryName: DEFAULT for resource: vfs-repo
>09:56:22,782 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 17ms
>09:56:22,786 INFO  [org.apache.helix.manager.zk.ParticipantManagerHelper] (MSC service thread 1-14) Created healthcheck info path /bpms-cluster/INSTANCES/nodeOne_12345/HEALTHREPORT
>09:56:22,786 INFO  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-14) Start HealthCheckInfoReportingTask
>09:56:22,787 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,787 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,790 WARN  [org.apache.helix.participant.HelixStateMachineEngine] (ZkClient-EventThread-200-localhost:2181) Fail to create msg-handler because cannot find stateModelFactory for model: LeaderStandby using factoryName: DEFAULT for resource: vfs-repo
>09:56:22,790 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Dropping NO-OP message. mid: 7934973e-ea52-4c80-92fe-3930b41f711c, from: nodeOne_12345
>09:56:22,798 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms
>09:56:22,798 WARN  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-14) Skip processing callbacks for listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787, path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, expected types: [CALLBACK, FINALIZE] but was INIT
>09:56:22,798 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-14) init handler: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,798 WARN  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-14) Skip processing callbacks for listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@282084c4, path: /bpms-cluster/CONTROLLER, expected types: [CALLBACK, FINALIZE] but was INIT
>09:56:22,798 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-14) init handler: /bpms-cluster/CONTROLLER, org.apache.helix.manager.zk.ZKHelixDataAccessor@282084c4
>09:56:22,799 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,799 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,799 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-14) Register state model factory for state model LeaderStandby using factory name DEFAULT with org.uberfire.io.impl.cluster.helix.LockTransitionalFactory@3149e47d
>09:56:22,822 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-14) Send NO_OP message to nodeOne_12345, msgId: 785ffad7-5854-4644-9e07-439127ca9ac1
>09:56:22,824 INFO  [org.apache.helix.messaging.DefaultMessagingService] (MSC service thread 1-14) registering msg factory for type USER_DEFINE_MSG
>09:56:22,835 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Dropping NO-OP message. mid: 785ffad7-5854-4644-9e07-439127ca9ac1, from: nodeOne_12345
>09:56:22,847 WARN  [org.apache.helix.manager.zk.ZkAsyncCallbacks] (MSC service thread 1-14-EventThread) org.apache.helix.manager.zk.ZkAsyncCallbacks$CreateCallbackHandler@1807a634, rc:NONODE, path: /bpms-cluster/INSTANCES/nodeOne_12345/CURRENTSTATES/145992ad1fb0006/vfs-repo
>09:56:22,882 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-14) Added msg-factory for type: USER_DEFINE_MSG, threadpool size 40
>09:56:22,905 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: a0222515-4b68-4ec4-a106-633e2f85da29
>09:56:22,906 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: a0222515-4b68-4ec4-a106-633e2f85da29 handling task scheduled
>09:56:22,906 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 107ms
>09:56:22,906 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) handling task: a0222515-4b68-4ec4-a106-633e2f85da29 begin, at: 1398434182906
>09:56:22,908 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,908 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,912 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Dropping NO-OP message. mid: a31dbc2d-18ee-4c7c-bf13-9de2232b139b, from: nodeOne_12345
>09:56:22,916 INFO  [org.jbpm.executor.impl.ExecutorImpl] (MSC service thread 1-14) Starting Executor Component ...
> 	 - Thread Pool Size: 1
> 	 - Interval: 3 Seconds
> 	 - Retries per Request: 3
>
>09:56:22,918 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 10ms
>09:56:22,919 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,919 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,931 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) Message a0222515-4b68-4ec4-a106-633e2f85da29 completed.
>09:56:22,933 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 14ms
>09:56:22,940 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,941 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,948 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:56:22,948 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 8ms
>09:56:22,950 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,950 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,952 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:56:22,952 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>09:56:22,961 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,961 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,971 INFO  [org.kie.services.remote.jms.RetryTrackerSingleton] (ServerService Thread Pool -- 64) JMS message retry limit set to 2
>09:56:22,972 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 008dab62-fc1b-4673-bdd2-f136d9c510ee
>09:56:22,972 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 008dab62-fc1b-4673-bdd2-f136d9c510ee handling task scheduled
>09:56:22,973 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 11ms
>09:56:22,972 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) handling task: 008dab62-fc1b-4673-bdd2-f136d9c510ee begin, at: 1398434182972
>09:56:22,981 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-1) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=OFFLINE--STANDBY
>09:56:22,988 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-1) message finished: a0222515-4b68-4ec4-a106-633e2f85da29, took 80
>09:56:22,989 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) msg: a0222515-4b68-4ec4-a106-633e2f85da29 handling task completed, results:true, at: 1398434182988, took:82
>09:56:22,990 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) Message 008dab62-fc1b-4673-bdd2-f136d9c510ee completed.
>09:56:22,997 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:56:22,997 INFO  [org.jboss.web] (ServerService Thread Pool -- 64) JBAS018210: Register web context: /business-central
>09:56:22,997 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:56:22,998 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-2) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--LEADER
>09:56:22,999 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:56:22,999 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>09:56:22,999 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-2) message finished: 008dab62-fc1b-4673-bdd2-f136d9c510ee, took 14
>09:56:22,999 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) msg: 008dab62-fc1b-4673-bdd2-f136d9c510ee handling task completed, results:true, at: 1398434182999, took:27
>09:56:23,173 INFO  [solder-servlet] (ServerService Thread Pool -- 64) Catch Integration for Servlets enabled
>09:56:23,299 INFO  [org.jboss.errai.bus.server.cluster.noop.NoopClusteringProvider] (ServerService Thread Pool -- 64) clustering support not configured.
>09:56:23,305 INFO  [org.jboss.errai.bus.server.service.bootstrap.OrderedBootstrap] (ServerService Thread Pool -- 64) starting errai bus ...
>09:56:23,307 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 64) authentication adapter configured: org.jboss.errai.bus.server.security.auth.JAASAdapter
>09:56:23,313 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 64) using dispatcher implementation: org.jboss.errai.bus.server.SimpleDispatcher
>09:56:23,315 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 64) using session provider implementation: org.jboss.errai.bus.server.HttpSessionProvider
>09:56:23,328 INFO  [org.jboss.errai.bus.server.service.bootstrap.LoadExtensions] (ServerService Thread Pool -- 64) searching for errai extensions ...
>09:56:23,340 INFO  [org.jboss.errai.bus.server.service.bootstrap.OrderedBootstrap] (ServerService Thread Pool -- 64) errai bus started.
>09:56:24,004 INFO  [org.jboss.as.server] (host-controller-connection-threads - 1) JBAS018559: Deployed "business-central.war" (runtime-name : "business-central.war")
>09:57:41,623 INFO  [org.jboss.resteasy.cdi.CdiInjectorFactory] (http-localhost/127.0.0.1:8080-1) Found BeanManager at java:comp/BeanManager
>09:57:42,013 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-1) -----createOrCloneRepository--- , repository name:bpms-assets
>09:57:42,023 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-1) CreateOrCloneRepositoryRequest event received.
>09:57:42,023 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-1) Approval request for Job: 1398434262014-1 received.
>09:57:42,881 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-1) Approval request for Job: 1398434262014-1 result: ACCEPTED
>09:57:42,881 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-1) CreateOrCloneRepositoryRequest event approved. Performing requested operation.
>09:57:42,888 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-1) -----JobRequestHelper:createOrCloneRepository--- , repository name:bpms-assets
>09:57:48,329 INFO  [org.apache.helix.messaging.AsyncCallback] (http-localhost/127.0.0.1:8080-1) Setting time out to 30000 ms
>09:57:48,330 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>09:57:48,392 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query return 2 rows
>09:57:48,395 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-1) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>09:57:48,395 INFO  [org.apache.helix.messaging.AsyncCallback] (http-localhost/127.0.0.1:8080-1) Setting time out to 30000 ms
>09:57:48,396 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (http-localhost/127.0.0.1:8080-1) registering correlation id 4fd157e8-6534-486a-992a-f7ba1b8be977
>09:57:52,982 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:52,982 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:52,986 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (ZkClient-EventThread-200-localhost:2181) Verified reply message ac14351b-5142-46b9-a305-de5a4434c3d5 correlation:4fd157e8-6534-486a-992a-f7ba1b8be977
>09:57:52,999 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: ac14351b-5142-46b9-a305-de5a4434c3d5
>09:57:53,005 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: ac14351b-5142-46b9-a305-de5a4434c3d5 handling task scheduled
>09:57:53,005 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 23ms
>09:57:53,005 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-9-thread-1) handling task: ac14351b-5142-46b9-a305-de5a4434c3d5 begin, at: 1398434273005
>09:57:53,006 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (pool-9-thread-1) Verified reply message ac14351b-5142-46b9-a305-de5a4434c3d5 correlation:4fd157e8-6534-486a-992a-f7ba1b8be977
>09:57:53,006 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (pool-9-thread-1) invoking reply message ac14351b-5142-46b9-a305-de5a4434c3d5, correlationid:4fd157e8-6534-486a-992a-f7ba1b8be977
>09:57:53,006 INFO  [org.apache.helix.messaging.AsyncCallback] (pool-9-thread-1) OnReply msg ac14351b-5142-46b9-a305-de5a4434c3d5
>09:57:53,007 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (pool-9-thread-1) Removing finished callback, correlationid:4fd157e8-6534-486a-992a-f7ba1b8be977
>09:57:53,007 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-9-thread-1) Message ac14351b-5142-46b9-a305-de5a4434c3d5 completed.
>09:57:53,017 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-9-thread-1) message finished: ac14351b-5142-46b9-a305-de5a4434c3d5, took 11
>09:57:53,017 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-9-thread-1) msg: ac14351b-5142-46b9-a305-de5a4434c3d5 handling task completed, results:true, at: 1398434273017, took:12
>09:57:53,018 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:53,018 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:53,019 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:57:53,019 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 1ms
>09:57:53,053 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:53,053 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:53,070 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 65eff92c-8c34-443a-a6f2-94072be7ea4d
>09:57:53,077 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 65eff92c-8c34-443a-a6f2-94072be7ea4d handling task scheduled
>09:57:53,078 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 25ms
>09:57:53,078 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) handling task: 65eff92c-8c34-443a-a6f2-94072be7ea4d begin, at: 1398434273078
>09:57:53,089 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) Message 65eff92c-8c34-443a-a6f2-94072be7ea4d completed.
>09:57:53,107 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-3) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=LEADER--STANDBY
>09:57:53,113 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-3) message finished: 65eff92c-8c34-443a-a6f2-94072be7ea4d, took 34
>09:57:53,114 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) msg: 65eff92c-8c34-443a-a6f2-94072be7ea4d handling task completed, results:true, at: 1398434273114, took:36
>09:57:53,166 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:53,166 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:53,168 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:57:53,168 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>09:57:53,425 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:53,425 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:53,466 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 3255a9dc-8c95-4482-bb34-42c2252d604f
>09:57:53,466 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 3255a9dc-8c95-4482-bb34-42c2252d604f handling task scheduled
>09:57:53,467 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 42ms
>09:57:53,467 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) handling task: 3255a9dc-8c95-4482-bb34-42c2252d604f begin, at: 1398434273467
>09:57:53,490 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>09:57:53,502 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) Message 3255a9dc-8c95-4482-bb34-42c2252d604f completed.
>09:57:53,538 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-4) message finished: 3255a9dc-8c95-4482-bb34-42c2252d604f, took 70
>09:57:53,538 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) msg: 3255a9dc-8c95-4482-bb34-42c2252d604f handling task completed, results:true, at: 1398434273538, took:71
>09:57:53,539 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:53,539 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:53,540 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:57:53,540 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 1ms
>09:57:53,549 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query return 2 rows
>09:57:53,556 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-1) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>09:57:53,629 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>09:57:53,650 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query return 2 rows
>09:57:53,660 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-1) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>09:57:53,778 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:53,778 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:53,822 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: d92de3e7-f64d-4240-a08f-267e32e54d07
>09:57:53,823 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: d92de3e7-f64d-4240-a08f-267e32e54d07 handling task scheduled
>09:57:53,823 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 45ms
>09:57:53,823 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) handling task: d92de3e7-f64d-4240-a08f-267e32e54d07 begin, at: 1398434273823
>09:57:53,855 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) Message d92de3e7-f64d-4240-a08f-267e32e54d07 completed.
>09:57:53,943 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-2) -----getJobStatus--- , jobId:1398434262014-1
>09:57:53,958 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-5) message finished: d92de3e7-f64d-4240-a08f-267e32e54d07, took 134
>09:57:53,958 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) msg: d92de3e7-f64d-4240-a08f-267e32e54d07 handling task completed, results:true, at: 1398434273958, took:135
>09:57:53,960 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:53,960 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:53,974 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:57:53,980 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 20ms
>09:57:54,023 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:54,023 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:54,058 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 543959f1-0ef8-491b-a06c-41676dd33f52
>09:57:54,059 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 543959f1-0ef8-491b-a06c-41676dd33f52 handling task scheduled
>09:57:54,059 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 36ms
>09:57:54,059 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) handling task: 543959f1-0ef8-491b-a06c-41676dd33f52 begin, at: 1398434274059
>09:57:54,106 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) Message 543959f1-0ef8-491b-a06c-41676dd33f52 completed.
>09:57:54,124 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-6) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--OFFLINE
>09:57:54,124 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-6) message finished: 543959f1-0ef8-491b-a06c-41676dd33f52, took 64
>09:57:54,124 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) msg: 543959f1-0ef8-491b-a06c-41676dd33f52 handling task completed, results:true, at: 1398434274124, took:65
>09:57:54,125 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:57:54,125 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:57:54,127 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:57:54,127 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>09:58:14,012 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-3) -----createOrganizationalUnit--- , OrganizationalUnit name:bpms, OrganizationalUnit owner:testadmin
>09:58:14,013 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-3) CreateOrganizationalUnitRequest event received.
>09:58:14,013 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-3) Approval request for Job: 1398434294012-2 received.
>09:58:14,017 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-3) Approval request for Job: 1398434294012-2 result: ACCEPTED
>09:58:14,017 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-3) CreateOrganizationalUnitRequest event approved. Performing requested operation.
>09:58:14,017 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-3) -----JobRequestHelper:createOrganizationalUnit--- , OrganizationalUnit name:bpms, OrganizationalUnit owner:testadmin
>09:58:14,073 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:14,073 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:14,089 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 3915ae21-d789-4508-aaf2-e7ebffbff68e
>09:58:14,090 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 3915ae21-d789-4508-aaf2-e7ebffbff68e handling task scheduled
>09:58:14,090 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 18ms
>09:58:14,090 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) handling task: 3915ae21-d789-4508-aaf2-e7ebffbff68e begin, at: 1398434294090
>09:58:14,127 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) Message 3915ae21-d789-4508-aaf2-e7ebffbff68e completed.
>09:58:14,174 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-7) message finished: 3915ae21-d789-4508-aaf2-e7ebffbff68e, took 83
>09:58:14,174 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) msg: 3915ae21-d789-4508-aaf2-e7ebffbff68e handling task completed, results:true, at: 1398434294174, took:84
>09:58:14,175 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:14,175 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:14,177 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:58:14,177 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>09:58:14,228 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:14,228 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:14,257 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 3f0aea16-ce2c-4599-8e32-a71e44438505
>09:58:14,257 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 3f0aea16-ce2c-4599-8e32-a71e44438505 handling task scheduled
>09:58:14,258 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 30ms
>09:58:14,258 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) handling task: 3f0aea16-ce2c-4599-8e32-a71e44438505 begin, at: 1398434294258
>09:58:14,278 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-3) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>09:58:14,287 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) Message 3f0aea16-ce2c-4599-8e32-a71e44438505 completed.
>09:58:14,311 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-8) message finished: 3f0aea16-ce2c-4599-8e32-a71e44438505, took 53
>09:58:14,312 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:14,321 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) msg: 3f0aea16-ce2c-4599-8e32-a71e44438505 handling task completed, results:true, at: 1398434294321, took:63
>09:58:14,321 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:14,322 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-3) JOSQL query return 2 rows
>09:58:14,336 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:58:14,336 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 24ms
>09:58:14,336 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-3) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>09:58:14,419 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-3) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>09:58:14,444 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-3) JOSQL query return 2 rows
>09:58:14,445 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-3) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>09:58:14,635 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:14,635 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:14,705 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 87385b95-0b84-4865-b0b9-146ce9928272
>09:58:14,705 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 87385b95-0b84-4865-b0b9-146ce9928272 handling task scheduled
>09:58:14,705 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 70ms
>09:58:14,705 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) handling task: 87385b95-0b84-4865-b0b9-146ce9928272 begin, at: 1398434294705
>09:58:14,737 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-4) -----getJobStatus--- , jobId:1398434294012-2
>09:58:14,742 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) Message 87385b95-0b84-4865-b0b9-146ce9928272 completed.
>09:58:14,778 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-9) message finished: 87385b95-0b84-4865-b0b9-146ce9928272, took 72
>09:58:14,778 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) msg: 87385b95-0b84-4865-b0b9-146ce9928272 handling task completed, results:true, at: 1398434294778, took:73
>09:58:14,778 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:14,778 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:14,780 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:58:14,780 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>09:58:14,820 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:14,820 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:14,855 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 91092cdf-26ee-4dd5-b01f-704b2d21a3e6
>09:58:14,856 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 91092cdf-26ee-4dd5-b01f-704b2d21a3e6 handling task scheduled
>09:58:14,856 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 36ms
>09:58:14,856 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) handling task: 91092cdf-26ee-4dd5-b01f-704b2d21a3e6 begin, at: 1398434294856
>09:58:14,891 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) Message 91092cdf-26ee-4dd5-b01f-704b2d21a3e6 completed.
>09:58:14,922 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-10) message finished: 91092cdf-26ee-4dd5-b01f-704b2d21a3e6, took 65
>09:58:14,923 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) msg: 91092cdf-26ee-4dd5-b01f-704b2d21a3e6 handling task completed, results:true, at: 1398434294923, took:67
>09:58:14,923 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:14,924 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:14,925 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:58:14,925 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>09:58:34,752 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-5) -----compileProject--- , repositoryName:bpms-assets, project name:clustering
>09:58:34,754 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-5) CompileProjectRequest event received.
>09:58:34,754 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-5) Approval request for Job: 1398434314753-3 received.
>09:58:34,755 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-5) Approval request for Job: 1398434314753-3 result: ACCEPTED
>09:58:34,755 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-5) CompileProjectRequest event approved. Performing requested operation.
>09:58:34,755 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-5) -----JobRequestHelper:compileProject--- , repositoryName:bpms-assets, project name:clustering
>09:58:36,012 ERROR [stderr] (http-localhost/127.0.0.1:8080-5) line 1:57 no viable alternative at character '\'
>09:58:36,373 INFO  [org.drools.compiler.kie.builder.impl.KieRepositoryImpl] (http-localhost/127.0.0.1:8080-5) KieModule was added:MemoryKieModule[ ReleaseId=org.jboss.qa.bpms:clustering:1.0.0.Final]
>09:58:36,610 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-6) -----getJobStatus--- , jobId:1398434314753-3
>09:58:36,641 INFO  [org.kie.services.remote.rest.async.AsyncDeploymentJobExecutor] (http-localhost/127.0.0.1:8080-7) Deployment executing as async jobs
>09:58:36,642 INFO  [org.kie.services.remote.rest.async.AsyncDeploymentJobExecutor] (http-localhost/127.0.0.1:8080-7) DEPLOY job for [org.jboss.qa.bpms:clustering:1.0.0.Final] submitted succesfully
>09:58:36,776 INFO  [stdout] (pool-22-thread-1)  wagon http use multi threaded http connection manager maxPerRoute 20, max total 40
>09:58:37,283 ERROR [stderr] (pool-22-thread-1) line 1:57 no viable alternative at character '\'
>09:58:37,551 INFO  [org.quartz.core.SchedulerSignalerImpl] (pool-22-thread-1) Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
>09:58:37,552 INFO  [org.quartz.core.QuartzScheduler] (pool-22-thread-1) Quartz Scheduler v.1.8.5 created.
>09:58:37,553 INFO  [org.quartz.impl.jdbcjobstore.JobStoreCMT] (pool-22-thread-1) Using db table-based data access locking (synchronization).
>09:58:37,555 INFO  [org.quartz.impl.jdbcjobstore.JobStoreCMT] (pool-22-thread-1) JobStoreCMT initialized.
>09:58:37,556 INFO  [org.quartz.core.QuartzScheduler] (pool-22-thread-1) Scheduler meta-data: Quartz Scheduler (v1.8.5) 'jBPMClusteredScheduler' with instanceId 'brmsperf05.mw.lab.eng.bos.redhat.com1398434317535'
>  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
>  NOT STARTED.
>  Currently in standby mode.
>  Number of jobs executed: 0
>  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 5 threads.
>  Using job-store 'org.quartz.impl.jdbcjobstore.JobStoreCMT' - which supports persistence. and is clustered.
>
>09:58:37,556 INFO  [org.quartz.impl.StdSchedulerFactory] (pool-22-thread-1) Quartz scheduler 'jBPMClusteredScheduler' initialized from specified file: '/mnt/hudson_workspace/workspace/bpms-clustering-smoke/quartz.properties'
>09:58:37,556 INFO  [org.quartz.impl.StdSchedulerFactory] (pool-22-thread-1) Quartz scheduler version: 1.8.5
>09:58:37,599 INFO  [org.quartz.core.QuartzScheduler] (pool-22-thread-1) Scheduler jBPMClusteredScheduler_$_brmsperf05.mw.lab.eng.bos.redhat.com1398434317535 started.
>09:58:38,117 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:38,117 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:38,142 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: f40bd341-ccc6-4c9a-b1e9-1e545447c601
>09:58:38,142 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: f40bd341-ccc6-4c9a-b1e9-1e545447c601 handling task scheduled
>09:58:38,142 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 25ms
>09:58:38,142 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) handling task: f40bd341-ccc6-4c9a-b1e9-1e545447c601 begin, at: 1398434318142
>09:58:38,154 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) Message f40bd341-ccc6-4c9a-b1e9-1e545447c601 completed.
>09:58:38,207 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-11) message finished: f40bd341-ccc6-4c9a-b1e9-1e545447c601, took 64
>09:58:38,207 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) msg: f40bd341-ccc6-4c9a-b1e9-1e545447c601 handling task completed, results:true, at: 1398434318207, took:65
>09:58:38,208 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:38,208 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:38,210 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:58:38,210 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>09:58:38,262 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:38,262 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:38,279 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: a5464d48-798b-4f39-906d-9da282bf3c7a
>09:58:38,328 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: a5464d48-798b-4f39-906d-9da282bf3c7a handling task scheduled
>09:58:38,329 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 67ms
>09:58:38,329 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) handling task: a5464d48-798b-4f39-906d-9da282bf3c7a begin, at: 1398434318328
>09:58:38,362 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-22-thread-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>09:58:38,364 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) Message a5464d48-798b-4f39-906d-9da282bf3c7a completed.
>09:58:38,381 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-12) message finished: a5464d48-798b-4f39-906d-9da282bf3c7a, took 51
>09:58:38,392 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-22-thread-1) JOSQL query return 2 rows
>09:58:38,382 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:38,403 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) msg: a5464d48-798b-4f39-906d-9da282bf3c7a handling task completed, results:true, at: 1398434318403, took:75
>09:58:38,403 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:38,405 INFO  [org.apache.helix.messaging.DefaultMessagingService] (pool-22-thread-1) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>09:58:38,405 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:58:38,405 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 23ms
>09:58:38,537 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-22-thread-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>09:58:38,549 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-22-thread-1) JOSQL query return 2 rows
>09:58:38,575 INFO  [org.apache.helix.messaging.DefaultMessagingService] (pool-22-thread-1) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>09:58:38,818 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:38,818 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:38,883 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: 727a06b8-8137-45fc-89e9-67ece8c35cd6
>09:58:38,884 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: 727a06b8-8137-45fc-89e9-67ece8c35cd6 handling task scheduled
>09:58:38,884 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 66ms
>09:58:38,884 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) handling task: 727a06b8-8137-45fc-89e9-67ece8c35cd6 begin, at: 1398434318884
>09:58:38,896 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) Message 727a06b8-8137-45fc-89e9-67ece8c35cd6 completed.
>09:58:38,937 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-13) message finished: 727a06b8-8137-45fc-89e9-67ece8c35cd6, took 52
>09:58:38,938 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:38,939 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) msg: 727a06b8-8137-45fc-89e9-67ece8c35cd6 handling task completed, results:true, at: 1398434318939, took:55
>09:58:38,939 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:38,941 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:58:38,941 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>09:58:39,010 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:39,010 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:39,051 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Scheduling message: d7d999b5-c5c2-4aba-b212-21ed9225c0a2
>09:58:39,052 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) Message: d7d999b5-c5c2-4aba-b212-21ed9225c0a2 handling task scheduled
>09:58:39,052 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 42ms
>09:58:39,052 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) handling task: d7d999b5-c5c2-4aba-b212-21ed9225c0a2 begin, at: 1398434319052
>09:58:39,093 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) Message d7d999b5-c5c2-4aba-b212-21ed9225c0a2 completed.
>09:58:39,123 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-14) message finished: d7d999b5-c5c2-4aba-b212-21ed9225c0a2, took 70
>09:58:39,123 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) msg: d7d999b5-c5c2-4aba-b212-21ed9225c0a2 handling task completed, results:true, at: 1398434319123, took:71
>09:58:39,165 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>09:58:39,166 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>09:58:39,184 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-200-localhost:2181) No Messages to process
>09:58:39,189 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-200-localhost:2181) 200 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 24ms
>10:00:14,950 INFO  [org.apache.coyote.http11] (MSC service thread 1-11) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost/127.0.0.1:8080
>10:00:14,951 INFO  [org.apache.coyote.http11] (MSC service thread 1-11) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost/127.0.0.1:8080
>10:00:14,955 INFO  [org.jboss.jaxr] (MSC service thread 1-10) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory
>10:00:14,955 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 85) MODCLUSTER000002: Initiating mod_cluster shutdown
>10:00:14,956 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) JBAS010409: Unbound data source [java:jboss/datasources/ExampleDS]
>10:00:14,959 INFO  [org.apache.catalina.core] (MSC service thread 1-4) JBWEB001079: Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started
>10:00:14,961 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-6) JBAS010409: Unbound data source [java:jboss/datasources/brms-jdbc-dsQuartz]
>10:00:14,965 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 83) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/queue/KIE.AUDIT
>10:00:14,964 INFO  [org.jboss.web] (ServerService Thread Pool -- 89) JBAS018224: Unregister web context: /business-central
>10:00:14,965 INFO  [org.apache.coyote.ajp] (MSC service thread 1-16) JBWEB003048: Pausing Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8009
>10:00:14,966 INFO  [org.apache.coyote.ajp] (MSC service thread 1-16) JBWEB003051: Stopping Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8009
>10:00:15,089 INFO  [org.jboss.as.jpa] (ServerService Thread Pool -- 85) JBAS011403: Stopping Persistence Unit Service 'business-central.war#org.jbpm.domain'
>10:00:15,093 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-9) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA]
>10:00:15,093 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-11) JBAS010409: Unbound data source [java:jboss/datasources/brms-jdbc-ds]
>10:00:15,096 INFO  [org.jboss.weld.deployer] (MSC service thread 1-9) JBAS016009: Stopping weld service for deployment business-central.war
>10:00:15,099 INFO  [org.hornetq.ra] (MSC service thread 1-13) HQ151003: HornetQ resource adaptor stopped
>10:00:15,100 WARN  [org.hornetq.core.client] (Thread-6 (HornetQ-client-global-threads-1545553401)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
>10:00:15,101 WARN  [org.hornetq.core.client] (Thread-8 (HornetQ-client-global-threads-1545553401)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
>10:00:15,102 WARN  [org.hornetq.core.server] (Thread-8 (HornetQ-client-global-threads-1545553401)) HQ222095: Connection failed with failedOver=false: HornetQException[errorType=DISCONNECTED message=HQ119015: The connection was disconnected because of server shutdown]
>	at org.hornetq.core.client.impl.ClientSessionFactoryImpl$CloseRunnable.run(ClientSessionFactoryImpl.java:1631) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:106) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51]
>
>10:00:15,117 INFO  [org.hornetq.core.server] (Thread-26 (HornetQ-server-HornetQServerImpl::serverUUID=37d218fc-cc81-11e3-ac85-ebcda1137edb-791162793)) HQ221029: stopped bridge sf.my-cluster.38d04edd-cc81-11e3-bde4-69d5bf946063
>10:00:15,129 INFO  [org.jboss.errai.cdi.server.events.ShutdownEventObserver] (MSC service thread 1-9) Shutting down CDI-to-ErraiBus event bridge
>10:00:15,143 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-9) disconnect nodeOne_12345(PARTICIPANT) from bpms-cluster
>10:00:15,143 INFO  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-9) Stop ParticipantHealthReportTimerTask
>10:00:15,143 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) shutting down TaskExecutor
>10:00:15,143 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) 0 tasks are still in the threadpool for msgType USER_DEFINE_MSG
>10:00:15,144 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) 0 tasks are still in the threadpool for msgType STATE_TRANSITION
>10:00:15,145 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) 0 tasks are still in the threadpool for msgType TASK_REPLY
>10:00:15,145 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-9) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--OFFLINE
>10:00:15,145 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-9) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=OFFLINE--STANDBY
>10:00:15,146 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-9) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=LEADER--STANDBY
>10:00:15,146 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-9) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--LEADER
>10:00:15,146 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) shutdown finished
>10:00:15,146 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-9) 19 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:00:15,146 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-9) nodeOne_12345 unsubscribe child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>10:00:15,147 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) Get FINALIZE notification
>10:00:15,147 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) 0 tasks never executed for msgType: USER_DEFINE_MSG. tasks: []
>10:00:15,147 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) 0 tasks never executed for msgType: STATE_TRANSITION. tasks: []
>10:00:15,147 WARN  [org.apache.helix.participant.statemachine.StateModel] (MSC service thread 1-9) Default reset method invoked. Either because the process longer own this resource or session timedout
>10:00:15,147 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-9) 0 tasks never executed for msgType: TASK_REPLY. tasks: []
>10:00:15,148 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-9) 19 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 1ms
>10:00:15,148 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-9) reset handler: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, org.apache.helix.messaging.handling.HelixTaskExecutor@63a01787
>10:00:15,148 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-9) 19 START:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor
>10:00:15,148 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-9) nodeOne_12345 unsubscribe child-change. path: /bpms-cluster/CONTROLLER, listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@282084c4
>10:00:15,148 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-9) Controller has changed
>10:00:15,150 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-9) _zkPropertyTransferSvcUrl : null Controller brmsperf05.mw.lab.eng.bos.redhat.com-CONTROLLER
>10:00:15,150 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-9) 19 END:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor Took: 2ms
>10:00:15,150 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-9) reset handler: /bpms-cluster/CONTROLLER, org.apache.helix.manager.zk.ZKHelixDataAccessor@282084c4
>10:00:15,151 INFO  [org.apache.helix.manager.zk.ZkClient] (MSC service thread 1-9) Closing zkclient: State:CONNECTED Timeout:30000 sessionid:0x145992ad1fb0006 local:/127.0.0.1:34258 remoteserver:localhost/127.0.0.1:2181 lastZxid:301 xid:608 sent:627 recv:662 queuedpkts:0 pendingresp:0 queuedevents:0
>10:00:15,151 INFO  [org.I0Itec.zkclient.ZkEventThread] (ZkClient-EventThread-200-localhost:2181) Terminate ZkClient event thread.
>10:00:15,175 INFO  [org.hornetq.core.server] (MSC service thread 1-8) HQ221002: HornetQ Server version 2.3.5.Final-redhat-2 (Monster Bee, 123) [37d218fc-cc81-11e3-ac85-ebcda1137edb] stopped
>10:00:15,181 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-14-EventThread) EventThread shut down
>10:00:15,182 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-9) Session: 0x145992ad1fb0006 closed
>10:00:15,182 INFO  [org.apache.helix.manager.zk.ZkClient] (MSC service thread 1-9) Closed zkclient
>10:00:15,182 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-9) Cluster manager: nodeOne_12345 disconnected
>10:00:15,187 INFO  [org.jbpm.executor.impl.ExecutorImpl] (MSC service thread 1-9)  >>>>> Destroying Executor !!!
>10:00:15,468 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-12) JBAS015877: Stopped deployment business-central.war (runtime-name: business-central.war) in 530ms
>10:00:15,480 INFO  [org.jboss.as] (MSC service thread 1-4) JBAS015950: JBoss BPM Suite 6.0.2.GA (AS 7.2.1.Final-redhat-10) stopped in 535ms

Actions: View

Attachments on bug 1091458: 889817 | 889818 | 889819