Attachment 873206 Details for Bug 1075181 – server log - node one

server log - node one

serverOne.log (text/x-log), 159.21 KB, created by Radovan Synek on 2014-03-11 16:28:13 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Radovan Synek

Created: 2014-03-11 16:28:13 UTC

Size: 159.21 KB

patch

obsolete

>05:25:44,461 INFO  [org.jboss.modules] (main) JBoss Modules version 1.2.2.Final-redhat-1
>05:25:44,984 INFO  [org.jboss.msc] (main) JBoss MSC version 1.0.4.GA-redhat-1
>05:25:45,270 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
>05:25:45,284 DEBUG [org.jboss.as.config] (MSC service thread 1-6) Configured system properties:
>	[Host Controller] = true
>	[Server:server-one] = 
>	awt.toolkit = sun.awt.X11.XToolkit
>	designerdataobjects = ${designerdataobjects:false}
>	file.encoding = UTF-8
>	file.encoding.pkg = sun.io
>	file.separator = /
>	java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment
>	java.awt.printerjob = sun.print.PSPrinterJob
>	java.class.path = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-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-balancer-single/jboss-eap-6.1/domain
>	jboss.domain.config.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/configuration
>	jboss.home.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1
>	jboss.host.name = dev80
>	jboss.modules.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/modules
>	jboss.node.name = master:server-one
>	jboss.qualified.host.name = dev80
>	jboss.server.base.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one
>	jboss.server.config.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/configuration
>	jboss.server.data.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/data
>	jboss.server.deploy.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/data/content
>	jboss.server.log.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/log
>	jboss.server.name = server-one
>	jboss.server.persist.config = true
>	jboss.server.temp.dir = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/tmp
>	line.separator = 
>
>	logging.configuration = file:/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/configuration/default-server-logging.properties
>	module.path = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/modules
>	org.jboss.boot.log.file = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/log/server.log
>	org.jboss.resolver.warning = true
>	org.jbpm.designer.perspective = ${org.jbpm.designer.perspective:full}
>	org.kie.example = ${org.kie.example:true}
>	org.xml.sax.driver = __redirected.__XMLReaderFactory
>	os.arch = amd64
>	os.name = Linux
>	os.version = 2.6.32-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-balancer-single/jboss-eap-6.1/jboss-modules.jar -mp /mnt/hudson_workspace/workspace/bpms-clustering-balancer-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-balancer-single/jboss-eap-6.1
>	user.home = /home/hudson
>	user.language = en
>	user.name = hudson
>	user.timezone = America/New_York
>05:25:45,291 DEBUG [org.jboss.as.config] (MSC service thread 1-6) VM Arguments: -D[Server:server-one] -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-balancer-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-balancer-single/jboss-eap-6.1/domain/servers/server-one/log -Djboss.server.temp.dir=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/tmp -Djboss.server.data.dir=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/data -Dorg.jboss.boot.log.file=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/log/server.log -Dlogging.configuration=file:/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/configuration/default-server-logging.properties 
>05:25:45,500 INFO  [org.xnio] (MSC service thread 1-4) XNIO Version 3.0.7.GA-redhat-1
>05:25:45,511 INFO  [org.xnio.nio] (MSC service thread 1-4) XNIO NIO Implementation Version 3.0.7.GA-redhat-1
>05:25:45,527 INFO  [org.jboss.remoting] (MSC service thread 1-4) JBoss Remoting version 3.2.16.GA-redhat-1
>05:25:50,375 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 37) JBAS013171: Activating Security Subsystem
>05:25:50,439 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 42) JBAS011800: Activating Naming Subsystem
>05:25:50,433 INFO  [org.jboss.as.security] (MSC service thread 1-7) JBAS013170: Current PicketBox version=4.0.17.SP2-redhat-2
>05:25:50,501 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 56) JBAS010280: Activating Infinispan subsystem.
>05:25:50,549 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 33) JBAS015537: Activating WebServices Extension
>05:25:50,559 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 59) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
>05:25:50,681 INFO  [org.jboss.as.naming] (MSC service thread 1-6) JBAS011802: Starting Naming Service
>05:25:50,700 INFO  [org.jboss.as.configadmin] (ServerService Thread Pool -- 60) JBAS016200: Activating ConfigAdmin Subsystem
>05:25:50,712 INFO  [org.jboss.as.jacorb] (ServerService Thread Pool -- 55) JBAS016300: Activating JacORB Subsystem
>05:25:50,744 INFO  [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 50) JBAS010260: Activating JGroups subsystem.
>05:25:50,825 INFO  [org.jboss.as.jsf] (ServerService Thread Pool -- 47) JBAS012605: Activated the following JSF Implementations: [main, 1.2]
>05:25:50,972 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)
>05:25:50,984 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-3) JBAS015400: Bound mail session [java:jboss/mail/Default]
>05:25:50,984 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-5) JBAS015400: Bound mail session [java:/mail/jbpmMailSession]
>05:25:51,208 INFO  [org.jboss.as.connector.logging] (MSC service thread 1-1) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.19.Final-redhat-2)
>05:25:51,267 INFO  [org.jboss.jaxr] (MSC service thread 1-6) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory
>05:25:52,608 INFO  [org.apache.coyote.http11] (MSC service thread 1-7) JBWEB003001: Coyote HTTP/1.1 initializing on : http-localhost/127.0.0.1:8080
>05:25:52,618 INFO  [org.apache.coyote.ajp] (MSC service thread 1-8) JBWEB003046: Starting Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8009
>05:25:52,646 INFO  [org.apache.coyote.http11] (MSC service thread 1-7) JBWEB003000: Coyote HTTP/1.1 starting on: http-localhost/127.0.0.1:8080
>05:25:52,859 INFO  [org.jboss.ws.common.management] (MSC service thread 1-3) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.1.4.Final-redhat-7
>05:25:53,188 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000001: Initializing mod_cluster ${project.version}
>05:25:53,388 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000032: Listening to proxy advertisements on /224.0.1.105:23364
>05:25:53,522 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000011: jboss.web will use 4e6189af-0502-3305-8ff3-fad7fee8b516 as jvm-route
>05:25:53,510 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=true,backup=false,sharedStore=true,journalDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/data/messagingjournal,bindingsDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/data/messagingbindings,largeMessagesDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/data/messaginglargemessages,pagingDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/data/messagingpaging)
>05:25:53,528 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221006: Waiting to obtain live lock
>05:25:54,378 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221013: Using NIO Journal
>05:25:54,388 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.
>05:25:54,502 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.
>05:25:54,578 INFO  [org.jboss.as.jacorb] (MSC service thread 1-2) JBAS016330: CORBA ORB Service started
>05:25:55,165 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/datasources/brms-jdbc-dsQuartz]
>05:25:55,166 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
>05:25:55,171 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-8) JBAS010400: Bound data source [java:jboss/datasources/brms-jdbc-ds]
>05:25:55,464 INFO  [org.jboss.as.jacorb] (MSC service thread 1-1) JBAS016328: CORBA Naming Service started
>05:25:55,470 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221034: Waiting to obtain live lock
>05:25:55,471 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221035: Live Server Obtained live lock
>05:25:55,939 INFO  [org.jboss.as.remoting] (MSC service thread 1-5) JBAS017100: Listening on 127.0.0.1:4447
>05:25:59,107 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221020: Started Netty Acceptor version 3.6.6.Final-redhat-1-fd3c6b7 127.0.0.1:5455 for CORE protocol
>05:25:59,119 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221020: Started Netty Acceptor version 3.6.6.Final-redhat-1-fd3c6b7 127.0.0.1:5445 for CORE protocol
>05:25:59,186 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221007: Server is now live
>05:25:59,187 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221001: HornetQ Server version 2.3.5.Final-redhat-2 (Monster Bee, 123) [fa292441-a835-11e3-b835-6f64b395dc0d] 
>05:25:59,296 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
>05:25:59,300 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 63) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory
>05:25:59,603 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-3) JBAS010406: Registered connection factory java:/JmsXA
>05:25:59,897 INFO  [org.hornetq.ra] (MSC service thread 1-3) HornetQ resource adaptor started
>05:25:59,898 INFO  [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-3) IJ020002: Deployed: file://RaActivatorhornetq-ra
>05:25:59,916 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-4) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA]
>05:26:00,250 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss BPM Suite 6.0.1.GA (AS 7.2.1.Final-redhat-10) started in 16812ms - Started 158 of 279 services (118 services are passive or on-demand)
>05:26:07,794 INFO  [org.hornetq.core.server] (Thread-6 (HornetQ-server-HornetQServerImpl::serverUUID=fa292441-a835-11e3-b835-6f64b395dc0d-1797761180)) HQ221027: Bridge ClusterConnectionBridge@4e80fecf [name=sf.my-cluster.0043f207-a836-11e3-aee6-93f258a2f581, queue=QueueImpl[name=sf.my-cluster.0043f207-a836-11e3-aee6-93f258a2f581, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=fa292441-a835-11e3-b835-6f64b395dc0d]]@76d8f04c targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@4e80fecf [name=sf.my-cluster.0043f207-a836-11e3-aee6-93f258a2f581, queue=QueueImpl[name=sf.my-cluster.0043f207-a836-11e3-aee6-93f258a2f581, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=fa292441-a835-11e3-b835-6f64b395dc0d]]@76d8f04c targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@90286409[nodeUUID=fa292441-a835-11e3-b835-6f64b395dc0d, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost, address=jms, server=HornetQServerImpl::serverUUID=fa292441-a835-11e3-b835-6f64b395dc0d])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost], discoveryGroupConfiguration=null]] is connected
>05:28:03,668 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-4) JBAS015876: Starting deployment of "business-central.war" (runtime-name: "business-central.war")
>05:28:42,559 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015960: Class Path entry xalan-2.7.1-redhat-3.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-4.jar  does not point to a valid jar for a Class-Path reference.
>05:28:42,565 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015960: Class Path entry serializer-2.7.1-redhat-3.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-4.jar  does not point to a valid jar for a Class-Path reference.
>05:28:42,565 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015960: Class Path entry xercesImpl-2.9.1-redhat-4.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-4.jar  does not point to a valid jar for a Class-Path reference.
>05:28:42,566 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015960: Class Path entry xml-apis-1.3.04.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-4.jar  does not point to a valid jar for a Class-Path reference.
>05:28:42,566 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015960: Class Path entry commons-lang-2.6-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-4.jar  does not point to a valid jar for a Class-Path reference.
>05:28:42,567 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015960: Class Path entry commons-io-2.1-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-4.jar  does not point to a valid jar for a Class-Path reference.
>05:28:42,568 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015960: Class Path entry log4j-1.2.16-redhat-2.jar in /content/business-central.war/WEB-INF/lib/jbpmmigration-0.12-redhat-4.jar  does not point to a valid jar for a Class-Path reference.
>05:28:43,895 INFO  [org.jboss.as.jpa] (MSC service thread 1-1) JBAS011401: Read persistence.xml for org.jbpm.domain
>05:28:46,529 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("com.google.guava:main") which may be changed or removed in future versions without notice.
>05:28:46,530 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("com.google.guava:main") which may be changed or removed in future versions without notice.
>05:28:46,531 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("com.h2database.h2:main") which may be changed or removed in future versions without notice.
>05:28:46,531 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("com.h2database.h2:main") which may be changed or removed in future versions without notice.
>05:28:46,556 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("com.sun.xml.bind:main") which may be changed or removed in future versions without notice.
>05:28:46,556 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("com.sun.xml.bind:main") which may be changed or removed in future versions without notice.
>05:28:46,561 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.antlr:main") which may be changed or removed in future versions without notice.
>05:28:46,562 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.antlr:main") which may be changed or removed in future versions without notice.
>05:28:46,569 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.cli:main") which may be changed or removed in future versions without notice.
>05:28:46,569 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.cli:main") which may be changed or removed in future versions without notice.
>05:28:46,570 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.codec:main") which may be changed or removed in future versions without notice.
>05:28:46,571 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.codec:main") which may be changed or removed in future versions without notice.
>05:28:46,571 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.collections:main") which may be changed or removed in future versions without notice.
>05:28:46,572 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.collections:main") which may be changed or removed in future versions without notice.
>05:28:46,604 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.io:main") which may be changed or removed in future versions without notice.
>05:28:46,604 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.io:main") which may be changed or removed in future versions without notice.
>05:28:46,605 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.lang:main") which may be changed or removed in future versions without notice.
>05:28:46,606 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.commons.lang:main") which may be changed or removed in future versions without notice.
>05:28:46,725 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.cxf.impl:main") which may be changed or removed in future versions without notice.
>05:28:46,726 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.cxf.impl:main") which may be changed or removed in future versions without notice.
>05:28:46,732 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.httpcomponents:main") which may be changed or removed in future versions without notice.
>05:28:46,741 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.httpcomponents:main") which may be changed or removed in future versions without notice.
>05:28:46,743 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.james.mime4j:main") which may be changed or removed in future versions without notice.
>05:28:46,744 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.james.mime4j:main") which may be changed or removed in future versions without notice.
>05:28:46,752 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.neethi:main") which may be changed or removed in future versions without notice.
>05:28:46,753 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.neethi:main") which may be changed or removed in future versions without notice.
>05:28:46,755 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.velocity:main") which may be changed or removed in future versions without notice.
>05:28:46,756 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.velocity:main") which may be changed or removed in future versions without notice.
>05:28:46,758 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.xml-resolver:main") which may be changed or removed in future versions without notice.
>05:28:46,758 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.apache.xml-resolver:main") which may be changed or removed in future versions without notice.
>05:28:46,762 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-core-asl:main") which may be changed or removed in future versions without notice.
>05:28:46,763 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-core-asl:main") which may be changed or removed in future versions without notice.
>05:28:46,765 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-jaxrs:main") which may be changed or removed in future versions without notice.
>05:28:46,766 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-jaxrs:main") which may be changed or removed in future versions without notice.
>05:28:46,768 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-mapper-asl:main") which may be changed or removed in future versions without notice.
>05:28:46,768 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-mapper-asl:main") which may be changed or removed in future versions without notice.
>05:28:46,770 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-xc:main") which may be changed or removed in future versions without notice.
>05:28:46,771 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jackson.jackson-xc:main") which may be changed or removed in future versions without notice.
>05:28:46,772 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jettison:main") which may be changed or removed in future versions without notice.
>05:28:46,773 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.jettison:main") which may be changed or removed in future versions without notice.
>05:28:46,773 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.woodstox:main") which may be changed or removed in future versions without notice.
>05:28:46,774 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.codehaus.woodstox:main") which may be changed or removed in future versions without notice.
>05:28:46,783 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.dom4j:main") which may be changed or removed in future versions without notice.
>05:28:46,783 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.dom4j:main") which may be changed or removed in future versions without notice.
>05:28:46,798 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.javassist:main") which may be changed or removed in future versions without notice.
>05:28:46,799 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.javassist:main") which may be changed or removed in future versions without notice.
>05:28:46,799 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.jboss.as.web:main") which may be changed or removed in future versions without notice.
>05:28:46,800 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.jboss.as.web:main") which may be changed or removed in future versions without notice.
>05:28:46,800 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.jboss.netty:main") which may be changed or removed in future versions without notice.
>05:28:46,801 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-3) JBAS018567: Deployment "deployment.business-central.war" is using a private module ("org.jboss.netty:main") which may be changed or removed in future versions without notice.
>05:28:46,821 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.jdom:main") which may be changed or removed in future versions without notice.
>05:28:46,822 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.jdom:main") which may be changed or removed in future versions without notice.
>05:28:46,822 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.jgroups:main") which may be changed or removed in future versions without notice.
>05:28:46,823 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.jgroups:main") which may be changed or removed in future versions without notice.
>05:28:46,824 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.joda.time:main") which may be changed or removed in future versions without notice.
>05:28:46,825 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.joda.time:main") which may be changed or removed in future versions without notice.
>05:28:46,853 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.yaml.snakeyaml:main") which may be changed or removed in future versions without notice.
>05:28:46,854 WARN  [org.jboss.as.dependency.unsupported] (MSC service thread 1-3) JBAS018568: Deployment "deployment.business-central.war" is using an unsupported module ("org.yaml.snakeyaml:main") which may be changed or removed in future versions without notice.
>05:28:47,722 INFO  [org.jboss.weld.deployer] (MSC service thread 1-1) JBAS016002: Processing weld deployment business-central.war
>05:28:48,231 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-1) 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
>
>05:28:49,166 INFO  [org.jboss.weld.deployer] (MSC service thread 1-7) JBAS016005: Starting Services for CDI deployment: business-central.war
>05:28:49,494 INFO  [org.jboss.weld.Version] (MSC service thread 1-7) WELD-000900 1.1.13 (redhat)
>05:28:49,880 INFO  [org.jboss.as.jpa] (ServerService Thread Pool -- 64) JBAS011402: Starting Persistence Unit Service 'business-central.war#org.jbpm.domain'
>05:28:49,883 INFO  [org.jboss.weld.deployer] (MSC service thread 1-7) JBAS016008: Starting weld service for deployment business-central.war
>05:28:49,905 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 65) HQ221003: trying to deploy queue jms.queue.KIE.SESSION
>05:28:50,044 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.SESSION
>05:28:50,046 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.SESSION.ALL
>05:28:50,046 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 66) HQ221003: trying to deploy queue jms.queue.KIE.AUDIT
>05:28:50,050 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.AUDIT
>05:28:50,052 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.AUDIT.ALL
>05:28:50,052 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 68) HQ221003: trying to deploy queue jms.queue.KIE.TASK
>05:28:50,057 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.TASK
>05:28:50,058 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.TASK.ALL
>05:28:50,059 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 67) HQ221003: trying to deploy queue jms.queue.KIE.RESPONSE
>05:28:50,077 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 67) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.RESPONSE
>05:28:50,150 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 67) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.RESPONSE.ALL
>05:28:50,361 INFO  [org.jboss.as.ejb3] (MSC service thread 1-8) JBAS014142: Started message driven bean 'KieSessionRequesMessageBean' with 'hornetq-ra' resource adapter
>05:28:50,422 INFO  [org.jboss.as.ejb3] (MSC service thread 1-4) JBAS014142: Started message driven bean 'JMSAuditProcessor' with 'hornetq-ra' resource adapter
>05:28:50,425 INFO  [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014142: Started message driven bean 'TaskServiceRequesMessageBean' with 'hornetq-ra' resource adapter
>05:28:50,990 INFO  [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 64) HCANN000001: Hibernate Commons Annotations {4.0.1.Final-redhat-2}
>05:28:51,035 INFO  [org.hibernate.Version] (ServerService Thread Pool -- 64) HHH000412: Hibernate Core {4.2.0.SP1-redhat-1}
>05:28:51,042 INFO  [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 64) HHH000206: hibernate.properties not found
>05:28:51,059 INFO  [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 64) HHH000021: Bytecode provider name : javassist
>05:28:51,177 INFO  [org.hibernate.ejb.Ejb3Configuration] (ServerService Thread Pool -- 64) HHH000204: Processing PersistenceUnitInfo [
>	name: org.jbpm.domain
>	...]
>05:28:55,430 INFO  [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (ServerService Thread Pool -- 64) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
>05:28:56,169 INFO  [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 64) HHH000400: Using dialect: org.hibernate.dialect.MySQL5InnoDBDialect
>05:28:56,216 INFO  [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (ServerService Thread Pool -- 64) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory
>05:28:56,235 INFO  [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (ServerService Thread Pool -- 64) HHH000397: Using ASTQueryTranslatorFactory
>05:28:56,391 INFO  [org.hibernate.validator.internal.util.Version] (ServerService Thread Pool -- 64) HV000001: Hibernate Validator 4.3.1.Final-redhat-1
>05:28:58,374 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000228: Running hbm2ddl schema update
>05:28:58,374 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000102: Fetching database metadata
>05:28:58,384 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000396: Updating schema
>05:28:58,391 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Attachment
>05:28:58,394 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BAMTaskSummary
>05:28:58,396 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BooleanExpression
>05:28:58,398 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Content
>05:28:58,400 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ContextMappingInfo
>05:28:58,402 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationKeyInfo
>05:28:58,404 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationPropertyInfo
>05:28:58,406 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Deadline
>05:28:58,408 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Delegation_delegates
>05:28:58,409 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ErrorInfo
>05:28:58,411 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Escalation
>05:28:58,413 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: EventTypes
>05:28:58,414 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: I18NText
>05:28:58,416 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NodeInstanceLog
>05:28:58,418 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification
>05:28:58,420 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_BAs
>05:28:58,421 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_Recipients
>05:28:58,424 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_email_header
>05:28:58,426 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: OrganizationalEntity
>05:28:58,428 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_BAs
>05:28:58,429 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_ExclOwners
>05:28:58,431 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_PotOwners
>05:28:58,433 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Recipients
>05:28:58,435 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Stakeholders
>05:28:58,448 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceInfo
>05:28:58,453 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceLog
>05:28:58,458 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment
>05:28:58,463 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment_potentialOwners
>05:28:58,467 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: RequestInfo
>05:28:58,472 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: SessionInfo
>05:28:58,477 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Task
>05:28:58,483 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskDef
>05:28:58,488 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskEvent
>05:28:58,492 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: VariableInstanceLog
>05:28:58,494 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: WorkItemInfo
>05:28:58,499 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: email_header
>05:28:58,502 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: task_comment
>05:28:58,504 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Attachment
>05:28:58,507 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BAMTaskSummary
>05:28:58,509 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BooleanExpression
>05:28:58,512 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Content
>05:28:58,530 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ContextMappingInfo
>05:28:58,532 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationKeyInfo
>05:28:58,535 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationPropertyInfo
>05:28:58,537 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Deadline
>05:28:58,541 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Delegation_delegates
>05:28:58,543 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ErrorInfo
>05:28:58,545 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Escalation
>05:28:58,547 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: EventTypes
>05:28:58,549 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: I18NText
>05:28:58,552 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NodeInstanceLog
>05:28:58,554 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification
>05:28:58,556 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_BAs
>05:28:58,559 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_Recipients
>05:28:58,561 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_email_header
>05:28:58,566 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: OrganizationalEntity
>05:28:58,570 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_BAs
>05:28:58,575 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_ExclOwners
>05:28:58,579 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_PotOwners
>05:28:58,583 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Recipients
>05:28:58,586 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Stakeholders
>05:28:58,588 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceInfo
>05:28:58,590 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceLog
>05:28:58,592 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment
>05:28:58,594 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment_potentialOwners
>05:28:58,597 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: RequestInfo
>05:28:58,599 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: SessionInfo
>05:28:58,601 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Task
>05:28:58,602 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskDef
>05:28:58,604 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskEvent
>05:28:58,606 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: VariableInstanceLog
>05:28:58,609 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: WorkItemInfo
>05:28:58,611 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: email_header
>05:28:58,613 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: task_comment
>05:28:59,163 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table I18NText (id bigint not null auto_increment, language varchar(255), shortText varchar(255), text longtext, Task_Subjects_Id bigint, Task_Names_Id bigint, Task_Descriptions_Id bigint, Reassignment_Documentation_Id bigint, Notification_Subjects_Id bigint, Notification_Names_Id bigint, Notification_Documentation_Id bigint, Notification_Descriptions_Id bigint, Deadline_Documentation_Id bigint, primary key (id)) ENGINE=InnoDB
>05:28:59,164 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'i18ntext' already exists
>05:28:59,198 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table NodeInstanceLog (id bigint not null auto_increment, connection varchar(255), log_date datetime, externalId varchar(255), nodeId varchar(255), nodeInstanceId varchar(255), nodeName varchar(255), nodeType varchar(255), processId varchar(255), processInstanceId bigint not null, type integer not null, workItemId bigint, primary key (id)) ENGINE=InnoDB
>05:28:59,198 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'nodeinstancelog' already exists
>05:28:59,234 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Notification (DTYPE varchar(31) not null, id bigint not null auto_increment, priority integer not null, Escalation_Notifications_Id bigint, primary key (id)) ENGINE=InnoDB
>05:28:59,234 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'notification' already exists
>05:28:59,270 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Notification_BAs (task_id bigint not null, entity_id varchar(255) not null) ENGINE=InnoDB
>05:28:59,270 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'notification_bas' already exists
>05:28:59,306 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Notification_Recipients (task_id bigint not null, entity_id varchar(255) not null) ENGINE=InnoDB
>05:28:59,306 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'notification_recipients' already exists
>05:28:59,342 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Notification_email_header (Notification_id bigint not null, emailHeaders_id bigint not null, mapkey varchar(255) not null, primary key (Notification_id, mapkey)) ENGINE=InnoDB
>05:28:59,342 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'notification_email_header' already exists
>05:28:59,377 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table OrganizationalEntity (DTYPE varchar(31) not null, id varchar(255) not null, primary key (id)) ENGINE=InnoDB
>05:28:59,378 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'organizationalentity' already exists
>05:28:59,413 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table PeopleAssignments_BAs (task_id bigint not null, entity_id varchar(255) not null) ENGINE=InnoDB
>05:28:59,414 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'peopleassignments_bas' already exists
>05:28:59,449 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table PeopleAssignments_ExclOwners (task_id bigint not null, entity_id varchar(255) not null) ENGINE=InnoDB
>05:28:59,450 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'peopleassignments_exclowners' already exists
>05:28:59,485 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table PeopleAssignments_PotOwners (task_id bigint not null, entity_id varchar(255) not null) ENGINE=InnoDB
>05:28:59,486 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'peopleassignments_potowners' already exists
>05:28:59,521 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table PeopleAssignments_Recipients (task_id bigint not null, entity_id varchar(255) not null) ENGINE=InnoDB
>05:28:59,521 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'peopleassignments_recipients' already exists
>05:28:59,557 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table PeopleAssignments_Stakeholders (task_id bigint not null, entity_id varchar(255) not null) ENGINE=InnoDB
>05:28:59,558 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) Table 'peopleassignments_stakeholders' already exists
>05:29:02,732 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000232: Schema update complete
>05:29:07,143 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-5) Solder Config XML provider starting...
>05:29:07,150 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-5) Loading XmlDocumentProvider: org.jboss.solder.config.xml.bootstrap.ResourceLoaderXmlDocumentProvider
>05:29:07,232 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-5) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar/META-INF/beans.xml
>05:29:07,256 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-5) Reading XML file: jar:file:/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/modules/system/layers/bpms/org/jbpm/main/jbpm-kie-services-6.0.2-redhat-4.jar!/META-INF/beans.xml
>05:29:07,266 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-5) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/uberfire-api-0.3.2-redhat-4.jar/META-INF/beans.xml
>05:29:07,274 INFO  [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-5) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/uberfire-security-server-0.3.2-redhat-4.jar/META-INF/beans.xml
>05:29:07,283 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) starting errai cdi ...
>05:29:07,326 INFO  [org.jboss.solder.Version] (MSC service thread 1-5) Solder 3.2.1.Final (build id: 3.2.1.Final)
>05:29:07,719 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.es.backend.server.ExecutorServiceEntryPointImpl
>05:29:07,970 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.m2repo.backend.server.M2RepoServiceImpl
>05:29:10,886 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.project.backend.server.ProjectServiceImpl
>05:29:10,899 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.project.backend.server.KModuleServiceImpl
>05:29:10,949 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.project.backend.server.POMServiceImpl
>05:29:11,075 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.defaulteditor.backend.server.DefaultEditorServiceImpl
>05:29:11,141 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @SessionScoped class org.jbpm.formModeler.renderer.backend.service.FormRenderingServiceImpl
>05:29:11,157 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.formModeler.renderer.backend.service.FormRendererIncluderServiceImpl
>05:29:11,358 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.FileNavigatorServiceImpl
>05:29:11,418 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.WorkbenchServicesImpl
>05:29:11,506 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.VFSServicesServerImpl
>05:29:11,582 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.plugin.RuntimePluginsServiceServerImpl
>05:29:11,590 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.repositories.RepositoryServiceEditorImpl
>05:29:11,640 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.organizationalunit.OrganizationalUnitServiceImpl
>05:29:11,651 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.uberfire.backend.server.repositories.RepositoryServiceImpl
>05:29:11,767 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.enums.backend.server.EnumServiceImpl
>05:29:12,469 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.projecteditor.backend.server.ProjectScreenServiceImpl
>05:29:14,891 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.datamodeller.backend.server.DataModelerServiceImpl
>05:29:16,736 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.dtable.backend.server.GuidedDecisionTableEditorServiceImpl
>05:29:16,780 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.scorecardxls.backend.server.ScoreCardXLSServiceImpl
>05:29:18,574 INFO  [org.jboss.errai.common.metadata.MetaDataScanner] (Thread-91) added class scanning extensions: org.jboss.errai.common.metadata.JbossVFsTypeHandler
>05:29:23,121 INFO  [org.jboss.errai.reflections.Reflections] (Thread-91) Reflections took 4533 ms to scan 95 urls, producing 2096 keys and 10015 values [using 2 cores]
>05:29:24,986 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.inbox.backend.server.InboxServiceImpl
>05:29:25,059 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.dsltext.backend.server.DSLTextEditorServiceImpl
>05:29:25,255 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.dtablexls.backend.server.DecisionTableXLSServiceImpl
>05:29:25,723 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.backend.source.SourceServicesImpl
>05:29:25,746 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.backend.security.KieWorkbenchSecurityServiceImpl
>05:29:25,753 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.backend.validation.ValidationServiceImpl
>05:29:25,814 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.scorecard.backend.server.GuidedScoreCardEditorServiceImpl
>05:29:26,082 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.FormModelerProcessStarterEntryPointImpl
>05:29:26,091 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.TypeRoleServiceEntryPointImpl
>05:29:26,114 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.TaskServiceEntryPointImpl
>05:29:26,140 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.FormServiceEntryPointImpl
>05:29:26,152 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.ht.backend.server.UserServiceEntryPointImpl
>05:29:26,541 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.bd.backend.server.DataServiceEntryPointImpl
>05:29:26,558 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.bd.backend.server.KieSessionEntryPointImpl
>05:29:26,699 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.console.ng.bd.backend.server.DeploymentManagerEntryPointImpl
>05:29:26,862 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.template.server.GuidedRuleTemplateEditorServiceImpl
>05:29:26,936 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.explorer.backend.server.ExplorerServiceImpl
>05:29:27,322 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.builder.BuildServiceImpl
>05:29:28,777 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.designer.server.service.DefaultDesignerAssetService
>05:29:29,636 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.workitems.backend.server.WorkItemsEditorServiceImpl
>05:29:29,691 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.drltext.backend.server.DRLTextEditorServiceImpl
>05:29:30,080 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.testscenario.backend.server.ScenarioTestEditorServiceImpl
>05:29:30,409 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.services.datamodel.backend.server.IncrementalDataModelServiceImpl
>05:29:30,489 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.globals.backend.server.GlobalsEditorServiceImpl
>05:29:30,546 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.rule.backend.server.GuidedRuleEditorServiceImpl
>05:29:30,585 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.drools.workbench.screens.guided.rule.backend.server.EnumDropdownServiceImpl
>05:29:30,626 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.config.ResourceConfigServiceImpl
>05:29:30,652 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.version.VersionServiceImpl
>05:29:30,704 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service class org.guvnor.common.services.backend.file.RenameServiceImpl
>05:29:30,726 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.metadata.MetadataServiceImpl
>05:29:30,742 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service class org.guvnor.common.services.backend.file.CopyServiceImpl
>05:29:30,751 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.config.AppConfigServiceImpl
>05:29:30,781 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service class org.guvnor.common.services.backend.file.DeleteServiceImpl
>05:29:30,828 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.metadata.CategoryServiceImpl
>05:29:30,878 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.guvnor.common.services.backend.rulenames.RuleNamesServiceImpl
>05:29:30,921 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.dashboard.renderer.service.impl.DashboardRendererServiceImpl
>05:29:31,065 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.jbpm.formModeler.panels.modeler.backend.FormModelerServiceImpl
>05:29:31,196 INFO  [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-5) discovered errai service: public@Service @ApplicationScoped class org.kie.workbench.common.screens.search.backend.server.SearchServiceImpl
>05:29:32,485 INFO  [org.drools.compiler.kie.builder.impl.ClasspathKieProject] (MSC service thread 1-5) Found kmodule: vfs:/content/business-central.war/WEB-INF/lib/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar/META-INF/kmodule.xml
>05:29:32,488 INFO  [org.drools.compiler.kie.builder.impl.ClasspathKieProject] (MSC service thread 1-5) Virtual file physical path = /mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/tmp/vfs/deployment807a75649a1f7f13/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar-88041cd2b5f44ff3/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar
>05:29:32,795 INFO  [org.drools.compiler.kie.builder.impl.KieRepositoryImpl] (MSC service thread 1-5) KieModule was added:ZipKieModule[ ReleaseId=org.drools:drools-wb-rest-defaultapprover:6.0.2-redhat-4file=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/domain/servers/server-one/tmp/vfs/deployment807a75649a1f7f13/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar-88041cd2b5f44ff3/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar]
>05:29:41,189 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-5) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.uberfire.backend.server.UserServicesImpl.identity
>05:29:41,464 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-5) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.guvnor.inbox.backend.server.InboxServiceImpl.identity
>05:29:41,651 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-5) WELD-001440 Scope type @javax.enterprise.context.RequestScoped() used on injection point [field] @Inject @Named @RequestScoped private org.jbpm.console.ng.bd.backend.server.DeploymentManagerEntryPointImpl.deploymentUnits
>05:29:41,685 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-5) 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
>05:29:41,686 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-5) 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
>05:29:41,758 WARN  [org.jboss.weld.Bootstrap] (MSC service thread 1-5) WELD-001440 Scope type @javax.enterprise.context.RequestScoped() used on injection point [field] @Inject @RequestScoped private org.jbpm.designer.repository.vfs.VFSRepository.descriptor
>05:29:42,327 INFO  [org.apache.helix.controller.GenericHelixController] (MSC service thread 1-5) createDefaultRegistry
>05:29:42,359 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-5) Create a zk-based cluster manager. zkSvr: localhost:2181, clusterName: bpms-cluster, instanceName: nodeOne_12345, type: PARTICIPANT
>05:29:42,366 INFO  [org.apache.helix.HelixManagerProperties] (MSC service thread 1-5) load helix-manager properties: {minimum_supported_version.batch_message=0.6.1, clustermanager.version=0.6.2-incubating, minimum_supported_version.participant=0.4}
>05:29:42,400 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-5) Added msg-factory for type: TASK_REPLY, threadpool size 40
>05:29:42,612 INFO  [org.apache.sshd.common.util.SecurityUtils] (MSC service thread 1-5) BouncyCastle not registered, using the default JCE provider
>05:29:44,383 INFO  [org.guvnor.m2repo.backend.server.GuvnorM2Repository] (MSC service thread 1-5) Maven Repository root set to: repositories/kie
>05:29:44,383 INFO  [org.guvnor.m2repo.backend.server.GuvnorM2Repository] (MSC service thread 1-5) Creating Maven Repository root: repositories/kie
>05:29:45,610 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'type' to 'kjar'.
>05:29:45,611 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'drools.dateformat' to 'dd-MMM-yyyy'.
>05:29:45,611 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'drools.defaultlanguage' to 'en'.
>05:29:45,611 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'rule-modeller-onlyShowDSLStatements' to 'false'.
>05:29:45,612 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'designer.context' to 'designer'.
>05:29:45,612 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'drools.defaultcountry' to 'US'.
>05:29:45,612 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'drools.datetimeformat' to 'dd-MMM-yyyy hh:mm:ss'.
>05:29:45,612 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'build.enable-incremental' to 'true'.
>05:29:45,616 INFO  [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-5) Setting preference 'designer.profile' to 'jbpm'.
>05:29:45,638 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-5) ClusterManager.connect()
>05:29:45,685 INFO  [org.I0Itec.zkclient.ZkEventThread] (ZkClient-EventThread-197-localhost:2181) Starting ZkClient event thread.
>05:29:45,708 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:zookeeper.version=3.3.3-1203054, built on 11/17/2011 05:47 GMT
>05:29:45,708 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:host.name=dev80.mw.lab.eng.bos.redhat.com
>05:29:45,709 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:java.version=1.7.0_45
>05:29:45,709 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:java.vendor=Oracle Corporation
>05:29:45,709 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:java.home=/qa/tools/opt/x86_64/jdk1.7.0_45/jre
>05:29:45,710 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:java.class.path=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1/jboss-modules.jar
>05:29:45,710 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) 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
>05:29:45,710 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:java.io.tmpdir=/tmp
>05:29:45,711 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:java.compiler=<NA>
>05:29:45,711 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:os.name=Linux
>05:29:45,711 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:os.arch=amd64
>05:29:45,714 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:os.version=2.6.32-431.1.2.el6.x86_64
>05:29:45,714 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:user.name=hudson
>05:29:45,714 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:user.home=/home/hudson
>05:29:45,715 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Client environment:user.dir=/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/jboss-eap-6.1
>05:29:45,718 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-5) Initiating client connection, connectString=localhost:2181 sessionTimeout=30000 watcher=org.apache.helix.manager.zk.ZkClient@7e650919
>05:29:45,741 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-5-SendThread()) Opening socket connection to server localhost/127.0.0.1:2181
>05:29:45,745 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-5-SendThread(localhost:2181)) Socket connection established to localhost/127.0.0.1:2181, initiating session
>05:29:45,787 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-5-SendThread(localhost:2181)) Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x144ab4f7a060007, negotiated timeout = 30000
>05:29:45,790 INFO  [org.I0Itec.zkclient.ZkClient] (MSC service thread 1-5-EventThread) zookeeper state changed (SyncConnected)
>05:29:45,809 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-5) KeeperState: SyncConnected, zookeeper:State:CONNECTED Timeout:30000 sessionid:0x144ab4f7a060007 local:/127.0.0.1:50922 remoteserver:localhost/127.0.0.1:2181 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0
>05:29:45,809 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-5) Handling new session, session id: 144ab4f7a060007, instance: nodeOne_12345, instanceTye: PARTICIPANT, cluster: bpms-cluster, zkconnection: State:CONNECTED Timeout:30000 sessionid:0x144ab4f7a060007 local:/127.0.0.1:50922 remoteserver:localhost/127.0.0.1:2181 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0
>05:29:45,810 WARN  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-5) ParticipantHealthReportTimerTask already stopped
>05:29:45,964 INFO  [org.apache.helix.manager.zk.ParticipantManagerHelper] (MSC service thread 1-5) instance: nodeOne_12345 auto-joining bpms-cluster is false
>05:29:46,015 INFO  [org.apache.helix.messaging.DefaultMessagingService] (MSC service thread 1-5) registering msg factory for type STATE_TRANSITION
>05:29:46,079 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-5) Added msg-factory for type: STATE_TRANSITION, threadpool size 40
>05:29:46,117 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-5) 15 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,117 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-5) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,141 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-5) Dropping NO-OP message. mid: edc6b19d-a31e-44b9-8f71-6be70176a6b3, from: nodeOne_12345
>05:29:46,169 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-5) 15 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 53ms
>05:29:46,170 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-5) Added listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753 for type: MESSAGES to path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES
>05:29:46,170 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-5) 15 START:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor
>05:29:46,171 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-5) nodeOne_12345 subscribes child-change. path: /bpms-cluster/CONTROLLER, listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@46d88b0c
>05:29:46,178 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-5) Controller has changed
>05:29:46,197 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-5) _zkPropertyTransferSvcUrl : null Controller dev80.mw.lab.eng.bos.redhat.com-CONTROLLER
>05:29:46,198 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-5) 15 END:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor Took: 28ms
>05:29:46,198 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-5) Added listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@46d88b0c for type: CONTROLLER to path: /bpms-cluster/CONTROLLER
>05:29:46,198 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,201 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,205 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-5) Register state model factory for state model SchedulerTaskQueue using factory name DEFAULT with org.apache.helix.participant.statemachine.ScheduledTaskStateModelFactory@54edf258
>05:29:46,209 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:29:46,210 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 11ms
>05:29:46,214 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-5) Send NO_OP message to nodeOne_12345, msgId: eef4ea92-03a3-4fd7-b176-78b39f7efb7b
>05:29:46,221 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,221 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,221 INFO  [org.apache.helix.manager.zk.ParticipantManagerHelper] (MSC service thread 1-5) Created healthcheck info path /bpms-cluster/INSTANCES/nodeOne_12345/HEALTHREPORT
>05:29:46,222 INFO  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-5) Start HealthCheckInfoReportingTask
>05:29:46,247 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Dropping NO-OP message. mid: eef4ea92-03a3-4fd7-b176-78b39f7efb7b, from: nodeOne_12345
>05:29:46,252 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 32ms
>05:29:46,253 WARN  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-5) Skip processing callbacks for listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753, path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, expected types: [CALLBACK, FINALIZE] but was INIT
>05:29:46,253 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-5) init handler: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,254 WARN  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-5) Skip processing callbacks for listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@46d88b0c, path: /bpms-cluster/CONTROLLER, expected types: [CALLBACK, FINALIZE] but was INIT
>05:29:46,254 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-5) init handler: /bpms-cluster/CONTROLLER, org.apache.helix.manager.zk.ZKHelixDataAccessor@46d88b0c
>05:29:46,256 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-5) Register state model factory for state model LeaderStandby using factory name DEFAULT with org.uberfire.io.impl.cluster.helix.LockTransitionalFactory@b83afb2
>05:29:46,279 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,279 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,284 INFO  [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-5) Send NO_OP message to nodeOne_12345, msgId: 9015cdd5-002c-481f-9808-83607cec9e6a
>05:29:46,287 INFO  [org.apache.helix.messaging.DefaultMessagingService] (MSC service thread 1-5) registering msg factory for type USER_DEFINE_MSG
>05:29:46,331 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Dropping NO-OP message. mid: 9015cdd5-002c-481f-9808-83607cec9e6a, from: nodeOne_12345
>05:29:46,352 WARN  [org.apache.helix.manager.zk.ZkAsyncCallbacks] (MSC service thread 1-5-EventThread) org.apache.helix.manager.zk.ZkAsyncCallbacks$CreateCallbackHandler@2825e46e, rc:NONODE, path: /bpms-cluster/INSTANCES/nodeOne_12345/CURRENTSTATES/144ab4f7a060007/vfs-repo
>05:29:46,400 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-5) Added msg-factory for type: USER_DEFINE_MSG, threadpool size 40
>05:29:46,446 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 57bc44a1-c70e-4adb-944c-f0fd82088ac7
>05:29:46,458 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 57bc44a1-c70e-4adb-944c-f0fd82088ac7 handling task scheduled
>05:29:46,458 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 179ms
>05:29:46,463 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) handling task: 57bc44a1-c70e-4adb-944c-f0fd82088ac7 begin, at: 1394443786462
>05:29:46,469 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,469 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,506 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Dropping NO-OP message. mid: d036bf83-4144-4593-bec1-53d6d511c456, from: nodeOne_12345
>05:29:46,519 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 50ms
>05:29:46,534 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,534 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,545 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) Message 57bc44a1-c70e-4adb-944c-f0fd82088ac7 completed.
>05:29:46,570 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:29:46,571 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 37ms
>05:29:46,575 INFO  [org.jbpm.executor.impl.ExecutorImpl] (MSC service thread 1-5) Starting Executor Component ...
> 	 - Thread Pool Size: 1
> 	 - Interval: 3 Seconds
> 	 - Retries per Request: 3
>
>05:29:46,581 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,581 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,612 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:29:46,614 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 33ms
>05:29:46,631 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,631 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,639 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-1) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=OFFLINE--STANDBY
>05:29:46,643 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-1) message finished: 57bc44a1-c70e-4adb-944c-f0fd82088ac7, took 170
>05:29:46,644 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-1) msg: 57bc44a1-c70e-4adb-944c-f0fd82088ac7 handling task completed, results:true, at: 1394443786644, took:182
>05:29:46,650 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:29:46,650 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 20ms
>05:29:46,678 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:46,678 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:46,729 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 626f0bb4-253a-4bbf-b58b-55aed39625e7
>05:29:46,731 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 626f0bb4-253a-4bbf-b58b-55aed39625e7 handling task scheduled
>05:29:46,731 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 53ms
>05:29:46,732 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) handling task: 626f0bb4-253a-4bbf-b58b-55aed39625e7 begin, at: 1394443786732
>05:29:46,802 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) Message 626f0bb4-253a-4bbf-b58b-55aed39625e7 completed.
>05:29:46,813 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-2) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--LEADER
>05:29:47,428 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-2) message finished: 626f0bb4-253a-4bbf-b58b-55aed39625e7, took 668
>05:29:47,428 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-2) msg: 626f0bb4-253a-4bbf-b58b-55aed39625e7 handling task completed, results:true, at: 1394443787428, took:696
>05:29:47,443 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:29:47,444 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:29:47,451 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:29:47,451 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 8ms
>05:29:47,781 INFO  [org.kie.services.remote.jms.RetryTrackerSingleton] (ServerService Thread Pool -- 69) JMS message retry limit set to 2
>05:29:47,827 INFO  [org.jboss.web] (ServerService Thread Pool -- 69) JBAS018210: Register web context: /business-central
>05:29:48,912 INFO  [solder-servlet] (ServerService Thread Pool -- 69) Catch Integration for Servlets enabled
>05:29:49,558 INFO  [org.jboss.errai.bus.server.cluster.noop.NoopClusteringProvider] (ServerService Thread Pool -- 69) clustering support not configured.
>05:29:49,581 INFO  [org.jboss.errai.bus.server.service.bootstrap.OrderedBootstrap] (ServerService Thread Pool -- 69) starting errai bus ...
>05:29:49,588 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 69) authentication adapter configured: org.jboss.errai.bus.server.security.auth.JAASAdapter
>05:29:49,608 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 69) using dispatcher implementation: org.jboss.errai.bus.server.SimpleDispatcher
>05:29:49,619 INFO  [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 69) using session provider implementation: org.jboss.errai.bus.server.HttpSessionProvider
>05:29:49,661 INFO  [org.jboss.errai.bus.server.service.bootstrap.LoadExtensions] (ServerService Thread Pool -- 69) searching for errai extensions ...
>05:29:49,683 INFO  [org.jboss.errai.bus.server.service.bootstrap.OrderedBootstrap] (ServerService Thread Pool -- 69) errai bus started.
>05:29:50,741 INFO  [org.jboss.as.server] (host-controller-connection-threads - 1) JBAS018559: Deployed "business-central.war" (runtime-name : "business-central.war")
>05:31:02,429 INFO  [org.jboss.resteasy.cdi.CdiInjectorFactory] (http-localhost/127.0.0.1:8080-1) Found BeanManager at java:comp/BeanManager
>05:31:03,706 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-1) -----createOrCloneRepository--- , repository name:bpms-assets
>05:31:03,728 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-1) CreateOrCloneRepositoryRequest event received.
>05:31:03,756 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-1) Approval request for Job: 1394443863706-1 received.
>05:31:05,960 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-1) Approval request for Job: 1394443863706-1 result: ACCEPTED
>05:31:05,960 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-1) CreateOrCloneRepositoryRequest event approved. Performing requested operation.
>05:31:05,975 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-1) -----JobRequestHelper:createOrCloneRepository--- , repository name:bpms-assets
>05:31:10,946 INFO  [org.apache.helix.messaging.AsyncCallback] (http-localhost/127.0.0.1:8080-1) Setting time out to 30000 ms
>05:31:10,956 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>05:31:11,109 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query return 2 rows
>05:31:11,114 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-1) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>05:31:11,115 INFO  [org.apache.helix.messaging.AsyncCallback] (http-localhost/127.0.0.1:8080-1) Setting time out to 30000 ms
>05:31:11,115 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (http-localhost/127.0.0.1:8080-1) registering correlation id fc976c5f-3626-42ce-b9fe-ddc84abc5d03
>05:31:16,213 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:16,214 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:16,237 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (ZkClient-EventThread-197-localhost:2181) Verified reply message 67e2d643-f37a-4b51-a809-199fb1cd22b3 correlation:fc976c5f-3626-42ce-b9fe-ddc84abc5d03
>05:31:16,248 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 67e2d643-f37a-4b51-a809-199fb1cd22b3
>05:31:16,259 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 67e2d643-f37a-4b51-a809-199fb1cd22b3 handling task scheduled
>05:31:16,259 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-9-thread-1) handling task: 67e2d643-f37a-4b51-a809-199fb1cd22b3 begin, at: 1394443876259
>05:31:16,260 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 46ms
>05:31:16,261 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (pool-9-thread-1) Verified reply message 67e2d643-f37a-4b51-a809-199fb1cd22b3 correlation:fc976c5f-3626-42ce-b9fe-ddc84abc5d03
>05:31:16,262 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (pool-9-thread-1) invoking reply message 67e2d643-f37a-4b51-a809-199fb1cd22b3, correlationid:fc976c5f-3626-42ce-b9fe-ddc84abc5d03
>05:31:16,262 INFO  [org.apache.helix.messaging.AsyncCallback] (pool-9-thread-1) OnReply msg 67e2d643-f37a-4b51-a809-199fb1cd22b3
>05:31:16,278 INFO  [org.apache.helix.messaging.handling.AsyncCallbackService] (pool-9-thread-1) Removing finished callback, correlationid:fc976c5f-3626-42ce-b9fe-ddc84abc5d03
>05:31:16,280 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-9-thread-1) Message 67e2d643-f37a-4b51-a809-199fb1cd22b3 completed.
>05:31:16,304 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-9-thread-1) message finished: 67e2d643-f37a-4b51-a809-199fb1cd22b3, took 43
>05:31:16,305 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-9-thread-1) msg: 67e2d643-f37a-4b51-a809-199fb1cd22b3 handling task completed, results:true, at: 1394443876305, took:46
>05:31:16,307 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:16,308 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:16,317 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:16,317 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 10ms
>05:31:16,387 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:16,388 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:16,405 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 155e42d3-1659-498f-9568-2b25d9117c06
>05:31:16,415 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 155e42d3-1659-498f-9568-2b25d9117c06 handling task scheduled
>05:31:16,416 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 29ms
>05:31:16,415 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) handling task: 155e42d3-1659-498f-9568-2b25d9117c06 begin, at: 1394443876415
>05:31:16,449 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) Message 155e42d3-1659-498f-9568-2b25d9117c06 completed.
>05:31:16,461 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-3) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=LEADER--STANDBY
>05:31:16,462 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-3) message finished: 155e42d3-1659-498f-9568-2b25d9117c06, took 43
>05:31:16,462 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-3) msg: 155e42d3-1659-498f-9568-2b25d9117c06 handling task completed, results:true, at: 1394443876462, took:47
>05:31:16,469 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:16,470 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:16,479 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:16,479 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 10ms
>05:31:16,524 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:16,525 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:16,541 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: ed5caa30-7a5b-4e88-8b0a-1a11ba21b415
>05:31:16,555 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: ed5caa30-7a5b-4e88-8b0a-1a11ba21b415 handling task scheduled
>05:31:16,555 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 31ms
>05:31:16,556 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) handling task: ed5caa30-7a5b-4e88-8b0a-1a11ba21b415 begin, at: 1394443876556
>05:31:16,569 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) Message ed5caa30-7a5b-4e88-8b0a-1a11ba21b415 completed.
>05:31:16,581 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-4) message finished: ed5caa30-7a5b-4e88-8b0a-1a11ba21b415, took 22
>05:31:16,582 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-4) msg: ed5caa30-7a5b-4e88-8b0a-1a11ba21b415 handling task completed, results:true, at: 1394443876582, took:26
>05:31:16,583 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:16,583 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:16,595 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:16,596 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 14ms
>05:31:16,675 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>05:31:16,772 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query return 2 rows
>05:31:16,778 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-1) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>05:31:16,874 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>05:31:16,978 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-1) JOSQL query return 2 rows
>05:31:16,996 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-1) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>05:31:17,180 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:17,180 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:17,204 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 94a5e532-09eb-4367-965e-633c89c04a36
>05:31:17,206 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 94a5e532-09eb-4367-965e-633c89c04a36 handling task scheduled
>05:31:17,207 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 27ms
>05:31:17,209 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) handling task: 94a5e532-09eb-4367-965e-633c89c04a36 begin, at: 1394443877209
>05:31:17,279 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) Message 94a5e532-09eb-4367-965e-633c89c04a36 completed.
>05:31:17,306 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:17,306 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:17,378 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 3601ecb3-00a0-4189-a46e-b6757217be1e
>05:31:17,379 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 3601ecb3-00a0-4189-a46e-b6757217be1e handling task scheduled
>05:31:17,380 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 74ms
>05:31:17,380 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) handling task: 3601ecb3-00a0-4189-a46e-b6757217be1e begin, at: 1394443877380
>05:31:17,384 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:17,384 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:17,418 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-5) message finished: 94a5e532-09eb-4367-965e-633c89c04a36, took 174
>05:31:17,419 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-5) msg: 94a5e532-09eb-4367-965e-633c89c04a36 handling task completed, results:true, at: 1394443877419, took:210
>05:31:17,427 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 43ms
>05:31:17,408 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) Message 3601ecb3-00a0-4189-a46e-b6757217be1e completed.
>05:31:17,456 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (pool-14-thread-6) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--OFFLINE
>05:31:17,457 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-6) message finished: 3601ecb3-00a0-4189-a46e-b6757217be1e, took 71
>05:31:17,458 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-6) msg: 3601ecb3-00a0-4189-a46e-b6757217be1e handling task completed, results:true, at: 1394443877458, took:78
>05:31:17,472 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:17,472 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:17,493 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:17,493 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 21ms
>05:31:21,192 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-2) -----getJobStatus--- , jobId:1394443863706-1
>05:31:22,179 ERROR [org.jboss.modcluster] (ContainerBackgroundProcessor[StandardEngine[jboss.web]]) MODCLUSTER000042: Error MEM sending STATUS command to brmsperf01.mw.lab.eng.bos.redhat.com/10.16.90.201:8080, configuration will be reset: MEM: Can't read node
>05:31:26,628 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-3) -----createOrganizationalUnit--- , OrganizationalUnit name:bpms, OrganizationalUnit owner:testadmin
>05:31:26,632 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-3) CreateOrganizationalUnitRequest event received.
>05:31:26,634 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-3) Approval request for Job: 1394443886629-2 received.
>05:31:26,674 INFO  [org.drools.workbench.common.services.rest.JobRequestApprovalService] (http-localhost/127.0.0.1:8080-3) Approval request for Job: 1394443886629-2 result: ACCEPTED
>05:31:26,674 INFO  [org.drools.workbench.common.services.rest.JobRequestObserver] (http-localhost/127.0.0.1:8080-3) CreateOrganizationalUnitRequest event approved. Performing requested operation.
>05:31:26,675 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-3) -----JobRequestHelper:createOrganizationalUnit--- , OrganizationalUnit name:bpms, OrganizationalUnit owner:testadmin
>05:31:26,886 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:26,886 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:26,905 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: c6a1aceb-dc38-4b7f-ba60-fb88bb6cd7bd
>05:31:26,914 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: c6a1aceb-dc38-4b7f-ba60-fb88bb6cd7bd handling task scheduled
>05:31:26,914 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 28ms
>05:31:26,914 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) handling task: c6a1aceb-dc38-4b7f-ba60-fb88bb6cd7bd begin, at: 1394443886914
>05:31:26,941 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) Message c6a1aceb-dc38-4b7f-ba60-fb88bb6cd7bd completed.
>05:31:26,959 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-7) message finished: c6a1aceb-dc38-4b7f-ba60-fb88bb6cd7bd, took 39
>05:31:26,960 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-7) msg: c6a1aceb-dc38-4b7f-ba60-fb88bb6cd7bd handling task completed, results:true, at: 1394443886960, took:46
>05:31:26,967 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:26,968 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:26,999 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 40f0d30c-eae2-4390-9162-aeb7bbb5738b
>05:31:27,006 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 40f0d30c-eae2-4390-9162-aeb7bbb5738b handling task scheduled
>05:31:27,006 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 39ms
>05:31:27,008 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) handling task: 40f0d30c-eae2-4390-9162-aeb7bbb5738b begin, at: 1394443887007
>05:31:27,017 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:27,017 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:27,049 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 32ms
>05:31:27,052 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) Message 40f0d30c-eae2-4390-9162-aeb7bbb5738b completed.
>05:31:27,066 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-8) message finished: 40f0d30c-eae2-4390-9162-aeb7bbb5738b, took 48
>05:31:27,067 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-8) msg: 40f0d30c-eae2-4390-9162-aeb7bbb5738b handling task completed, results:true, at: 1394443887067, took:60
>05:31:27,071 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:27,071 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:27,487 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:27,487 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 416ms
>05:31:27,548 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-3) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>05:31:27,618 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-3) JOSQL query return 2 rows
>05:31:27,625 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-3) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>05:31:27,702 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-3) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>05:31:27,808 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (http-localhost/127.0.0.1:8080-3) JOSQL query return 2 rows
>05:31:27,824 INFO  [org.apache.helix.messaging.DefaultMessagingService] (http-localhost/127.0.0.1:8080-3) Send 1 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>05:31:28,056 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:28,057 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:28,132 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 022be7f2-a224-4fc5-954b-50c02686da7f
>05:31:28,152 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 022be7f2-a224-4fc5-954b-50c02686da7f handling task scheduled
>05:31:28,153 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 97ms
>05:31:28,158 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) handling task: 022be7f2-a224-4fc5-954b-50c02686da7f begin, at: 1394443888158
>05:31:28,182 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) Message 022be7f2-a224-4fc5-954b-50c02686da7f completed.
>05:31:28,188 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-9) message finished: 022be7f2-a224-4fc5-954b-50c02686da7f, took 20
>05:31:28,188 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-9) msg: 022be7f2-a224-4fc5-954b-50c02686da7f handling task completed, results:true, at: 1394443888188, took:30
>05:31:28,190 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:28,191 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:28,197 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:28,198 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 8ms
>05:31:28,225 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:28,226 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:28,244 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 4179821a-6325-42f7-b2aa-45f2ba10162a
>05:31:28,252 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 4179821a-6325-42f7-b2aa-45f2ba10162a handling task scheduled
>05:31:28,253 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 28ms
>05:31:28,253 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) handling task: 4179821a-6325-42f7-b2aa-45f2ba10162a begin, at: 1394443888253
>05:31:28,305 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) Message 4179821a-6325-42f7-b2aa-45f2ba10162a completed.
>05:31:28,356 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-10) message finished: 4179821a-6325-42f7-b2aa-45f2ba10162a, took 81
>05:31:28,356 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-10) msg: 4179821a-6325-42f7-b2aa-45f2ba10162a handling task completed, results:true, at: 1394443888356, took:103
>05:31:28,369 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:28,370 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:28,377 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:28,377 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 8ms
>05:31:28,413 INFO  [org.drools.workbench.common.services.rest.JobRequestHelper] (http-localhost/127.0.0.1:8080-4) -----getJobStatus--- , jobId:1394443886629-2
>05:31:33,561 INFO  [org.kie.services.remote.rest.async.AsyncDeploymentJobExecutor] (http-localhost/127.0.0.1:8080-5) DEPLOY job for [org.jboss.qa.bpms:clustering:1.0.0.Final] submitted succesfully
>05:31:40,882 ERROR [stderr] (pool-22-thread-1) line 1:57 no viable alternative at character '\'
>05:31:42,608 INFO  [org.drools.compiler.kie.builder.impl.KieRepositoryImpl] (pool-22-thread-1) KieModule was added:ZipKieModule[ ReleaseId=org.jboss.qa.bpms:clustering:1.0.0.Finalfile=/home/hudson/.m2/repository/org/jboss/qa/bpms/clustering/1.0.0.Final/clustering-1.0.0.Final.jar]
>05:31:43,623 INFO  [org.kie.services.remote.rest.async.AsyncDeploymentJobExecutor] (http-localhost/127.0.0.1:8080-7) DEPLOY job NOT submitted: A job already exists to deploy deployment [org.jboss.qa.bpms:clustering:1.0.0.Final]
>05:31:47,350 ERROR [stderr] (pool-22-thread-1) line 1:57 no viable alternative at character '\'
>05:31:49,311 INFO  [org.quartz.core.SchedulerSignalerImpl] (pool-22-thread-1) Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
>05:31:49,317 INFO  [org.quartz.core.QuartzScheduler] (pool-22-thread-1) Quartz Scheduler v.1.8.5 created.
>05:31:49,323 INFO  [org.quartz.impl.jdbcjobstore.JobStoreCMT] (pool-22-thread-1) Using db table-based data access locking (synchronization).
>05:31:49,330 INFO  [org.quartz.impl.jdbcjobstore.JobStoreCMT] (pool-22-thread-1) JobStoreCMT initialized.
>05:31:49,334 INFO  [org.quartz.core.QuartzScheduler] (pool-22-thread-1) Scheduler meta-data: Quartz Scheduler (v1.8.5) 'jBPMClusteredScheduler' with instanceId 'dev80.mw.lab.eng.bos.redhat.com1394443909183'
>  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
>  NOT STARTED.
>  Currently in standby mode.
>  Number of jobs executed: 0
>  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 5 threads.
>  Using job-store 'org.quartz.impl.jdbcjobstore.JobStoreCMT' - which supports persistence. and is clustered.
>
>05:31:49,335 INFO  [org.quartz.impl.StdSchedulerFactory] (pool-22-thread-1) Quartz scheduler 'jBPMClusteredScheduler' initialized from specified file: '/mnt/hudson_workspace/workspace/bpms-clustering-balancer-single/quartz.properties'
>05:31:49,335 INFO  [org.quartz.impl.StdSchedulerFactory] (pool-22-thread-1) Quartz scheduler version: 1.8.5
>05:31:49,490 INFO  [org.quartz.core.QuartzScheduler] (pool-22-thread-1) Scheduler jBPMClusteredScheduler_$_dev80.mw.lab.eng.bos.redhat.com1394443909183 started.
>05:31:51,480 INFO  [org.jboss.web] (ServerService Thread Pool -- 88) JBAS018224: Unregister web context: /business-central
>05:31:51,589 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) JBAS010409: Unbound data source [java:jboss/datasources/brms-jdbc-dsQuartz]
>05:31:51,590 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) JBAS010409: Unbound data source [java:jboss/datasources/ExampleDS]
>05:31:51,594 INFO  [org.apache.coyote.http11] (MSC service thread 1-7) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost/127.0.0.1:8080
>05:31:51,595 INFO  [org.apache.coyote.http11] (MSC service thread 1-7) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost/127.0.0.1:8080
>05:31:51,634 INFO  [org.jboss.jaxr] (MSC service thread 1-6) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory
>05:31:51,633 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 89) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/queue/KIE.AUDIT
>05:31:51,706 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 92) JBAS011605: Unbound messaging object to jndi name java:/queue/KIE.TASK.ALL
>05:31:51,771 INFO  [org.apache.catalina.core] (MSC service thread 1-5) JBWEB001079: Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started
>05:31:53,199 WARN  [org.hornetq.core.client] (Thread-9 (HornetQ-client-global-threads-1277915893)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
>05:31:53,200 WARN  [org.hornetq.core.client] (Thread-10 (HornetQ-client-global-threads-1277915893)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED]
>05:31:53,231 INFO  [org.hornetq.core.server] (Thread-21 (HornetQ-server-HornetQServerImpl::serverUUID=fa292441-a835-11e3-b835-6f64b395dc0d-1797761180)) HQ221029: stopped bridge sf.my-cluster.0043f207-a836-11e3-aee6-93f258a2f581
>05:31:54,179 WARN  [org.jbpm.services.task.HumanTaskConfigurator] (pool-22-thread-1) No default interceptor found of type org.jbpm.services.task.persistence.TaskTransactionInterceptor might be mssing jbpm-human-task-jpa module on classpath (error null
>05:31:54,349 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:54,350 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:54,366 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: d99e70bd-c26c-4813-9d7c-dcac4f0584f1
>05:31:54,408 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: d99e70bd-c26c-4813-9d7c-dcac4f0584f1 handling task scheduled
>05:31:54,408 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 59ms
>05:31:54,409 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) handling task: d99e70bd-c26c-4813-9d7c-dcac4f0584f1 begin, at: 1394443914409
>05:31:54,424 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) Message d99e70bd-c26c-4813-9d7c-dcac4f0584f1 completed.
>05:31:54,432 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-11) message finished: d99e70bd-c26c-4813-9d7c-dcac4f0584f1, took 20
>05:31:54,433 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-11) msg: d99e70bd-c26c-4813-9d7c-dcac4f0584f1 handling task completed, results:true, at: 1394443914433, took:24
>05:31:54,436 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:54,437 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:54,444 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:54,445 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 9ms
>05:31:54,517 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:54,518 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:54,552 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 7f530ff3-33fa-461a-8b89-fe342626e57b
>05:31:54,561 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 7f530ff3-33fa-461a-8b89-fe342626e57b handling task scheduled
>05:31:54,562 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 45ms
>05:31:54,563 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) handling task: 7f530ff3-33fa-461a-8b89-fe342626e57b begin, at: 1394443914563
>05:31:54,603 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) Message 7f530ff3-33fa-461a-8b89-fe342626e57b completed.
>05:31:54,616 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-12) message finished: 7f530ff3-33fa-461a-8b89-fe342626e57b, took 44
>05:31:54,617 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-12) msg: 7f530ff3-33fa-461a-8b89-fe342626e57b handling task completed, results:true, at: 1394443914617, took:54
>05:31:54,623 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:54,624 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:54,631 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:54,632 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 9ms
>05:31:54,658 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-22-thread-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>05:31:54,720 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-22-thread-1) JOSQL query return 1 rows
>05:31:54,726 INFO  [org.apache.helix.messaging.DefaultMessagingService] (pool-22-thread-1) Send 0 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>05:31:54,804 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-22-thread-1) JOSQL query: SELECT DISTINCT  mapSubKey, recordId, '', ''  FROM EXTERNALVIEW.Table WHERE mapSubKey LIKE '%' AND recordId LIKE 'vfs-repo' AND mapKey LIKE '%'  AND mapValue LIKE '%'  AND mapSubKey IN ((SELECT [*]id FROM :LIVEINSTANCES))
>05:31:54,848 INFO  [org.apache.helix.messaging.CriteriaEvaluator] (pool-22-thread-1) JOSQL query return 1 rows
>05:31:54,859 INFO  [org.apache.helix.messaging.DefaultMessagingService] (pool-22-thread-1) Send 0 messages with criteria instanceName=%resourceName=vfs-repopartitionName=partitionState=
>05:31:54,956 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:54,957 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:54,977 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 7f9c5b68-1654-4769-94f8-c3fd61c31ae5
>05:31:54,979 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 7f9c5b68-1654-4769-94f8-c3fd61c31ae5 handling task scheduled
>05:31:54,980 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 24ms
>05:31:54,991 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) handling task: 7f9c5b68-1654-4769-94f8-c3fd61c31ae5 begin, at: 1394443914991
>05:31:55,012 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) Message 7f9c5b68-1654-4769-94f8-c3fd61c31ae5 completed.
>05:31:55,016 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-13) message finished: 7f9c5b68-1654-4769-94f8-c3fd61c31ae5, took 22
>05:31:55,017 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-13) msg: 7f9c5b68-1654-4769-94f8-c3fd61c31ae5 handling task completed, results:true, at: 1394443915017, took:26
>05:31:55,022 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:55,022 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:55,034 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:55,035 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 13ms
>05:31:55,027 ERROR [org.kie.services.remote.rest.async.AsyncDeploymentJobExecutor] (pool-22-thread-1) Unable to deploy [org.jboss.qa.bpms:clustering:1.0.0.Final]: java.lang.IllegalStateException: Unable to find transaction: java:comp/UserTransaction
>	at org.drools.persistence.jta.JtaTransactionManager.findUserTransaction(JtaTransactionManager.java:140) [drools-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at org.drools.persistence.jta.JtaTransactionManager.<init>(JtaTransactionManager.java:79) [drools-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at org.jbpm.shared.services.impl.TransactionalCommandService.<init>(TransactionalCommandService.java:23) [jbpm-shared-services-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at org.jbpm.kie.services.cdi.producer.TransactionalCommandServiceProducer.produceCommandService(TransactionalCommandServiceProducer.java:18) [jbpm-kie-services-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_45]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_45]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_45]
>	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_45]
>	at org.jboss.weld.util.reflection.SecureReflections$13.work(SecureReflections.java:267) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.util.reflection.SecureReflectionAccess.run(SecureReflectionAccess.java:52) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.util.reflection.SecureReflectionAccess.runAsInvocation(SecureReflectionAccess.java:137) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.util.reflection.SecureReflections.invoke(SecureReflections.java:263) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.introspector.jlr.WeldMethodImpl.invokeOnInstance(WeldMethodImpl.java:170) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.injection.MethodInjectionPoint.invokeOnInstance(MethodInjectionPoint.java:137) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.ProducerMethod$ProducerMethodProducer.produce(ProducerMethod.java:136) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.AbstractProducerBean$AbstractProducer.produce(AbstractProducerBean.java:319) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.AbstractProducerBean.create(AbstractProducerBean.java:307) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.context.unbound.DependentContextImpl.get(DependentContextImpl.java:68) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.manager.BeanManagerImpl.getReference(BeanManagerImpl.java:608) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.manager.BeanManagerImpl.getReference(BeanManagerImpl.java:674) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.injection.FieldInjectionPoint.inject(FieldInjectionPoint.java:134) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.util.Beans.injectBoundFields(Beans.java:763) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.util.Beans.injectFieldsAndInitializers(Beans.java:772) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.ManagedBean$ManagedBeanInjectionTarget$1$1.proceed(ManagedBean.java:165) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.injection.InjectionContextImpl.run(InjectionContextImpl.java:48) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.ManagedBean$ManagedBeanInjectionTarget$1.work(ManagedBean.java:161) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.ManagedBean$FixInjectionPoint.run(ManagedBean.java:135) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.ManagedBean$ManagedBeanInjectionTarget.inject(ManagedBean.java:157) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.ManagedBean.create(ManagedBean.java:297) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.context.AbstractContext.get(AbstractContext.java:101) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.proxy.ContextBeanInstance.getInstance(ContextBeanInstance.java:90) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:79) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jbpm.kie.services.impl.RuntimeDataServiceImpl$Proxy$_$$_WeldClientProxy.indexOnDeploy(RuntimeDataServiceImpl$Proxy$_$$_WeldClientProxy.java) [jbpm-kie-services-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_45]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_45]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_45]
>	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_45]
>	at org.jboss.weld.util.reflection.SecureReflections$13.work(SecureReflections.java:267) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.util.reflection.SecureReflectionAccess.run(SecureReflectionAccess.java:52) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.util.reflection.SecureReflectionAccess.runAsInvocation(SecureReflectionAccess.java:137) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.util.reflection.SecureReflections.invoke(SecureReflections.java:263) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.introspector.jlr.WeldMethodImpl.invokeOnInstance(WeldMethodImpl.java:170) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.introspector.ForwardingWeldMethod.invokeOnInstance(ForwardingWeldMethod.java:51) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.injection.MethodInjectionPoint.invokeOnInstanceWithSpecialValue(MethodInjectionPoint.java:154) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.event.ObserverMethodImpl.sendEvent(ObserverMethodImpl.java:245) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.event.ObserverMethodImpl.sendEvent(ObserverMethodImpl.java:233) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.event.ObserverMethodImpl.notify(ObserverMethodImpl.java:213) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.event.ObserverNotifier.notifyObserver(ObserverNotifier.java:117) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.event.TransactionalObserverNotifier.notifyObserver(TransactionalObserverNotifier.java:44) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.event.ObserverNotifier.notifyObservers(ObserverNotifier.java:85) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.event.ObserverNotifier.fireEvent(ObserverNotifier.java:80) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jboss.weld.event.EventImpl.fire(EventImpl.java:68) [weld-core-1.1.13.Final-redhat-1.jar:1.1.13.Final-redhat-1]
>	at org.jbpm.kie.services.impl.AbstractDeploymentService.commonDeploy(AbstractDeploymentService.java:106) [jbpm-kie-services-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at org.jbpm.kie.services.impl.KModuleDeploymentService.deploy(KModuleDeploymentService.java:110) [jbpm-kie-services-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at org.jbpm.kie.services.impl.KModuleDeploymentService$Proxy$_$$_WeldClientProxy.deploy(KModuleDeploymentService$Proxy$_$$_WeldClientProxy.java) [jbpm-kie-services-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at org.kie.services.remote.rest.async.AsyncDeploymentJobExecutor$DeploymentJobCallable.call(AsyncDeploymentJobExecutor.java:241) [kie-services-remote-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	at org.kie.services.remote.rest.async.AsyncDeploymentJobExecutor$DeploymentJobCallable.call(AsyncDeploymentJobExecutor.java:217) [kie-services-remote-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	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]
>Caused by: javax.naming.NameNotFoundException: java:comp/UserTransaction
>	at org.jboss.as.naming.InitialContext.findContext(InitialContext.java:198)
>	at org.jboss.as.naming.InitialContext.lookup(InitialContext.java:118)
>	at org.jboss.as.naming.NamingContext.lookup(NamingContext.java:183)
>	at org.jboss.as.naming.NamingContext.lookup(NamingContext.java:179)
>	at javax.naming.InitialContext.lookup(InitialContext.java:411) [rt.jar:1.7.0_45]
>	at org.drools.persistence.jta.JtaTransactionManager.findUserTransaction(JtaTransactionManager.java:132) [drools-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4]
>	... 60 more
>
>05:31:55,057 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:55,067 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:55,082 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: b2f20de5-d7da-4e89-86a8-43669517426e
>05:31:55,089 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: b2f20de5-d7da-4e89-86a8-43669517426e handling task scheduled
>05:31:55,089 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 32ms
>05:31:55,091 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) handling task: b2f20de5-d7da-4e89-86a8-43669517426e begin, at: 1394443915091
>05:31:55,109 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) Message b2f20de5-d7da-4e89-86a8-43669517426e completed.
>05:31:55,113 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-14-thread-14) message finished: b2f20de5-d7da-4e89-86a8-43669517426e, took 18
>05:31:55,114 INFO  [org.apache.helix.messaging.handling.HelixTask] (pool-14-thread-14) msg: b2f20de5-d7da-4e89-86a8-43669517426e handling task completed, results:true, at: 1394443915114, took:23
>05:31:55,119 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:31:55,119 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeOne_12345 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:31:55,137 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process
>05:31:55,137 INFO  [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 18ms
>05:32:01,557 WARN  [org.jboss.modcluster] (ServerService Thread Pool -- 88) MODCLUSTER000025: Failed to drain 7 remaining active sessions from default-host:/business-central within 10.000000.1 seconds
>05:32:01,567 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 88) MODCLUSTER000021: All pending requests drained from default-host:/business-central in 10.010000.1 seconds
>05:32:01,686 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 93) MODCLUSTER000024: All active sessions drained from default-host:/business-central in 9.912000.1 seconds
>05:32:01,726 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 93) MODCLUSTER000021: All pending requests drained from default-host:/business-central in 9.952000.1 seconds
>05:32:01,745 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 93) MODCLUSTER000002: Initiating mod_cluster shutdown
>05:32:01,777 INFO  [org.apache.coyote.ajp] (MSC service thread 1-2) JBWEB003048: Pausing Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8009
>05:32:01,778 INFO  [org.apache.coyote.ajp] (MSC service thread 1-2) JBWEB003051: Stopping Coyote AJP/1.3 on ajp-localhost/127.0.0.1:8009
>05:32:01,815 INFO  [org.jboss.as.jpa] (ServerService Thread Pool -- 99) JBAS011403: Stopping Persistence Unit Service 'business-central.war#org.jbpm.domain'
>05:32:01,818 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-5) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA]
>05:32:01,822 INFO  [org.jboss.weld.deployer] (MSC service thread 1-8) JBAS016009: Stopping weld service for deployment business-central.war
>05:32:01,823 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) JBAS010409: Unbound data source [java:jboss/datasources/brms-jdbc-ds]
>05:32:01,850 INFO  [org.hornetq.ra] (MSC service thread 1-2) HQ151003: HornetQ resource adaptor stopped
>05:32:01,929 INFO  [org.hornetq.core.server] (MSC service thread 1-7) HQ221002: HornetQ Server version 2.3.5.Final-redhat-2 (Monster Bee, 123) [fa292441-a835-11e3-b835-6f64b395dc0d] stopped
>05:32:01,938 INFO  [org.jboss.errai.cdi.server.events.ShutdownEventObserver] (MSC service thread 1-8) Shutting down CDI-to-ErraiBus event bridge
>05:32:01,942 INFO  [org.jbpm.executor.impl.ExecutorImpl] (MSC service thread 1-8)  >>>>> Destroying Executor !!!
>05:32:01,946 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-8) disconnect nodeOne_12345(PARTICIPANT) from bpms-cluster
>05:32:01,947 INFO  [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-8) Stop ParticipantHealthReportTimerTask
>05:32:01,947 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) shutting down TaskExecutor
>05:32:01,948 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) 0 tasks are still in the threadpool for msgType USER_DEFINE_MSG
>05:32:01,951 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) 0 tasks are still in the threadpool for msgType STATE_TRANSITION
>05:32:01,952 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) 0 tasks are still in the threadpool for msgType TASK_REPLY
>05:32:01,953 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-8) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--OFFLINE
>05:32:01,954 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-8) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=OFFLINE--STANDBY
>05:32:01,954 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-8) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=LEADER--STANDBY
>05:32:01,954 INFO  [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-8) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--LEADER
>05:32:01,955 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) shutdown finished
>05:32:01,955 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-8) 18 START:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
>05:32:01,955 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-8) nodeOne_12345 unsubscribe child-change. path: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:32:01,957 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) Get FINALIZE notification
>05:32:01,957 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) 0 tasks never executed for msgType: USER_DEFINE_MSG. tasks: []
>05:32:01,958 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) 0 tasks never executed for msgType: STATE_TRANSITION. tasks: []
>05:32:01,958 WARN  [org.apache.helix.participant.statemachine.StateModel] (MSC service thread 1-8) Default reset method invoked. Either because the process longer own this resource or session timedout
>05:32:01,958 INFO  [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-8) 0 tasks never executed for msgType: TASK_REPLY. tasks: []
>05:32:01,958 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-8) 18 END:INVOKE /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 3ms
>05:32:01,959 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-8) reset handler: /bpms-cluster/INSTANCES/nodeOne_12345/MESSAGES, org.apache.helix.messaging.handling.HelixTaskExecutor@a1c3753
>05:32:01,959 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-8) 18 START:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor
>05:32:01,959 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-8) nodeOne_12345 unsubscribe child-change. path: /bpms-cluster/CONTROLLER, listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@46d88b0c
>05:32:01,959 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-8) Controller has changed
>05:32:01,961 INFO  [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-8) _zkPropertyTransferSvcUrl : null Controller dev80.mw.lab.eng.bos.redhat.com-CONTROLLER
>05:32:01,962 INFO  [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-8) 18 END:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor Took: 3ms
>05:32:01,962 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-8) reset handler: /bpms-cluster/CONTROLLER, org.apache.helix.manager.zk.ZKHelixDataAccessor@46d88b0c
>05:32:01,962 INFO  [org.apache.helix.manager.zk.ZkClient] (MSC service thread 1-8) Closing zkclient: State:CONNECTED Timeout:30000 sessionid:0x144ab4f7a060007 local:/127.0.0.1:50922 remoteserver:localhost/127.0.0.1:2181 lastZxid:253 xid:595 sent:604 recv:639 queuedpkts:0 pendingresp:0 queuedevents:0
>05:32:01,963 INFO  [org.I0Itec.zkclient.ZkEventThread] (ZkClient-EventThread-197-localhost:2181) Terminate ZkClient event thread.
>05:32:01,968 INFO  [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-5-EventThread) EventThread shut down
>05:32:01,968 INFO  [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-8) Session: 0x144ab4f7a060007 closed
>05:32:01,971 INFO  [org.apache.helix.manager.zk.ZkClient] (MSC service thread 1-8) Closed zkclient
>05:32:01,971 INFO  [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-8) Cluster manager: nodeOne_12345 disconnected
>05:32:02,816 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-7) JBAS015877: Stopped deployment business-central.war (runtime-name: business-central.war) in 11296ms
>05:32:02,839 INFO  [org.jboss.as] (MSC service thread 1-4) JBAS015950: JBoss BPM Suite 6.0.1.GA (AS 7.2.1.Final-redhat-10) stopped in 11295ms

Actions: View

Attachments on bug 1075181: 873206 | 873207