Attachment 868355 Details for Bug 1070607 – server log - node two

server log - node two

serverTwo.log (text/x-log), 163.72 KB, created by Radovan Synek on 2014-02-27 08:46:54 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Radovan Synek

Created: 2014-02-27 08:46:54 UTC

Size: 163.72 KB

patch

obsolete

>10:29:17,795 INFO  [org.jboss.modules] (main) JBoss Modules version 1.2.2.Final-redhat-1
>10:29:18,216 INFO  [org.jboss.msc] (main) JBoss MSC version 1.0.4.GA-redhat-1
>10:29:18,337 INFO  [org.jboss.as] (MSC service thread 1-6) JBAS015899: JBoss BPM Suite 6.0.1.GA (AS 7.2.1.Final-redhat-10) starting
>10:29:18,349 DEBUG [org.jboss.as.config] (MSC service thread 1-6) Configured system properties:
>	[Host Controller] = true
>	[Server:server-two] = 
>	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-single/jboss-eap-6.1/jboss-modules.jar
>	java.class.version = 51.0
>	java.endorsed.dirs = /qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/endorsed
>	java.ext.dirs = /qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/ext:/usr/java/packages/lib/ext
>	java.home = /qa/tools/opt/x86_64/jdk1.7.0_45/jre
>	java.io.tmpdir = /tmp
>	java.library.path = /qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/amd64/server:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/amd64:/qa/tools/opt/x86_64/jdk1.7.0_45/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_45-b18
>	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_45
>	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.45-b08
>	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-single/jboss-eap-6.1/domain
>	jboss.domain.config.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/configuration
>	jboss.home.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1
>	jboss.host.name = dev109
>	jboss.modules.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/modules
>	jboss.node.name = master:server-two
>	jboss.qualified.host.name = dev109
>	jboss.server.base.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two
>	jboss.server.config.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/configuration
>	jboss.server.data.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/data
>	jboss.server.deploy.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/data/content
>	jboss.server.log.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/log
>	jboss.server.name = server-two
>	jboss.server.persist.config = true
>	jboss.server.temp.dir = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/tmp
>	line.separator = 
>
>	logging.configuration = file:/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/configuration/default-server-logging.properties
>	module.path = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/modules
>	org.jboss.boot.log.file = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/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-431.1.2.el6.x86_64
>	path.separator = :
>	sun.arch.data.model = 64
>	sun.boot.class.path = /qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/resources.jar:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/rt.jar:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/sunrsasign.jar:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/jsse.jar:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/jce.jar:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/charsets.jar:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/jfr.jar:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/classes
>	sun.boot.library.path = /qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/amd64
>	sun.cpu.endian = little
>	sun.cpu.isalist = 
>	sun.io.unicode.encoding = UnicodeLittle
>	sun.java.command = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/jboss-modules.jar -mp /mnt/hudson_workspace/workspace/bpms-clustering-single/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-single/jboss-eap-6.1
>	user.home = /home/hudson
>	user.language = en
>	user.name = hudson
>	user.timezone = America/New_York
>10:29:18,360 DEBUG [org.jboss.as.config] (MSC service thread 1-6) VM Arguments: -D[Server:server-two] -XX:PermSize=256m -XX:MaxPermSize=1024m -Xms1503m -Xmx1503m -D[Host Controller]=true -Ddesignerdataobjects=${designerdataobjects:false} -Dorg.kie.example=${org.kie.example:true} -Djboss.home.dir=/mnt/hudson_workspace/workspace/bpms-clustering-single/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-single/jboss-eap-6.1/domain/servers/server-two/log -Djboss.server.temp.dir=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/tmp -Djboss.server.data.dir=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/data -Dorg.jboss.boot.log.file=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/log/server.log -Dlogging.configuration=file:/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/configuration/default-server-logging.properties 
>10:29:18,487 INFO  [org.xnio] (MSC service thread 1-8) XNIO Version 3.0.7.GA-redhat-1
>10:29:18,495 INFO  [org.xnio.nio] (MSC service thread 1-8) XNIO NIO Implementation Version 3.0.7.GA-redhat-1
>10:29:18,505 INFO  [org.jboss.remoting] (MSC service thread 1-8) JBoss Remoting version 3.2.16.GA-redhat-1
>10:29:20,007 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 37) JBAS013171: Activating Security Subsystem
>10:29:20,010 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 33) JBAS015537: Activating WebServices Extension
>10:29:20,019 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 42) JBAS011800: Activating Naming Subsystem
>10:29:20,020 INFO  [org.jboss.as.security] (MSC service thread 1-8) JBAS013170: Current PicketBox version=4.0.17.SP2-redhat-2
>10:29:20,034 INFO  [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 50) JBAS010260: Activating JGroups subsystem.
>10:29:20,037 INFO  [org.jboss.as.jacorb] (ServerService Thread Pool -- 55) JBAS016300: Activating JacORB Subsystem
>10:29:20,039 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 56) JBAS010280: Activating Infinispan subsystem.
>10:29:20,044 INFO  [org.jboss.as.jsf] (ServerService Thread Pool -- 47) JBAS012605: Activated the following JSF Implementations: [main, 1.2]
>10:29:20,044 INFO  [org.jboss.as.configadmin] (ServerService Thread Pool -- 60) JBAS016200: Activating ConfigAdmin Subsystem
>10:29:20,069 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 59) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
>10:29:20,078 INFO  [org.jboss.as.connector.logging] (MSC service thread 1-4) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.19.Final-redhat-2)
>10:29:20,089 INFO  [org.jboss.as.naming] (MSC service thread 1-5) JBAS011802: Starting Naming Service
>10:29:20,091 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-16) JBAS015400: Bound mail session [java:/mail/jbpmMailSession]
>10:29:20,095 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-7) JBAS015400: Bound mail session [java:jboss/mail/Default]
>10:29:20,123 INFO  [org.jboss.jaxr] (MSC service thread 1-6) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory
>10:29:20,126 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 59) JBAS010404: Deploying non-JDBC-compliant driver class com.mysql.jdbc.Driver (version 5.1)
>10:29:20,290 INFO  [org.jboss.ws.common.management] (MSC service thread 1-10) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.1.4.Final-redhat-7
>10:29:20,301 INFO  [org.apache.coyote.ajp] (MSC service thread 1-3) JBWEB003046: Starting Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8209
>10:29:20,301 INFO  [org.apache.coyote.http11] (MSC service thread 1-2) JBWEB003001: Coyote HTTP/1.1 initializing on : http-localhost/127.0.0.1:8280
>10:29:20,302 INFO  [org.apache.coyote.http11] (MSC service thread 1-2) JBWEB003000: Coyote HTTP/1.1 starting on: http-localhost/127.0.0.1:8280
>10:29:20,360 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000001: Initializing mod_cluster ${project.version}
>10:29:20,398 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000032: Listening to proxy advertisements on /224.0.1.105:23364
>10:29:20,430 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=true,backup=false,sharedStore=true,journalDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/data/messagingjournal,bindingsDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/data/messagingbindings,largeMessagesDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/data/messaginglargemessages,pagingDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/data/messagingpaging)
>10:29:20,432 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221006: Waiting to obtain live lock
>10:29:20,495 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221013: Using NIO Journal
>10:29:20,497 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.
>10:29:20,500 INFO  [org.jboss.as.jacorb] (MSC service thread 1-15) JBAS016330: CORBA ORB Service started
>10:29:20,505 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.
>10:29:20,617 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) JBAS010400: Bound data source [java:jboss/datasources/brms-jdbc-dsQuartz]
>10:29:20,617 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
>10:29:20,617 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) JBAS010400: Bound data source [java:jboss/datasources/brms-jdbc-ds]
>10:29:20,629 INFO  [org.jboss.as.jacorb] (MSC service thread 1-2) JBAS016328: CORBA Naming Service started
>10:29:20,664 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221034: Waiting to obtain live lock
>10:29:20,664 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221035: Live Server Obtained live lock
>10:29:20,841 INFO  [org.jboss.as.remoting] (MSC service thread 1-12) JBAS017100: Listening on 127.0.0.1:4647
>10:29:21,378 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221020: Started Netty Acceptor version 3.6.6.Final-redhat-1-fd3c6b7 127.0.0.1:5655 for CORE protocol
>10:29:21,380 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221020: Started Netty Acceptor version 3.6.6.Final-redhat-1-fd3c6b7 127.0.0.1:5645 for CORE protocol
>10:29:21,390 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221007: Server is now live
>10:29:21,390 INFO  [org.hornetq.core.server] (MSC service thread 1-6) HQ221001: HornetQ Server version 2.3.5.Final-redhat-2 (Monster Bee, 123) [c321ed64-9efa-11e3-a171-9f9238f4ec4e] 
>10:29:21,429 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory
>10:29:21,431 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 63) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
>10:29:21,508 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-7) JBAS010406: Registered connection factory java:/JmsXA
>10:29:21,537 INFO  [org.hornetq.ra] (MSC service thread 1-7) HornetQ resource adaptor started
>10:29:21,537 INFO  [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-7) IJ020002: Deployed: file://RaActivatorhornetq-ra
>10:29:21,541 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-10) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA]
>10:29:21,599 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss BPM Suite 6.0.1.GA (AS 7.2.1.Final-redhat-10) started in 4633ms - Started 158 of 279 services (118 services are passive or on-demand)
>10:29:21,645 INFO  [org.hornetq.core.server] (Thread-6 (HornetQ-server-HornetQServerImpl::serverUUID=c321ed64-9efa-11e3-a171-9f9238f4ec4e-1515590220)) HQ221027: Bridge ClusterConnectionBridge@301ac8c7 [name=sf.my-cluster.c1d3c2ec-9efa-11e3-8bf3-a59d998dfa93, queue=QueueImpl[name=sf.my-cluster.c1d3c2ec-9efa-11e3-8bf3-a59d998dfa93, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=c321ed64-9efa-11e3-a171-9f9238f4ec4e]]@2761f55e targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@301ac8c7 [name=sf.my-cluster.c1d3c2ec-9efa-11e3-8bf3-a59d998dfa93, queue=QueueImpl[name=sf.my-cluster.c1d3c2ec-9efa-11e3-8bf3-a59d998dfa93, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=c321ed64-9efa-11e3-a171-9f9238f4ec4e]]@2761f55e targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@916311140[nodeUUID=c321ed64-9efa-11e3-a171-9f9238f4ec4e, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost, address=jms, server=HornetQServerImpl::serverUUID=c321ed64-9efa-11e3-a171-9f9238f4ec4e])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost], discoveryGroupConfiguration=null]] is connected
>10:30:11,043 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-8) JBAS015876: Starting deployment of "business-central.war" (runtime-name: "business-central.war")
>10:30:16,260 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-10) JBAS015960: Class Path entry xalan-2.7.1-redhat-3.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-3.jar  does not point to a valid jar for a Class-Path reference.
>10:30:16,260 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-10) JBAS015960: Class Path entry serializer-2.7.1-redhat-3.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-3.jar  does not point to a valid jar for a Class-Path reference.
>10:30:16,261 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-10) JBAS015960: Class Path entry xercesImpl-2.9.1-redhat-4.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-3.jar  does not point to a valid jar for a Class-Path reference.
>10:30:16,261 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-10) JBAS015960: Class Path entry xml-apis-1.3.04.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-3.jar  does not point to a valid jar for a Class-Path reference.
>10:30:16,261 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-10) JBAS015960: Class Path entry commons-lang-2.6-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-3.jar  does not point to a valid jar for a Class-Path reference.
>10:30:16,261 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-10) JBAS015960: Class Path entry commons-io-2.1-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-3.jar  does not point to a valid jar for a Class-Path reference.
>10:30:16,261 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-10) JBAS015960: Class Path entry log4j-1.2.16-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-3.jar  does not point to a valid jar for a Class-Path reference.
>10:30:16,697 INFO  [org.jboss.as.jpa] (MSC service thread 1-8) JBAS011401: Read persistence.xml for org.jbpm.domain
>10:30:17,319 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,319 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,320 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,321 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,321 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,321 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,321 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,322 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,322 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,322 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,322 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,323 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,323 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,323 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,325 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,325 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,326 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,326 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,339 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,339 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,343 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,343 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,343 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,344 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,344 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,344 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,344 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,344 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,347 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,347 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,348 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,348 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,349 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,349 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,350 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,350 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,351 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,351 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,352 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,352 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,353 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,353 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,353 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,354 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,355 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,355 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,355 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,355 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,356 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,357 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,363 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,363 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,364 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,364 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-1) 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.
>10:30:17,365 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,365 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,366 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,366 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-1) 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.
>10:30:17,603 INFO  [org.jboss.weld.deployer] (MSC service thread 1-10) JBAS016002: Processing weld deployment business-central.war
>10:30:17,747 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-10) 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
>
>10:30:18,043 INFO  [org.jboss.weld.deployer] (MSC service thread 1-11) JBAS016005: Starting Services for CDI deployment: business-central.war
>10:30:18,105 INFO  [org.jboss.weld.Version] (MSC service thread 1-11) WELD-000900 1.1.13 (redhat)
>10:30:18,188 INFO  [org.jboss.as.jpa] (ServerService Thread Pool -- 64) JBAS011402: Starting Persistence Unit Service 'business-central.war#org.jbpm.domain'
>10:30:18,196 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 66) HQ221003: trying to deploy queue jms.queue.KIE.AUDIT
>10:30:18,193 INFO  [org.jboss.weld.deployer] (MSC service thread 1-11) JBAS016008: Starting weld service for deployment business-central.war
>10:30:18,211 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.AUDIT
>10:30:18,211 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.AUDIT.ALL
>10:30:18,212 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 68) HQ221003: trying to deploy queue jms.queue.KIE.SESSION
>10:30:18,229 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.SESSION
>10:30:18,229 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.SESSION.ALL
>10:30:18,229 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 67) HQ221003: trying to deploy queue jms.queue.KIE.TASK
>10:30:18,233 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 67) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.TASK
>10:30:18,233 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 67) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.TASK.ALL
>10:30:18,233 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 65) HQ221003: trying to deploy queue jms.queue.KIE.RESPONSE
>10:30:18,236 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.RESPONSE
>10:30:18,236 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.RESPONSE.ALL
>10:30:18,275 INFO  [org.jboss.as.ejb3] (MSC service thread 1-15) JBAS014142: Started message driven bean 'TaskServiceRequesMessageBean' with 'hornetq-ra' resource adapter
>10:30:18,277 INFO  [org.jboss.as.ejb3] (MSC service thread 1-6) JBAS014142: Started message driven bean 'JMSAuditProcessor' with 'hornetq-ra' resource adapter
>10:30:18,281 INFO  [org.jboss.as.ejb3] (MSC service thread 1-4) JBAS014142: Started message driven bean 'KieSessionRequesMessageBean' with 'hornetq-ra' resource adapter
>10:30:18,422 INFO  [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 64) HCANN000001: Hibernate Commons Annotations {4.0.1.Final-redhat-2}
>10:30:18,429 INFO  [org.hibernate.Version] (ServerService Thread Pool -- 64) HHH000412: Hibernate Core {4.2.0.SP1-redhat-1}
>10:30:18,432 INFO  [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 64) HHH000206: hibernate.properties not found
>10:30:18,434 INFO  [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 64) HHH000021: Bytecode provider name : javassist
>10:30:18,460 INFO  [org.hibernate.ejb.Ejb3Configuration] (ServerService Thread Pool -- 64) HHH000204: Processing PersistenceUnitInfo [
>	name: org.jbpm.domain
>	...]
>10:30:19,789 INFO  [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (ServerService Thread Pool -- 64) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
>10:30:20,122 INFO  [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 64) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
>10:30:20,143 INFO  [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (ServerService Thread Pool -- 64) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory
>10:30:20,148 INFO  [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (ServerService Thread Pool -- 64) HHH000397: Using ASTQueryTranslatorFactory
>10:30:20,192 INFO  [org.hibernate.validator.internal.util.Version] (ServerService Thread Pool -- 64) HV000001: Hibernate Validator 4.3.1.Final-redhat-1
>10:30:20,835 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000228: Running hbm2ddl schema update
>10:30:20,835 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000102: Fetching database metadata
>10:30:20,839 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000396: Updating schema
>10:30:20,843 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Attachment
>10:30:20,845 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BAMTaskSummary
>10:30:20,846 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BooleanExpression
>10:30:20,847 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Content
>10:30:20,848 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ContextMappingInfo
>10:30:20,850 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationKeyInfo
>10:30:20,851 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationPropertyInfo
>10:30:20,852 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Deadline
>10:30:20,853 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Delegation_delegates
>10:30:20,855 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ErrorInfo
>10:30:20,856 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Escalation
>10:30:20,857 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: EventTypes
>10:30:20,858 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: I18NText
>10:30:20,859 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NodeInstanceLog
>10:30:20,860 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification
>10:30:20,861 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_BAs
>10:30:20,862 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_Recipients
>10:30:20,863 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_email_header
>10:30:20,864 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: OrganizationalEntity
>10:30:20,866 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_BAs
>10:30:20,867 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_ExclOwners
>10:30:20,868 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_PotOwners
>10:30:20,869 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Recipients
>10:30:20,871 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Stakeholders
>10:30:20,872 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceInfo
>10:30:20,873 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceLog
>10:30:20,874 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment
>10:30:20,875 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment_potentialOwners
>10:30:20,876 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: RequestInfo
>10:30:20,878 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: SessionInfo
>10:30:20,879 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Task
>10:30:20,880 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskDef
>10:30:20,882 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskEvent
>10:30:20,883 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: VariableInstanceLog
>10:30:20,884 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: WorkItemInfo
>10:30:20,885 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: email_header
>10:30:20,886 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: task_comment
>10:30:20,888 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Attachment
>10:30:20,889 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BAMTaskSummary
>10:30:20,890 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BooleanExpression
>10:30:20,891 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Content
>10:30:20,892 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ContextMappingInfo
>10:30:20,893 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationKeyInfo
>10:30:20,894 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationPropertyInfo
>10:30:20,895 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Deadline
>10:30:20,897 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Delegation_delegates
>10:30:20,898 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ErrorInfo
>10:30:20,899 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Escalation
>10:30:20,900 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: EventTypes
>10:30:20,901 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: I18NText
>10:30:20,902 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NodeInstanceLog
>10:30:20,903 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification
>10:30:20,904 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_BAs
>10:30:20,905 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_Recipients
>10:30:20,907 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_email_header
>10:30:20,908 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: OrganizationalEntity
>10:30:20,909 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_BAs
>10:30:20,910 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_ExclOwners
>10:30:20,911 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_PotOwners
>10:30:20,912 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Recipients
>10:30:20,913 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Stakeholders
>10:30:20,914 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceInfo
>10:30:20,915 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceLog
>10:30:20,916 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment
>10:30:20,918 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment_potentialOwners
>10:30:20,919 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: RequestInfo
>10:30:20,920 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: SessionInfo
>10:30:20,921 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Task
>10:30:20,922 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskDef
>10:30:20,923 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskEvent
>10:30:20,924 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: VariableInstanceLog
>10:30:20,925 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: WorkItemInfo
>10:30:20,927 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: email_header
>10:30:20,928 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: task_comment
>10:30:23,307 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Notification add index FK_bdbeml3768go5im41cgfpyso9 (Escalation_Notifications_Id), add constraint FK_bdbeml3768go5im41cgfpyso9 foreign key (Escalation_Notifications_Id) references Escalation (id)
>10:30:23,307 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_bdbeml3768go5im41cgfpyso9'
>10:30:23,354 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Notification_BAs add index FK_mfbsnbrhth4rjhqc2ud338s4i (entity_id), add constraint FK_mfbsnbrhth4rjhqc2ud338s4i foreign key (entity_id) references OrganizationalEntity (id)
>10:30:23,354 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_mfbsnbrhth4rjhqc2ud338s4i'
>10:30:23,401 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Notification_BAs add index FK_fc0uuy76t2bvxaxqysoo8xts7 (task_id), add constraint FK_fc0uuy76t2bvxaxqysoo8xts7 foreign key (task_id) references Notification (id)
>10:30:23,402 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_fc0uuy76t2bvxaxqysoo8xts7'
>10:30:23,450 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Notification_Recipients add index FK_blf9jsrumtrthdaqnpwxt25eu (entity_id), add constraint FK_blf9jsrumtrthdaqnpwxt25eu foreign key (entity_id) references OrganizationalEntity (id)
>10:30:23,450 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_blf9jsrumtrthdaqnpwxt25eu'
>10:30:23,498 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Notification_Recipients add index FK_3l244pj8sh78vtn9imaymrg47 (task_id), add constraint FK_3l244pj8sh78vtn9imaymrg47 foreign key (task_id) references Notification (id)
>10:30:23,498 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_3l244pj8sh78vtn9imaymrg47'
>10:30:23,990 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_Recipients add index FK_4g7y3wx6gnokf6vycgpxs83d6 (entity_id), add constraint FK_4g7y3wx6gnokf6vycgpxs83d6 foreign key (entity_id) references OrganizationalEntity (id)
>10:30:23,990 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_4g7y3wx6gnokf6vycgpxs83d6'
>10:30:24,056 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table PeopleAssignments_Recipients add index FK_enhk831fghf6akjilfn58okl4 (task_id), add constraint FK_enhk831fghf6akjilfn58okl4 foreign key (task_id) references Task (id)
>10:30:24,056 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_enhk831fghf6akjilfn58okl4'
>10:30:24,271 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Reassignment_potentialOwners add index FK_8frl6la7tgparlnukhp8xmody (entity_id), add constraint FK_8frl6la7tgparlnukhp8xmody foreign key (entity_id) references OrganizationalEntity (id)
>10:30:24,271 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_8frl6la7tgparlnukhp8xmody'
>10:30:24,319 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Reassignment_potentialOwners add index FK_qbega5ncu6b9yigwlw55aeijn (task_id), add constraint FK_qbega5ncu6b9yigwlw55aeijn foreign key (task_id) references Reassignment (id)
>10:30:24,319 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_qbega5ncu6b9yigwlw55aeijn'
>10:30:24,373 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: alter table Task add index FK_dpk0f9ucm14c78bsxthh7h8yh (taskInitiator_id), add constraint FK_dpk0f9ucm14c78bsxthh7h8yh foreign key (taskInitiator_id) references OrganizationalEntity (id)
>10:30:24,373 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Duplicate key name 'FK_dpk0f9ucm14c78bsxthh7h8yh'
>10:30:24,570 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000232: Schema update complete
>10:30:26,007 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-15) Solder Config XML provider starting...
>10:30:26,009 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-15) Loading XmlDocumentProvider: org.jboss.solder.config.xml.bootstrap.ResourceLoaderXmlDocumentProvider
>10:30:26,023 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-15) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/drools-wb-rest-defaultapprover-6.0.2-redhat-3.jar/META-INF/beans.xml
>10:30:26,031 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-15) Reading XML file: jar:file:/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/modules/system/layers/bpms/org/jbpm/main/jbpm-kie-services-6.0.2-redhat-3.jar!/META-INF/beans.xml
>10:30:26,033 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-15) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/uberfire-security-server-0.3.2-redhat-3.jar/META-INF/beans.xml
>10:30:26,034 WARN  [org.jboss.solder.config.xml.model.ModelBuilder] (MSC service thread 1-15) Solder Config could not resolve XML namspace for: {}
>10:30:26,035 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-15) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/uberfire-api-0.3.2-redhat-3.jar/META-INF/beans.xml
>10:30:26,036 WARN  [org.jboss.solder.config.xml.model.ModelBuilder] (MSC service thread 1-15) Solder Config could not resolve XML namspace for: {}
>10:30:26,037 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) starting errai cdi ...
>10:30:26,049 INFO  [org.jboss.solder.Version] (MSC service thread 1-15) Solder 3.2.1.Final (build id: 3.2.1.Final)
>10:30:26,549 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.FileNavigatorServiceImpl
>10:30:26,585 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.WorkbenchServicesImpl
>10:30:26,642 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.VFSServicesServerImpl
>10:30:26,687 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.plugin.RuntimePluginsServiceServerImpl
>10:30:26,693 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.repositories.RepositoryServiceEditorImpl
>10:30:26,716 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.organizationalunit.OrganizationalUnitServiceImpl
>10:30:26,723 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.repositories.RepositoryServiceImpl
>10:30:26,791 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.FormModelerProcessStarterEntryPointImpl
>10:30:26,798 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.TypeRoleServiceEntryPointImpl
>10:30:26,813 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.TaskServiceEntryPointImpl
>10:30:26,827 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.FormServiceEntryPointImpl
>10:30:26,834 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.UserServiceEntryPointImpl
>10:30:26,898 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.explorer.backend.server.ExplorerServiceImpl
>10:30:27,159 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.bd.backend.server.DataServiceEntryPointImpl
>10:30:27,163 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.bd.backend.server.KieSessionEntryPointImpl
>10:30:27,197 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.bd.backend.server.DeploymentManagerEntryPointImpl
>10:30:27,234 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.dsltext.backend.server.DSLTextEditorServiceImpl
>10:30:27,384 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.backend.validation.file.FileNameValidationServiceImpl
>10:30:27,392 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.backend.source.SourceServicesImpl
>10:30:27,395 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.backend.security.KieWorkbenchSecurityServiceImpl
>10:30:27,403 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.backend.validation.java.IdentifierValidationServiceImpl
>10:30:28,055 INFO  [org.jboss.errai.common.metadata.MetaDataScanner] (Thread-96) added class scanning extensions: org.jboss.errai.common.metadata.JbossVFsTypeHandler
>10:30:29,871 INFO  [org.jboss.errai.reflections.Reflections] (Thread-96) Reflections took 1812 ms to scan 95 urls, producing 1082 keys and 6067 values [using 2 cores]
>10:30:30,648 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.dtablexls.backend.server.DecisionTableXLSServiceImpl
>10:30:31,411 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.m2repo.backend.server.M2RepoServiceImpl
>10:30:31,438 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.search.backend.server.SearchServiceImpl
>10:30:31,460 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.dashboard.renderer.service.impl.DashboardRendererServiceImpl
>10:30:31,558 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.projecteditor.backend.server.ProjectScreenServiceImpl
>10:30:31,569 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@SessionScoped @Service class org.jbpm.formModeler.renderer.backend.service.FormRenderingServiceImpl
>10:30:31,571 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.formModeler.renderer.backend.service.FormRendererIncluderServiceImpl
>10:30:31,594 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.inbox.backend.server.InboxServiceImpl
>10:30:31,629 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.enums.backend.server.EnumServiceImpl
>10:30:31,860 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.formModeler.panels.modeler.backend.FormModelerServiceImpl
>10:30:31,878 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.scorecardxls.backend.server.ScoreCardXLSServiceImpl
>10:30:32,004 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.globals.backend.server.GlobalsEditorServiceImpl
>10:30:32,391 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.designer.server.service.DefaultDesignerAssetService
>10:30:32,583 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.defaulteditor.backend.server.DefaultEditorServiceImpl
>10:30:32,778 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.datamodeller.backend.server.DataModelerServiceImpl
>10:30:32,809 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.es.backend.server.ExecutorServiceEntryPointImpl
>10:30:32,911 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.testscenario.backend.server.ScenarioTestEditorServiceImpl
>10:30:32,925 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.scorecard.backend.server.GuidedScoreCardEditorServiceImpl
>10:30:33,076 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.template.server.GuidedRuleTemplateEditorServiceImpl
>10:30:33,138 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.drltext.backend.server.DRLTextEditorServiceImpl
>10:30:33,175 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.rule.backend.server.GuidedRuleEditorServiceImpl
>10:30:33,188 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.rule.backend.server.EnumDropdownServiceImpl
>10:30:33,227 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.datamodel.backend.server.IncrementalDataModelServiceImpl
>10:30:33,395 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.dtable.backend.server.GuidedDecisionTableEditorServiceImpl
>10:30:33,418 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.config.ResourceConfigServiceImpl
>10:30:33,431 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.version.VersionServiceImpl
>10:30:33,455 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@Service class org.guvnor.common.services.backend.file.RenameServiceImpl
>10:30:33,465 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.metadata.MetadataServiceImpl
>10:30:33,472 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@Service class org.guvnor.common.services.backend.file.CopyServiceImpl
>10:30:33,476 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.config.AppConfigServiceImpl
>10:30:33,489 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@Service class org.guvnor.common.services.backend.file.DeleteServiceImpl
>10:30:33,509 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.metadata.CategoryServiceImpl
>10:30:33,532 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.rulenames.RuleNamesServiceImpl
>10:30:33,559 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.workitems.backend.server.WorkItemsEditorServiceImpl
>10:30:33,642 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.project.backend.server.ProjectServiceImpl
>10:30:33,644 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.project.backend.server.KModuleServiceImpl
>10:30:33,652 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.project.backend.server.POMServiceImpl
>10:30:33,683 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-15) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.builder.BuildServiceImpl
>10:30:33,899 INFO  [org.drools.compiler.kie.builder.impl.ClasspathKieProject] (MSC service thread 1-15) Found kmodule: vfs:/content/business-central.war/WEB-INF/lib/drools-wb-rest-defaultapprover-6.0.2-redhat-3.jar/META-INF/kmodule.xml
>10:30:33,900 INFO  [org.drools.compiler.kie.builder.impl.ClasspathKieProject] (MSC service thread 1-15) Virtual file physical path = /mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/tmp/vfs/deployment5348652af3f01389/drools-wb-rest-defaultapprover-6.0.2-redhat-3.jar-9289109dc205c70e/drools-wb-rest-defaultapprover-6.0.2-redhat-3.jar
>10:30:33,959 INFO  [org.drools.compiler.kie.builder.impl.KieRepositoryImpl] (MSC service thread 1-15) KieModule was added:ZipKieModule[ ReleaseId=org.drools:drools-wb-rest-defaultapprover:6.0.2-redhat-3file=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/domain/servers/server-two/tmp/vfs/deployment5348652af3f01389/drools-wb-rest-defaultapprover-6.0.2-redhat-3.jar-9289109dc205c70e/drools-wb-rest-defaultapprover-6.0.2-redhat-3.jar]
>10:30:37,127 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-15) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.uberfire.backend.server.UserServicesImpl.identity
>10:30:37,150 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-15) 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
>10:30:37,150 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-15) 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
>10:30:37,202 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-15) WELD-001440 Scope type @javax.enterprise.context.RequestScoped() used on injection point [field] @Inject @RequestScoped private org.jbpm.console.ng.bd.backend.server.DeploymentManagerEntryPointImpl.deploymentUnits
>10:30:37,313 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-15) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.guvnor.inbox.backend.server.InboxServiceImpl.identity
>10:30:37,356 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-15) WELD-001440 Scope type @javax.enterprise.context.RequestScoped() used on injection point [field] @Inject @RequestScoped private org.jbpm.designer.repository.vfs.VFSRepository.descriptor
>10:30:37,530 INFO  [org.apache.helix.controller.GenericHelixController] (MSC service thread 1-15) createDefaultRegistry
>10:30:37,544 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-15) Create a zk-based cluster manager. zkSvr: localhost:2181, clusterName: bpms-cluster, instanceName: nodeTwo_12346, type: PARTICIPANT
>10:30:37,546 INFO  [org.apache.helix.HelixManagerProperties] (MSC service thread 1-15) load helix-manager properties: {minimum_supported_version.batch_message=0.6.1, clustermanager.version=0.6.2-incubating, minimum_supported_version.participant=0.4}
>10:30:37,556 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-15) Added msg-factory for type: TASK_REPLY, threadpool size 40
>10:30:37,591 INFO  [org.apache.sshd.common.util.SecurityUtils] (MSC service thread 1-15) BouncyCastle not registered, using the default JCE provider
>10:30:38,116 INFO  [org.guvnor.m2repo.backend.server.GuvnorM2Repository] (MSC service thread 1-15) Maven Repository root set to: repositories/kie
>10:30:38,116 INFO  [org.guvnor.m2repo.backend.server.GuvnorM2Repository] (MSC service thread 1-15) Creating Maven Repository root: repositories/kie
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'type' to 'kjar'.
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'drools.dateformat' to 'dd-MMM-yyyy'.
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'drools.defaultlanguage' to 'en'.
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'rule-modeller-onlyShowDSLStatements' to 'false'.
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'designer.context' to 'designer'.
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'drools.defaultcountry' to 'US'.
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'drools.datetimeformat' to 'dd-MMM-yyyy hh:mm:ss'.
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'build.enable-incremental' to 'true'.
>10:30:38,583 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-15) Setting preference 'designer.profile' to 'jbpm'.
>10:30:38,591 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-15) ClusterManager.connect()
>10:30:38,603 INFO  [org.I0Itec.zkclient.ZkEventThread] (ZkClient-EventThread-198-localhost:2181) Starting ZkClient event thread.
>10:30:38,609 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:zookeeper.version=3.3.3-1203054, built on 11/17/2011 05:47 GMT
>10:30:38,609 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:host.name=dev109.mw.lab.eng.bos.redhat.com
>10:30:38,609 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:java.version=1.7.0_45
>10:30:38,609 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:java.vendor=Oracle Corporation
>10:30:38,609 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:java.home=/qa/tools/opt/x86_64/jdk1.7.0_45/jre
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:java.class.path=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1/jboss-modules.jar
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:java.library.path=/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/amd64/server:/qa/tools/opt/x86_64/jdk1.7.0_45/jre/lib/amd64:/qa/tools/opt/x86_64/jdk1.7.0_45/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
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:java.io.tmpdir=/tmp
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:java.compiler=<NA>
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:os.name=Linux
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:os.arch=amd64
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:os.version=2.6.32-431.1.2.el6.x86_64
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:user.name=hudson
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:user.home=/home/hudson
>10:30:38,610 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Client environment:user.dir=/mnt/hudson_workspace/workspace/bpms-clustering-single/jboss-eap-6.1
>10:30:38,611 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-15) Initiating client connection, connectString=localhost:2181 sessionTimeout=30000 watcher=org.apache.helix.manager.zk.ZkClient@5d3454a2
>10:30:38,620 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-15-SendThread()) Opening socket connection to server localhost/127.0.0.1:2181
>10:30:38,622 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-15-SendThread(localhost:2181)) Socket connection established to localhost/127.0.0.1:2181, initiating session
>10:30:38,640 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-15-SendThread(localhost:2181)) Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x1446ed02ea10007, negotiated timeout = 30000
>10:30:38,641 INFO  [org.I0Itec.zkclient.ZkClient] (MSC service thread 1-15-EventThread) zookeeper state changed (SyncConnected)
>10:30:38,650 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-15) KeeperState: SyncConnected, zookeeper:State:CONNECTED Timeout:30000 sessionid:0x1446ed02ea10007 local:/127.0.0.1:40929 remoteserver:localhost/127.0.0.1:2181 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0
>10:30:38,651 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-15) Handling new session, session id: 1446ed02ea10007, instance: nodeTwo_12346, instanceTye: PARTICIPANT, cluster: bpms-cluster, zkconnection: State:CONNECTED Timeout:30000 sessionid:0x1446ed02ea10007 local:/127.0.0.1:40929 remoteserver:localhost/127.0.0.1:2181 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0
>10:30:38,651 WARN  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-15) ParticipantHealthReportTimerTask already stopped
>10:30:38,719 INFO  [org.apache.helix.manager.zk.ParticipantManagerHelper] (MSC service thread 1-15) instance: nodeTwo_12346 auto-joining bpms-cluster is false
>10:30:38,772 INFO  [org.apache.helix.messaging.DefaultMessagingService] (MSC service thread 1-15) registering msg factory for type STATE_TRANSITION
>10:30:38,797 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-15) Added msg-factory for type: STATE_TRANSITION, threadpool size 40
>10:30:38,833 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-15) 25 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:30:38,834 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-15) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:30:38,844 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-15) Dropping NO-OP message. mid: 5f272065-82db-4f41-80da-0ad7e94e7800, from: nodeTwo_12346
>10:30:38,848 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-15) 25 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 15ms
>10:30:38,848 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-15) Added listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362 for type: MESSAGES to path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES
>10:30:38,848 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-15) 25 START:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor
>10:30:38,848 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-15) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/CONTROLLER, listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@77c51d55
>10:30:38,850 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-15) Controller has changed
>10:30:38,864 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-15) _zkPropertyTransferSvcUrl : null Controller dev109.mw.lab.eng.bos.redhat.com-CONTROLLER
>10:30:38,864 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-15) 25 END:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor Took: 16ms
>10:30:38,864 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-15) Added listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@77c51d55 for type: CONTROLLER to path: /bpms-cluster/CONTROLLER
>10:30:38,864 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:30:38,865 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:30:38,866 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-15) Register state model factory for state model SchedulerTaskQueue using factory name DEFAULT with org.apache.helix.participant.statemachine.ScheduledTaskStateModelFactory@4478836
>10:30:38,901 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-15) Send NO_OP message to nodeTwo_12346, msgId: a26c76d9-8a24-4f7b-91b5-e282b14ae0ac
>10:30:38,908 WARN  [org.apache.helix.participant.HelixStateMachineEngine] (ZkClient-EventThread-198-localhost:2181) Fail to create msg-handler because cannot find stateModelFactory for model: LeaderStandby using factoryName: DEFAULT for resource: vfs-repo
>10:30:38,909 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 45ms
>10:30:38,913 INFO  [org.apache.helix.manager.zk.ParticipantManagerHelper] (MSC service thread 1-15) Created healthcheck info path /bpms-cluster/INSTANCES/nodeTwo_12346/HEALTHREPORT
>10:30:38,913 INFO  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-15) Start HealthCheckInfoReportingTask
>10:30:38,914 WARN  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-15) Skip processing callbacks for listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362, path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, expected types: [CALLBACK, FINALIZE] but was INIT
>10:30:38,914 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-15) init handler: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:30:38,914 WARN  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-15) Skip processing callbacks for listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@77c51d55, path: /bpms-cluster/CONTROLLER, expected types: [CALLBACK, FINALIZE] but was INIT
>10:30:38,914 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-15) init handler: /bpms-cluster/CONTROLLER, org.apache.helix.manager.zk.ZKHelixDataAccessor@77c51d55
>10:30:38,914 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-15) Register state model factory for state model LeaderStandby using factory name DEFAULT with org.uberfire.io.impl.cluster.helix.LockTransitionalFactory@2bc9d3a6
>10:30:38,919 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:30:38,920 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:30:38,937 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-15) Send NO_OP message to nodeTwo_12346, msgId: 92dc03a1-ba7b-4fb4-9d8c-784acc89be2d
>10:30:38,939 INFO  [org.apache.helix.messaging.DefaultMessagingService] (MSC service thread 1-15) registering msg factory for type USER_DEFINE_MSG
>10:30:38,950 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Dropping NO-OP message. mid: a26c76d9-8a24-4f7b-91b5-e282b14ae0ac, from: nodeTwo_12346
>10:30:38,961 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Dropping NO-OP message. mid: 92dc03a1-ba7b-4fb4-9d8c-784acc89be2d, from: nodeTwo_12346
>10:30:38,986 WARN  [org.apache.helix.manager.zk.ZkAsyncCallbacks] (MSC service thread 1-15-EventThread) org.apache.helix.manager.zk.ZkAsyncCallbacks$CreateCallbackHandler@565d49dc, rc:NONODE, path: /bpms-cluster/INSTANCES/nodeTwo_12346/CURRENTSTATES/1446ed02ea10007/vfs-repo
>10:30:39,052 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-15) Added msg-factory for type: USER_DEFINE_MSG, threadpool size 40
>10:30:39,071 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: bcee2d9a-56b7-472c-bb30-ba330b4fde7e
>10:30:39,073 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: bcee2d9a-56b7-472c-bb30-ba330b4fde7e handling task scheduled
>10:30:39,073 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 154ms
>10:30:39,074 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) handling task: bcee2d9a-56b7-472c-bb30-ba330b4fde7e begin, at: 1393428639074
>10:30:39,076 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:30:39,076 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:30:39,090 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Dropping NO-OP message. mid: 5e454229-c93b-4664-91bb-253564fd66f7, from: nodeTwo_12346
>10:30:39,093 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) Message bcee2d9a-56b7-472c-bb30-ba330b4fde7e completed.
>10:30:39,099 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 23ms
>10:30:39,104 INFO  [org.jbpm.executor.impl.ExecutorImpl] (MSC service thread 1-15) Starting Executor Component ...
> 	 - Thread Pool Size: 1
> 	 - Interval: 3 Seconds
> 	 - Retries per Request: 3
>
>10:30:39,106 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:30:39,106 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:30:39,109 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:30:39,109 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>10:30:39,111 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:30:39,111 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:30:39,122 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:30:39,122 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 11ms
>10:30:39,129 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:30:39,129 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:30:39,162 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:30:39,163 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 34ms
>10:30:39,191 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-1) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=OFFLINE--STANDBY
>10:30:39,202 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) msg: bcee2d9a-56b7-472c-bb30-ba330b4fde7e handling task completed, results:true, at: 1393428639202, took:128
>10:30:39,222 INFO  [org.kie.services.remote.jms.RetryTrackerSingleton] (ServerService Thread Pool -- 77) JMS message retry limit set to 2
>10:30:39,248 INFO  [org.jboss.web] (ServerService Thread Pool -- 77) JBAS018210: Register web context: /business-central
>10:30:39,459 INFO  [solder-servlet] (ServerService Thread Pool -- 77) Catch Integration for Servlets enabled
>10:30:39,599 INFO  [org.jboss.errai.bus.server.cluster.noop.NoopClusteringProvider] (ServerService Thread Pool -- 77) clustering support not configured.
>10:30:39,607 INFO  [org.jboss.errai.bus.server.service.bootstrap.OrderedBootstrap] (ServerService Thread Pool -- 77) starting errai bus ...
>10:30:39,609 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 77) authentication adapter configured: org.jboss.errai.bus.server.security.auth.JAASAdapter
>10:30:39,614 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 77) using dispatcher implementation: org.jboss.errai.bus.server.SimpleDispatcher
>10:30:39,617 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 77) using session provider implementation: org.jboss.errai.bus.server.HttpSessionProvider
>10:30:39,632 INFO  [org.jboss.errai.bus.server.service.bootstrap.LoadExtensions] (ServerService Thread Pool -- 77) searching for errai extensions ...
>10:30:39,642 INFO  [org.jboss.errai.bus.server.service.bootstrap.OrderedBootstrap] (ServerService Thread Pool -- 77) errai bus started.
>10:30:39,910 INFO  [org.jboss.as.server] (host-controller-connection-threads - 1) JBAS018559: Deployed "business-central.war" (runtime-name : "business-central.war")
>10:32:09,824 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:09,824 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:09,834 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: d2c4cc76-00e5-417f-8c0e-ebff3445df72
>10:32:09,835 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-1) handling task: d2c4cc76-00e5-417f-8c0e-ebff3445df72 begin, at: 1393428729835
>10:32:09,836 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message starts with timeout 30000 MsgId: d2c4cc76-00e5-417f-8c0e-ebff3445df72
>10:32:09,836 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: d2c4cc76-00e5-417f-8c0e-ebff3445df72 handling task scheduled
>10:32:09,836 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms
>10:32:13,559 ERROR [org.uberfire.io.impl.cluster.helix.ClusterServiceHelix] (pool-15-thread-1) Error while processing cluster message: java.lang.NullPointerException
>	at org.uberfire.io.impl.cluster.IOServiceClusterImpl$NewFileSystemMessageHandler.handleMessage(IOServiceClusterImpl.java:894) [uberfire-io-0.3.2-redhat-3.jar:0.3.2-redhat-3]
>	at org.uberfire.io.impl.cluster.helix.ClusterServiceHelix$MessageHandlerResolverWrapper$1$1.handleMessage(ClusterServiceHelix.java:294) [uberfire-io-0.3.2-redhat-3.jar:0.3.2-redhat-3]
>	at org.apache.helix.messaging.handling.HelixTask.call(HelixTask.java:93) [helix-core-0.6.2-incubating.jar:0.6.2-incubating]
>	at org.apache.helix.messaging.handling.HelixTask.call(HelixTask.java:50) [helix-core-0.6.2-incubating.jar:0.6.2-incubating]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_45]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_45]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45]
>
>10:32:13,562 ERROR [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-1) Message execution failed. msgId: d2c4cc76-00e5-417f-8c0e-ebff3445df72, errorMsg: null
>10:32:13,715 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-1) Sending reply for message 7fe3882b-942d-45b9-b1b4-9701a0c9337c
>10:32:13,716 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:13,716 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:13,798 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:13,798 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 83ms
>10:32:13,798 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-1) message finished: d2c4cc76-00e5-417f-8c0e-ebff3445df72, took 3962
>10:32:13,798 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-1) msg: d2c4cc76-00e5-417f-8c0e-ebff3445df72 handling task completed, results:false, at: 1393428733798, took:3963
>10:32:14,068 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:14,069 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:14,110 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 27014a93-69b6-4ca6-a189-6c482860c2c2
>10:32:14,110 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 27014a93-69b6-4ca6-a189-6c482860c2c2 handling task scheduled
>10:32:14,111 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 43ms
>10:32:14,111 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) handling task: 27014a93-69b6-4ca6-a189-6c482860c2c2 begin, at: 1393428734110
>10:32:14,172 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) Message 27014a93-69b6-4ca6-a189-6c482860c2c2 completed.
>10:32:14,206 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-2) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--LEADER
>10:32:14,206 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) msg: 27014a93-69b6-4ca6-a189-6c482860c2c2 handling task completed, results:true, at: 1393428734206, took:96
>10:32:14,207 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:14,208 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:14,211 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:14,211 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 4ms
>10:32:14,260 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:14,261 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:14,283 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 7110e51a-7e07-44fb-92a1-4f3283b04708
>10:32:14,284 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 7110e51a-7e07-44fb-92a1-4f3283b04708 handling task scheduled
>10:32:14,284 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 24ms
>10:32:14,284 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) handling task: 7110e51a-7e07-44fb-92a1-4f3283b04708 begin, at: 1393428734284
>10:32:14,335 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) Message 7110e51a-7e07-44fb-92a1-4f3283b04708 completed.
>10:32:14,487 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-3) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=LEADER--STANDBY
>10:32:14,488 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) msg: 7110e51a-7e07-44fb-92a1-4f3283b04708 handling task completed, results:true, at: 1393428734487, took:203
>10:32:14,596 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:14,596 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:14,598 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:14,598 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>10:32:14,819 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:14,819 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:14,858 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: e0ac1c53-e67d-4cc6-a2ab-211fa2b210bc
>10:32:14,859 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: e0ac1c53-e67d-4cc6-a2ab-211fa2b210bc handling task scheduled
>10:32:14,859 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 40ms
>10:32:14,859 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-2) handling task: e0ac1c53-e67d-4cc6-a2ab-211fa2b210bc begin, at: 1393428734859
>10:32:14,925 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:14,926 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:14,990 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: e14eceb4-9830-41c5-816a-481c47d2fe85
>10:32:14,992 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: e14eceb4-9830-41c5-816a-481c47d2fe85 handling task scheduled
>10:32:14,992 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 67ms
>10:32:14,992 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-3) handling task: e14eceb4-9830-41c5-816a-481c47d2fe85 begin, at: 1393428734992
>10:32:15,290 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:15,291 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:15,379 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 04d38782-5c38-4328-b5ad-200e588b01d8
>10:32:15,380 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 04d38782-5c38-4328-b5ad-200e588b01d8 handling task scheduled
>10:32:15,381 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 91ms
>10:32:15,381 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) handling task: 04d38782-5c38-4328-b5ad-200e588b01d8 begin, at: 1393428735380
>10:32:15,399 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-2) Message e0ac1c53-e67d-4cc6-a2ab-211fa2b210bc completed.
>10:32:15,410 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) Message 04d38782-5c38-4328-b5ad-200e588b01d8 completed.
>10:32:15,434 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-2) message finished: e0ac1c53-e67d-4cc6-a2ab-211fa2b210bc, took 574
>10:32:15,434 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-2) msg: e0ac1c53-e67d-4cc6-a2ab-211fa2b210bc handling task completed, results:true, at: 1393428735434, took:575
>10:32:15,436 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-3) Message e14eceb4-9830-41c5-816a-481c47d2fe85 completed.
>10:32:15,448 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-4) message finished: 04d38782-5c38-4328-b5ad-200e588b01d8, took 64
>10:32:15,448 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) msg: 04d38782-5c38-4328-b5ad-200e588b01d8 handling task completed, results:true, at: 1393428735448, took:68
>10:32:15,493 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-3) message finished: e14eceb4-9830-41c5-816a-481c47d2fe85, took 500
>10:32:15,493 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:15,493 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-3) msg: e14eceb4-9830-41c5-816a-481c47d2fe85 handling task completed, results:true, at: 1393428735493, took:501
>10:32:15,494 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:15,496 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:15,496 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>10:32:15,686 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:15,686 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:15,743 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 1492f1b7-e2fa-4a8b-8d2e-ebe215a88913
>10:32:15,744 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 1492f1b7-e2fa-4a8b-8d2e-ebe215a88913 handling task scheduled
>10:32:15,744 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 58ms
>10:32:15,744 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) handling task: 1492f1b7-e2fa-4a8b-8d2e-ebe215a88913 begin, at: 1393428735744
>10:32:15,763 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) Message 1492f1b7-e2fa-4a8b-8d2e-ebe215a88913 completed.
>10:32:15,823 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-5) message finished: 1492f1b7-e2fa-4a8b-8d2e-ebe215a88913, took 78
>10:32:15,823 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) msg: 1492f1b7-e2fa-4a8b-8d2e-ebe215a88913 handling task completed, results:true, at: 1393428735823, took:79
>10:32:15,824 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:15,824 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:15,826 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:15,826 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>10:32:15,860 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:15,860 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:15,888 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: f62db841-e274-4ade-af77-fe16fecfc3a8
>10:32:15,889 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: f62db841-e274-4ade-af77-fe16fecfc3a8 handling task scheduled
>10:32:15,889 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 30ms
>10:32:15,889 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) handling task: f62db841-e274-4ade-af77-fe16fecfc3a8 begin, at: 1393428735889
>10:32:15,925 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) Message f62db841-e274-4ade-af77-fe16fecfc3a8 completed.
>10:32:15,954 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-6) message finished: f62db841-e274-4ade-af77-fe16fecfc3a8, took 64
>10:32:15,954 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) msg: f62db841-e274-4ade-af77-fe16fecfc3a8 handling task completed, results:true, at: 1393428735954, took:65
>10:32:15,984 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:15,984 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:15,987 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:15,987 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>10:32:16,033 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:16,033 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:16,044 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 56fad8e9-862f-4534-9a62-b848cc9ad58f
>10:32:16,045 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 56fad8e9-862f-4534-9a62-b848cc9ad58f handling task scheduled
>10:32:16,045 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms
>10:32:16,045 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) handling task: 56fad8e9-862f-4534-9a62-b848cc9ad58f begin, at: 1393428736045
>10:32:16,068 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) Message 56fad8e9-862f-4534-9a62-b848cc9ad58f completed.
>10:32:16,080 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-7) message finished: 56fad8e9-862f-4534-9a62-b848cc9ad58f, took 34
>10:32:16,080 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) msg: 56fad8e9-862f-4534-9a62-b848cc9ad58f handling task completed, results:true, at: 1393428736080, took:35
>10:32:16,081 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:16,081 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:16,083 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:16,083 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>10:32:16,111 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:16,111 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:16,141 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 570e555b-7caf-4402-92b0-3bd9b8ddeeca
>10:32:16,142 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 570e555b-7caf-4402-92b0-3bd9b8ddeeca handling task scheduled
>10:32:16,142 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 31ms
>10:32:16,142 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) handling task: 570e555b-7caf-4402-92b0-3bd9b8ddeeca begin, at: 1393428736142
>10:32:16,158 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) Message 570e555b-7caf-4402-92b0-3bd9b8ddeeca completed.
>10:32:16,176 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-8) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--OFFLINE
>10:32:16,176 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) msg: 570e555b-7caf-4402-92b0-3bd9b8ddeeca handling task completed, results:true, at: 1393428736176, took:34
>10:32:16,177 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:16,177 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:16,179 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:16,179 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>10:32:46,045 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:46,045 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:46,086 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 5e3e9aff-0b5f-4a72-a53d-26569f7e0cbc
>10:32:46,086 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 5e3e9aff-0b5f-4a72-a53d-26569f7e0cbc handling task scheduled
>10:32:46,086 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 41ms
>10:32:46,086 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-4) handling task: 5e3e9aff-0b5f-4a72-a53d-26569f7e0cbc begin, at: 1393428766086
>10:32:46,177 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:46,177 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:46,187 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 8f4f6e0e-c634-4295-88e1-0ed36d40d02f
>10:32:46,188 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 8f4f6e0e-c634-4295-88e1-0ed36d40d02f handling task scheduled
>10:32:46,188 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 11ms
>10:32:46,188 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-5) handling task: 8f4f6e0e-c634-4295-88e1-0ed36d40d02f begin, at: 1393428766188
>10:32:46,250 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-4) Message 5e3e9aff-0b5f-4a72-a53d-26569f7e0cbc completed.
>10:32:46,348 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-4) message finished: 5e3e9aff-0b5f-4a72-a53d-26569f7e0cbc, took 261
>10:32:46,348 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-4) msg: 5e3e9aff-0b5f-4a72-a53d-26569f7e0cbc handling task completed, results:true, at: 1393428766348, took:262
>10:32:46,349 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:46,349 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:46,353 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-5) Message 8f4f6e0e-c634-4295-88e1-0ed36d40d02f completed.
>10:32:46,415 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-5) message finished: 8f4f6e0e-c634-4295-88e1-0ed36d40d02f, took 226
>10:32:46,415 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-5) msg: 8f4f6e0e-c634-4295-88e1-0ed36d40d02f handling task completed, results:true, at: 1393428766415, took:227
>10:32:46,415 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:46,415 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 66ms
>10:32:46,446 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:46,446 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:46,448 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:46,448 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>10:32:46,560 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:46,560 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:46,595 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 2bc1f9f9-b045-4ddb-b2ac-7d797d09f3f5
>10:32:46,596 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 2bc1f9f9-b045-4ddb-b2ac-7d797d09f3f5 handling task scheduled
>10:32:46,596 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 36ms
>10:32:46,596 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) handling task: 2bc1f9f9-b045-4ddb-b2ac-7d797d09f3f5 begin, at: 1393428766596
>10:32:46,619 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) Message 2bc1f9f9-b045-4ddb-b2ac-7d797d09f3f5 completed.
>10:32:46,655 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-9) message finished: 2bc1f9f9-b045-4ddb-b2ac-7d797d09f3f5, took 58
>10:32:46,661 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) msg: 2bc1f9f9-b045-4ddb-b2ac-7d797d09f3f5 handling task completed, results:true, at: 1393428766661, took:65
>10:32:46,673 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:46,673 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:46,676 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:46,676 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>10:32:46,704 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:46,704 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:46,740 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: f618e6d0-a85a-4ccb-b67d-4c3edfbbbc33
>10:32:46,741 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: f618e6d0-a85a-4ccb-b67d-4c3edfbbbc33 handling task scheduled
>10:32:46,741 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 37ms
>10:32:46,741 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) handling task: f618e6d0-a85a-4ccb-b67d-4c3edfbbbc33 begin, at: 1393428766741
>10:32:46,799 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) Message f618e6d0-a85a-4ccb-b67d-4c3edfbbbc33 completed.
>10:32:47,008 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-10) message finished: f618e6d0-a85a-4ccb-b67d-4c3edfbbbc33, took 266
>10:32:47,008 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) msg: f618e6d0-a85a-4ccb-b67d-4c3edfbbbc33 handling task completed, results:true, at: 1393428767008, took:267
>10:32:47,010 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:47,010 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:47,039 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:47,040 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 30ms
>10:32:47,069 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:47,069 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:47,104 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: bf8b1cc6-26f1-42e5-a627-283414c2d96f
>10:32:47,105 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: bf8b1cc6-26f1-42e5-a627-283414c2d96f handling task scheduled
>10:32:47,105 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 36ms
>10:32:47,105 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) handling task: bf8b1cc6-26f1-42e5-a627-283414c2d96f begin, at: 1393428767105
>10:32:47,134 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) Message bf8b1cc6-26f1-42e5-a627-283414c2d96f completed.
>10:32:47,164 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-11) message finished: bf8b1cc6-26f1-42e5-a627-283414c2d96f, took 58
>10:32:47,164 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) msg: bf8b1cc6-26f1-42e5-a627-283414c2d96f handling task completed, results:true, at: 1393428767164, took:59
>10:32:47,165 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:47,165 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:47,167 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:47,167 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>10:32:47,189 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:47,189 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:47,199 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 743fa5b3-af12-4e6c-8153-c2324acac826
>10:32:47,200 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 743fa5b3-af12-4e6c-8153-c2324acac826 handling task scheduled
>10:32:47,200 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 11ms
>10:32:47,200 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) handling task: 743fa5b3-af12-4e6c-8153-c2324acac826 begin, at: 1393428767200
>10:32:47,230 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) Message 743fa5b3-af12-4e6c-8153-c2324acac826 completed.
>10:32:47,243 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-12) message finished: 743fa5b3-af12-4e6c-8153-c2324acac826, took 41
>10:32:47,243 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) msg: 743fa5b3-af12-4e6c-8153-c2324acac826 handling task completed, results:true, at: 1393428767243, took:43
>10:32:47,243 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:32:47,244 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:32:47,245 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:32:47,245 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>10:33:21,648 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:21,648 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:21,660 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 91f1fe75-90b0-4acb-aa21-1d099c5bf4fa
>10:33:21,671 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message starts with timeout 30000 MsgId: 91f1fe75-90b0-4acb-aa21-1d099c5bf4fa
>10:33:21,671 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 91f1fe75-90b0-4acb-aa21-1d099c5bf4fa handling task scheduled
>10:33:21,671 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-6) handling task: 91f1fe75-90b0-4acb-aa21-1d099c5bf4fa begin, at: 1393428801671
>10:33:21,671 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 23ms
>10:33:25,817 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:25,817 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:25,858 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 9d824add-44da-4014-a6a1-76bb02be9856
>10:33:25,869 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 9d824add-44da-4014-a6a1-76bb02be9856 handling task scheduled
>10:33:25,869 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 52ms
>10:33:25,869 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) handling task: 9d824add-44da-4014-a6a1-76bb02be9856 begin, at: 1393428805869
>10:33:25,916 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) Message 9d824add-44da-4014-a6a1-76bb02be9856 completed.
>10:33:25,948 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-13) message finished: 9d824add-44da-4014-a6a1-76bb02be9856, took 78
>10:33:25,948 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) msg: 9d824add-44da-4014-a6a1-76bb02be9856 handling task completed, results:true, at: 1393428805948, took:79
>10:33:25,949 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:25,949 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:25,952 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>10:33:51,671 WARN  [org.apache.helix.messaging.handling.MessageTimeoutTask] (Timer-1) Message time out, canceling. id:91f1fe75-90b0-4acb-aa21-1d099c5bf4fa timeout : 30000
>10:33:51,847 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:51,847 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:51,870 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: f3729de7-918b-410e-811a-27c846fe6e18
>10:33:51,870 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: f3729de7-918b-410e-811a-27c846fe6e18 handling task scheduled
>10:33:51,871 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 24ms
>10:33:51,871 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) handling task: f3729de7-918b-410e-811a-27c846fe6e18 begin, at: 1393428831870
>10:33:51,900 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) Message f3729de7-918b-410e-811a-27c846fe6e18 completed.
>10:33:51,954 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-14) message finished: f3729de7-918b-410e-811a-27c846fe6e18, took 83
>10:33:51,954 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) msg: f3729de7-918b-410e-811a-27c846fe6e18 handling task completed, results:true, at: 1393428831954, took:84
>10:33:51,955 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:51,955 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:51,958 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>10:33:51,972 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:51,973 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:52,047 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 99e9a5fe-392e-4927-9112-37b4e2395a29
>10:33:52,048 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 99e9a5fe-392e-4927-9112-37b4e2395a29 handling task scheduled
>10:33:52,048 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 76ms
>10:33:52,048 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-15) handling task: 99e9a5fe-392e-4927-9112-37b4e2395a29 begin, at: 1393428832048
>10:33:52,062 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-15) Message 99e9a5fe-392e-4927-9112-37b4e2395a29 completed.
>10:33:52,247 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-15) message finished: 99e9a5fe-392e-4927-9112-37b4e2395a29, took 198
>10:33:52,247 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-15) msg: 99e9a5fe-392e-4927-9112-37b4e2395a29 handling task completed, results:true, at: 1393428832247, took:199
>10:33:52,248 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:52,248 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:52,251 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>10:33:52,411 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:52,411 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:52,433 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: a5b7e668-f214-4df1-b6f8-ad76ba029919
>10:33:52,447 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: a5b7e668-f214-4df1-b6f8-ad76ba029919 handling task scheduled
>10:33:52,447 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 36ms
>10:33:52,447 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-7) handling task: a5b7e668-f214-4df1-b6f8-ad76ba029919 begin, at: 1393428832447
>10:33:52,580 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-7) Message a5b7e668-f214-4df1-b6f8-ad76ba029919 completed.
>10:33:52,630 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-7) message finished: a5b7e668-f214-4df1-b6f8-ad76ba029919, took 182
>10:33:52,630 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-7) msg: a5b7e668-f214-4df1-b6f8-ad76ba029919 handling task completed, results:true, at: 1393428832630, took:183
>10:33:52,631 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:52,631 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:52,690 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 1002e6d8-f2f4-4fdf-b610-f5ae570058ff
>10:33:52,690 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 1002e6d8-f2f4-4fdf-b610-f5ae570058ff handling task scheduled
>10:33:52,690 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 59ms
>10:33:52,690 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-8) handling task: 1002e6d8-f2f4-4fdf-b610-f5ae570058ff begin, at: 1393428832690
>10:33:52,811 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:52,811 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:52,823 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-8) Message 1002e6d8-f2f4-4fdf-b610-f5ae570058ff completed.
>10:33:52,872 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 64f3c9d6-3291-4dcb-b005-c555ab0af861
>10:33:52,873 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 64f3c9d6-3291-4dcb-b005-c555ab0af861 handling task scheduled
>10:33:52,873 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 62ms
>10:33:52,873 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-16) handling task: 64f3c9d6-3291-4dcb-b005-c555ab0af861 begin, at: 1393428832873
>10:33:52,892 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-15-thread-6) 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))
>10:33:52,894 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-8) message finished: 1002e6d8-f2f4-4fdf-b610-f5ae570058ff, took 203
>10:33:52,902 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-8) msg: 1002e6d8-f2f4-4fdf-b610-f5ae570058ff handling task completed, results:true, at: 1393428832902, took:212
>10:33:52,918 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:52,918 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:52,918 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-16) Message 64f3c9d6-3291-4dcb-b005-c555ab0af861 completed.
>10:33:52,954 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-16) message finished: 64f3c9d6-3291-4dcb-b005-c555ab0af861, took 80
>10:33:52,954 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-16) msg: 64f3c9d6-3291-4dcb-b005-c555ab0af861 handling task completed, results:true, at: 1393428832954, took:81
>10:33:52,956 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 38ms
>10:33:52,957 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:52,957 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:52,991 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 34ms
>10:33:53,040 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-15-thread-6) JOSQL query return 2 rows
>10:33:53,057 INFO  [org.apache.helix.messaging.DefaultMessagingService] (pool-15-thread-6) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>10:33:53,169 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-15-thread-6) 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))
>10:33:53,213 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-15-thread-6) JOSQL query return 2 rows
>10:33:53,215 INFO  [org.apache.helix.messaging.DefaultMessagingService] (pool-15-thread-6) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>10:33:53,401 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:53,401 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:53,415 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: 7288c11e-50b3-43e5-8ea0-eab39456baa4
>10:33:53,415 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: 7288c11e-50b3-43e5-8ea0-eab39456baa4 handling task scheduled
>10:33:53,415 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 14ms
>10:33:53,415 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-17) handling task: 7288c11e-50b3-43e5-8ea0-eab39456baa4 begin, at: 1393428833415
>10:33:53,425 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-6) Message 91f1fe75-90b0-4acb-aa21-1d099c5bf4fa completed.
>10:33:53,439 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-17) Message 7288c11e-50b3-43e5-8ea0-eab39456baa4 completed.
>10:33:53,469 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-6) Sending reply for message 0f9e2706-f2e3-4a61-8cff-5e7f32a1a86e
>10:33:53,499 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-17) message finished: 7288c11e-50b3-43e5-8ea0-eab39456baa4, took 83
>10:33:53,499 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-17) msg: 7288c11e-50b3-43e5-8ea0-eab39456baa4 handling task completed, results:true, at: 1393428833499, took:84
>10:33:53,523 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:53,532 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:53,559 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-6) message finished: 91f1fe75-90b0-4acb-aa21-1d099c5bf4fa, took 31887
>10:33:53,559 WARN  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-6) message 91f1fe75-90b0-4acb-aa21-1d099c5bf4fa not found in task map
>10:33:53,559 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-6) msg: 91f1fe75-90b0-4acb-aa21-1d099c5bf4fa handling task completed, results:true, at: 1393428833559, took:31888
>10:33:53,560 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:33:53,560 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 37ms
>10:33:53,739 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:53,739 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:53,756 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Scheduling message: b34928d2-036f-4c05-93f7-135a9f82933e
>10:33:53,762 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) Message: b34928d2-036f-4c05-93f7-135a9f82933e handling task scheduled
>10:33:53,763 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 24ms
>10:33:53,763 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-18) handling task: b34928d2-036f-4c05-93f7-135a9f82933e begin, at: 1393428833763
>10:33:53,774 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-18) Message b34928d2-036f-4c05-93f7-135a9f82933e completed.
>10:33:53,804 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-18) message finished: b34928d2-036f-4c05-93f7-135a9f82933e, took 40
>10:33:53,804 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-18) msg: b34928d2-036f-4c05-93f7-135a9f82933e handling task completed, results:true, at: 1393428833804, took:41
>10:33:53,805 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>10:33:53,805 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@554ef362
>10:33:53,807 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-198-localhost:2181) No Messages to process
>10:33:53,807 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-198-localhost:2181) 198 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms
>10:34:56,749 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) JBAS010409: Unbound data source [java:jboss/datasources/ExampleDS]
>10:34:56,750 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 90) MODCLUSTER000002: Initiating mod_cluster shutdown
>10:34:56,751 INFO  [org.jboss.jaxr] (MSC service thread 1-10) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory
>10:34:56,752 INFO  [org.apache.coyote.http11] (MSC service thread 1-6) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost/127.0.0.1:8280
>10:34:56,752 INFO  [org.apache.coyote.http11] (MSC service thread 1-6) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost/127.0.0.1:8280
>10:34:56,752 INFO  [org.apache.catalina.core] (MSC service thread 1-1) JBWEB001079: Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started
>10:34:56,756 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-14) JBAS010409: Unbound data source [java:jboss/datasources/brms-jdbc-dsQuartz]
>10:34:56,770 INFO  [org.apache.coyote.ajp] (MSC service thread 1-7) JBWEB003048: Pausing Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8209
>10:34:56,771 INFO  [org.apache.coyote.ajp] (MSC service thread 1-7) JBWEB003051: Stopping Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8209
>10:34:56,767 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 88) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/queue/KIE.AUDIT
>10:34:56,771 INFO  [org.jboss.web] (ServerService Thread Pool -- 95) JBAS018224: Unregister web context: /business-central
>10:34:56,883 INFO  [org.jboss.as.jpa] (ServerService Thread Pool -- 92) JBAS011403: Stopping Persistence Unit Service 'business-central.war#org.jbpm.domain'
>10:34:56,886 INFO  [org.jboss.weld.deployer] (MSC service thread 1-10) JBAS016009: Stopping weld service for deployment business-central.war
>10:34:56,885 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-13) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA]
>10:34:56,886 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) JBAS010409: Unbound data source [java:jboss/datasources/brms-jdbc-ds]
>10:34:56,891 INFO  [org.hornetq.ra] (MSC service thread 1-8) HQ151003: HornetQ resource adaptor stopped
>10:34:56,898 ERROR [org.hornetq.core.server] (Thread-10 (HornetQ-client-global-threads-502759701)) HQ224037: cluster connection Failed to handle message: java.lang.IllegalStateException: Cannot find binding for jms.queue.KIE.TASKc1d3c2ec-9efa-11e3-8bf3-a59d998dfa93
>	at org.hornetq.core.server.cluster.impl.ClusterConnectionImpl$MessageFlowRecordImpl.doConsumerClosed(ClusterConnectionImpl.java:1567) [hornetq-server-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.core.server.cluster.impl.ClusterConnectionImpl$MessageFlowRecordImpl.onMessage(ClusterConnectionImpl.java:1285) [hornetq-server-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:1114) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.core.client.impl.ClientConsumerImpl.access$500(ClientConsumerImpl.java:57) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:1249) [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_45]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45]
>
>10:34:56,899 ERROR [org.hornetq.core.server] (Thread-10 (HornetQ-client-global-threads-502759701)) HQ224037: cluster connection Failed to handle message: java.lang.IllegalStateException: Cannot find binding for jms.queue.KIE.SESSIONc1d3c2ec-9efa-11e3-8bf3-a59d998dfa93
>	at org.hornetq.core.server.cluster.impl.ClusterConnectionImpl$MessageFlowRecordImpl.doConsumerClosed(ClusterConnectionImpl.java:1567) [hornetq-server-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.core.server.cluster.impl.ClusterConnectionImpl$MessageFlowRecordImpl.onMessage(ClusterConnectionImpl.java:1285) [hornetq-server-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:1114) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.core.client.impl.ClientConsumerImpl.access$500(ClientConsumerImpl.java:57) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2]
>	at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:1249) [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_45]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45]
>
>10:34:56,910 INFO  [org.hornetq.core.server] (Thread-25 (HornetQ-server-HornetQServerImpl::serverUUID=c321ed64-9efa-11e3-a171-9f9238f4ec4e-1515590220)) HQ221029: stopped bridge sf.my-cluster.c1d3c2ec-9efa-11e3-8bf3-a59d998dfa93
>10:34:56,968 INFO  [org.jboss.errai.cdi.server.events.ShutdownEventObserver] (MSC service thread 1-10) Shutting down CDI-to-ErraiBus event bridge
>10:34:56,974 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-10) disconnect nodeTwo_12346(PARTICIPANT) from bpms-cluster
>10:34:56,975 INFO  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-10) Stop ParticipantHealthReportTimerTask
>10:34:56,975 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-10) shutting down TaskExecutor
>10:34:56,976 INFO  [org.hornetq.core.server] (MSC service thread 1-8) HQ221002: HornetQ Server version 2.3.5.Final-redhat-2 (Monster Bee, 123) [c321ed64-9efa-11e3-a171-9f9238f4ec4e] stopped
>10:34:56,976 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-10) 0 tasks are still in the threadpool for msgType USER_DEFINE_MSG
>10:34:56,978 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-10) 0 tasks are still in the threadpool for msgType STATE_TRANSITION
>10:34:56,978 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-10) 0 tasks are still in the threadpool for msgType TASK_REPLY
>10:34:56,978 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-10) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--OFFLINE
>10:34:56,979 INFO  [org.apache.helix.manager.zk.ZkClient] (MSC service thread 1-10) Closing zkclient: State:CONNECTED Timeout:30000 sessionid:0x1446ed02ea10007 local:/127.0.0.1:40929 remoteserver:localhost/127.0.0.1:2181 lastZxid:374 xid:664 sent:683 recv:735 queuedpkts:0 pendingresp:0 queuedevents:0
>10:34:56,979 INFO  [org.I0Itec.zkclient.ZkEventThread] (ZkClient-EventThread-198-localhost:2181) Terminate ZkClient event thread.
>10:34:56,989 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-10) Session: 0x1446ed02ea10007 closed
>10:34:56,989 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-15-EventThread) EventThread shut down
>10:34:56,990 INFO  [org.apache.helix.manager.zk.ZkClient] (MSC service thread 1-10) Closed zkclient
>10:34:56,990 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-10) Cluster manager: nodeTwo_12346 disconnected
>10:34:56,998 ERROR [org.jboss.weld.Bean] (MSC service thread 1-10) WELD-000019 Error destroying an instance Managed Bean [class org.kie.workbench.backend.ApplicationScopedProducer] with qualifiers [@Any @Default] of org.kie.workbench.backend.ApplicationScopedProducer@64f39ecb
>10:34:56,999 INFO  [org.jbpm.executor.impl.ExecutorImpl] (MSC service thread 1-10)  >>>>> Destroying Executor !!!
>10:34:57,470 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-7) JBAS015877: Stopped deployment business-central.war (runtime-name: business-central.war) in 730ms
>10:34:57,480 INFO  [org.jboss.as] (MSC service thread 1-13) JBAS015950: JBoss BPM Suite 6.0.1.GA (AS 7.2.1.Final-redhat-10) stopped in 737ms

Actions: View

Attachments on bug 1070607: 868353 | 868354 | 868355 | 868356 | 868357 | 868533