Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 873951 Details for
Bug 1076036
Reassignment of task did not happen after fail-over because of NPE in global timer service
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
server log - node two
serverTwo.log (text/x-log), 240.91 KB, created by
Radovan Synek
on 2014-03-13 12:14:23 UTC
(
hide
)
Description:
server log - node two
Filename:
MIME Type:
Creator:
Radovan Synek
Created:
2014-03-13 12:14:23 UTC
Size:
240.91 KB
patch
obsolete
>13:51:30,357 INFO [org.jboss.modules] (main) JBoss Modules version 1.2.2.Final-redhat-1 >13:51:30,944 INFO [org.jboss.msc] (main) JBoss MSC version 1.0.4.GA-redhat-1 >13:51:31,051 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 >13:51:31,061 DEBUG [org.jboss.as.config] (MSC service thread 1-6) Configured system properties: > [Host Controller] = true > [Server:server-two] = > awt.toolkit = sun.awt.X11.XToolkit > designerdataobjects = ${designerdataobjects:false} > file.encoding = UTF-8 > file.encoding.pkg = sun.io > file.separator = / > java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment > java.awt.printerjob = sun.print.PSPrinterJob > java.class.path = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/jboss-modules.jar > java.class.version = 51.0 > java.endorsed.dirs = /qa/tools/opt/jdk1.7.0_45/jre/lib/endorsed > java.ext.dirs = /qa/tools/opt/jdk1.7.0_45/jre/lib/ext:/usr/java/packages/lib/ext > java.home = /qa/tools/opt/jdk1.7.0_45/jre > java.io.tmpdir = /tmp > java.library.path = /qa/tools/opt/jdk1.7.0_45/jre/lib/i386/server:/qa/tools/opt/jdk1.7.0_45/jre/lib/i386:/qa/tools/opt/jdk1.7.0_45/jre/../lib/i386:/qa/tools/opt/jdk1.6.0_45/jre/lib/i386/server:/qa/tools/opt/jdk1.6.0_45/jre/lib/i386:/qa/tools/opt/jdk1.6.0_45/jre/../lib/i386:/usr/java/packages/lib/i386:/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) 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-matrix/e2a8916d/jboss-eap-6.1/domain > jboss.domain.config.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/configuration > jboss.home.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1 > jboss.host.name = dev114 > jboss.modules.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/modules > jboss.node.name = master:server-two > jboss.qualified.host.name = dev114 > jboss.server.base.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two > jboss.server.config.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/configuration > jboss.server.data.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/data > jboss.server.deploy.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/data/content > jboss.server.log.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/log > jboss.server.name = server-two > jboss.server.persist.config = true > jboss.server.temp.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/tmp > line.separator = > > logging.configuration = file:/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/configuration/default-server-logging.properties > module.path = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/modules > org.jboss.boot.log.file = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/log/server.log > org.jboss.resolver.warning = true > org.jbpm.designer.perspective = ${org.jbpm.designer.perspective:full} > org.kie.example = ${org.kie.example:true} > org.xml.sax.driver = __redirected.__XMLReaderFactory > os.arch = i386 > os.name = Linux > os.version = 2.6.18-371.3.1.el5PAE > path.separator = : > sun.arch.data.model = 32 > sun.boot.class.path = /qa/tools/opt/jdk1.7.0_45/jre/lib/resources.jar:/qa/tools/opt/jdk1.7.0_45/jre/lib/rt.jar:/qa/tools/opt/jdk1.7.0_45/jre/lib/sunrsasign.jar:/qa/tools/opt/jdk1.7.0_45/jre/lib/jsse.jar:/qa/tools/opt/jdk1.7.0_45/jre/lib/jce.jar:/qa/tools/opt/jdk1.7.0_45/jre/lib/charsets.jar:/qa/tools/opt/jdk1.7.0_45/jre/lib/jfr.jar:/qa/tools/opt/jdk1.7.0_45/jre/classes > sun.boot.library.path = /qa/tools/opt/jdk1.7.0_45/jre/lib/i386 > sun.cpu.endian = little > sun.cpu.isalist = > sun.io.unicode.encoding = UnicodeLittle > sun.java.command = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/jboss-modules.jar -mp /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/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 Tiered Compilers > sun.os.patch.level = unknown > user.country = US > user.dir = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1 > user.home = /home/hudson > user.language = en > user.name = hudson > user.timezone = US/Eastern >13:51:31,063 DEBUG [org.jboss.as.config] (MSC service thread 1-6) VM Arguments: -D[Server:server-two] -XX:PermSize=256m -XX:MaxPermSize=1024m -Xms1503m -Xmx1503m -D[Host Controller]=true -Ddesignerdataobjects=${designerdataobjects:false} -Dorg.kie.example=${org.kie.example:true} -Djboss.home.dir=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/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-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/log -Djboss.server.temp.dir=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/tmp -Djboss.server.data.dir=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/data -Dorg.jboss.boot.log.file=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/log/server.log -Dlogging.configuration=file:/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/configuration/default-server-logging.properties >13:51:31,171 INFO [org.xnio] (MSC service thread 1-8) XNIO Version 3.0.7.GA-redhat-1 >13:51:31,178 INFO [org.xnio.nio] (MSC service thread 1-8) XNIO NIO Implementation Version 3.0.7.GA-redhat-1 >13:51:31,192 INFO [org.jboss.remoting] (MSC service thread 1-8) JBoss Remoting version 3.2.16.GA-redhat-1 >13:51:32,373 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 33) JBAS015537: Activating WebServices Extension >13:51:32,374 INFO [org.jboss.as.security] (ServerService Thread Pool -- 37) JBAS013171: Activating Security Subsystem >13:51:32,381 INFO [org.jboss.as.security] (MSC service thread 1-3) JBAS013170: Current PicketBox version=4.0.17.SP2-redhat-2 >13:51:32,392 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 56) JBAS010280: Activating Infinispan subsystem. >13:51:32,403 INFO [org.jboss.as.configadmin] (ServerService Thread Pool -- 60) JBAS016200: Activating ConfigAdmin Subsystem >13:51:32,404 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 42) JBAS011800: Activating Naming Subsystem >13:51:32,406 INFO [org.jboss.as.jacorb] (ServerService Thread Pool -- 55) JBAS016300: Activating JacORB Subsystem >13:51:32,410 INFO [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 50) JBAS010260: Activating JGroups subsystem. >13:51:32,422 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 47) JBAS012605: Activated the following JSF Implementations: [main, 1.2] >13:51:32,436 INFO [org.jboss.as.connector.logging] (MSC service thread 1-14) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.19.Final-redhat-2) >13:51:32,442 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 59) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) >13:51:32,477 INFO [org.jboss.as.naming] (MSC service thread 1-6) JBAS011802: Starting Naming Service >13:51:32,479 INFO [org.jboss.as.mail.extension] (MSC service thread 1-6) JBAS015400: Bound mail session [java:/mail/jbpmMailSession] >13:51:32,480 INFO [org.jboss.as.mail.extension] (MSC service thread 1-11) JBAS015400: Bound mail session [java:jboss/mail/Default] >13:51:32,518 INFO [org.jboss.jaxr] (MSC service thread 1-12) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory >13:51:32,525 INFO [org.jboss.ws.common.management] (MSC service thread 1-13) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.1.4.Final-redhat-7 >13:51:32,547 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 59) JBAS010403: Deploying JDBC-compliant driver class com.microsoft.sqlserver.jdbc.SQLServerDriver (version 4.0) >13:51:32,610 INFO [org.apache.coyote.ajp] (MSC service thread 1-16) JBWEB003046: Starting Coyote AJP/1.3 on ajp-localhost.localdomain/127.0.0.1:8209 >13:51:32,613 INFO [org.apache.coyote.http11] (MSC service thread 1-15) JBWEB003001: Coyote HTTP/1.1 initializing on : http-localhost.localdomain/127.0.0.1:8280 >13:51:32,614 INFO [org.apache.coyote.http11] (MSC service thread 1-15) JBWEB003000: Coyote HTTP/1.1 starting on: http-localhost.localdomain/127.0.0.1:8280 >13:51:32,660 INFO [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000001: Initializing mod_cluster ${project.version} >13:51:32,673 INFO [org.jboss.modcluster] (ServerService Thread Pool -- 62) MODCLUSTER000032: Listening to proxy advertisements on /224.0.1.105:23364 >13:51:32,744 INFO [org.jboss.as.jacorb] (MSC service thread 1-5) JBAS016330: CORBA ORB Service started >13:51:32,761 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=true,backup=false,sharedStore=true,journalDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/data/messagingjournal,bindingsDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/data/messagingbindings,largeMessagesDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/data/messaginglargemessages,pagingDirectory=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/data/messagingpaging) >13:51:32,763 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221006: Waiting to obtain live lock >13:51:32,794 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. >13:51:32,802 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. >13:51:32,816 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221013: Using NIO Journal >13:51:32,865 INFO [org.jboss.as.jacorb] (MSC service thread 1-10) JBAS016328: CORBA Naming Service started >13:51:32,933 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221034: Waiting to obtain live lock >13:51:32,933 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221035: Live Server Obtained live lock >13:51:32,950 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-15) JBAS010400: Bound data source [java:jboss/datasources/brms-jdbc-ds] >13:51:32,951 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS] >13:51:32,951 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) JBAS010400: Bound data source [java:jboss/datasources/brms-jdbc-dsQuartz] >13:51:33,119 INFO [org.jboss.as.remoting] (MSC service thread 1-8) JBAS017100: Listening on 127.0.0.1:4647 >13:51:33,785 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221020: Started Netty Acceptor version 3.6.6.Final-redhat-1-fd3c6b7 127.0.0.1:5645 for CORE protocol >13:51:33,787 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221020: Started Netty Acceptor version 3.6.6.Final-redhat-1-fd3c6b7 127.0.0.1:5655 for CORE protocol >13:51:33,796 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221007: Server is now live >13:51:33,796 INFO [org.hornetq.core.server] (MSC service thread 1-13) HQ221001: HornetQ Server version 2.3.5.Final-redhat-2 (Monster Bee, 123) [f295bbfd-aa0e-11e3-909c-299a077080d4] >13:51:33,812 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory >13:51:33,814 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 63) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory >13:51:33,883 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-1) JBAS010406: Registered connection factory java:/JmsXA >13:51:33,910 INFO [org.hornetq.ra] (MSC service thread 1-1) HornetQ resource adaptor started >13:51:33,910 INFO [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-1) IJ020002: Deployed: file://RaActivatorhornetq-ra >13:51:33,913 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-11) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA] >13:51:33,948 INFO [org.hornetq.core.server] (Thread-5 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221027: Bridge ClusterConnectionBridge@636a29 [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@636a29 [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@15348729[nodeUUID=f295bbfd-aa0e-11e3-909c-299a077080d4, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost-localdomain, address=jms, server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]] is connected >13:51:33,961 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss BPM Suite 6.0.1.GA (AS 7.2.1.Final-redhat-10) started in 4201ms - Started 158 of 279 services (118 services are passive or on-demand) >13:52:29,214 INFO [org.jboss.as.server.deployment] (MSC service thread 1-9) JBAS015876: Starting deployment of "business-central.war" (runtime-name: "business-central.war") >13:52:54,454 WARN [org.jboss.as.server.deployment] (MSC service thread 1-6) 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. >13:52:54,454 WARN [org.jboss.as.server.deployment] (MSC service thread 1-6) 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. >13:52:54,454 WARN [org.jboss.as.server.deployment] (MSC service thread 1-6) 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. >13:52:54,454 WARN [org.jboss.as.server.deployment] (MSC service thread 1-6) 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. >13:52:54,455 WARN [org.jboss.as.server.deployment] (MSC service thread 1-6) 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. >13:52:54,455 WARN [org.jboss.as.server.deployment] (MSC service thread 1-6) 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. >13:52:54,455 WARN [org.jboss.as.server.deployment] (MSC service thread 1-6) 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. >13:52:54,889 INFO [org.jboss.as.jpa] (MSC service thread 1-7) JBAS011401: Read persistence.xml for org.jbpm.domain >13:52:55,422 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,423 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,423 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,423 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,427 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,427 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,428 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,491 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,497 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,497 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,497 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,497 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,497 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,497 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,502 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,502 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,503 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,503 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,522 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,522 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,526 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,526 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,527 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,527 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,532 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,532 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,533 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,533 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,534 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,534 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,536 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,536 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,537 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,537 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,537 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,538 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,538 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,539 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,539 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,539 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,539 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,540 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,543 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,543 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,553 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,554 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,554 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,554 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,554 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,554 WARN [org.jboss.as.dependency.private] (MSC service thread 1-11) 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. >13:52:55,568 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,569 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,569 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,569 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,570 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,570 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,588 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,588 WARN [org.jboss.as.dependency.unsupported] (MSC service thread 1-11) 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. >13:52:55,973 INFO [org.jboss.weld.deployer] (MSC service thread 1-14) JBAS016002: Processing weld deployment business-central.war >13:52:56,131 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-14) 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 > >13:52:56,445 INFO [org.jboss.weld.deployer] (MSC service thread 1-7) JBAS016005: Starting Services for CDI deployment: business-central.war >13:52:56,504 INFO [org.jboss.weld.Version] (MSC service thread 1-7) WELD-000900 1.1.13 (redhat) >13:52:56,579 INFO [org.jboss.weld.deployer] (MSC service thread 1-1) JBAS016008: Starting weld service for deployment business-central.war >13:52:56,581 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 64) JBAS011402: Starting Persistence Unit Service 'business-central.war#org.jbpm.domain' >13:52:56,584 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 65) HQ221003: trying to deploy queue jms.queue.KIE.TASK >13:52:56,612 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.TASK >13:52:56,612 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.TASK.ALL >13:52:56,616 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 67) HQ221003: trying to deploy queue jms.queue.KIE.SESSION >13:52:56,617 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 67) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.SESSION >13:52:56,617 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 67) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.SESSION.ALL >13:52:56,618 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 66) HQ221003: trying to deploy queue jms.queue.KIE.RESPONSE >13:52:56,620 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.RESPONSE >13:52:56,620 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.RESPONSE.ALL >13:52:56,621 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 68) HQ221003: trying to deploy queue jms.queue.KIE.AUDIT >13:52:56,622 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/queue/KIE.AUDIT >13:52:56,623 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011601: Bound messaging object to jndi name java:/queue/KIE.AUDIT.ALL >13:52:56,635 INFO [org.jboss.as.ejb3] (MSC service thread 1-13) JBAS014142: Started message driven bean 'KieSessionRequesMessageBean' with 'hornetq-ra' resource adapter >13:52:56,651 INFO [org.jboss.as.ejb3] (MSC service thread 1-12) JBAS014142: Started message driven bean 'JMSAuditProcessor' with 'hornetq-ra' resource adapter >13:52:56,665 INFO [org.jboss.as.ejb3] (MSC service thread 1-16) JBAS014142: Started message driven bean 'TaskServiceRequesMessageBean' with 'hornetq-ra' resource adapter >13:52:56,794 INFO [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 64) HCANN000001: Hibernate Commons Annotations {4.0.1.Final-redhat-2} >13:52:56,801 INFO [org.hibernate.Version] (ServerService Thread Pool -- 64) HHH000412: Hibernate Core {4.2.0.SP1-redhat-1} >13:52:56,803 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 64) HHH000206: hibernate.properties not found >13:52:56,806 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 64) HHH000021: Bytecode provider name : javassist >13:52:56,832 INFO [org.hibernate.ejb.Ejb3Configuration] (ServerService Thread Pool -- 64) HHH000204: Processing PersistenceUnitInfo [ > name: org.jbpm.domain > ...] >13:52:57,795 INFO [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (ServerService Thread Pool -- 64) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider >13:52:58,018 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 64) HHH000400: Using dialect: org.hibernate.dialect.SQLServer2008Dialect >13:52:58,044 INFO [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (ServerService Thread Pool -- 64) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory >13:52:58,049 INFO [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (ServerService Thread Pool -- 64) HHH000397: Using ASTQueryTranslatorFactory >13:52:58,093 INFO [org.hibernate.validator.internal.util.Version] (ServerService Thread Pool -- 64) HV000001: Hibernate Validator 4.3.1.Final-redhat-1 >13:52:58,726 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000228: Running hbm2ddl schema update >13:52:58,726 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000102: Fetching database metadata >13:52:58,731 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000396: Updating schema >13:52:58,762 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Attachment >13:52:58,771 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BAMTaskSummary >13:52:58,778 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: BooleanExpression >13:52:58,785 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Content >13:52:58,793 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ContextMappingInfo >13:52:58,800 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationKeyInfo >13:52:58,807 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: CorrelationPropertyInfo >13:52:58,814 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Deadline >13:52:58,822 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Delegation_delegates >13:52:58,829 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ErrorInfo >13:52:58,836 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Escalation >13:52:58,843 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: EventTypes >13:52:58,850 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: I18NText >13:52:58,858 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: NodeInstanceLog >13:52:58,865 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification >13:52:58,872 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_BAs >13:52:58,880 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_Recipients >13:52:58,894 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Notification_email_header >13:52:58,901 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: OrganizationalEntity >13:52:58,912 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_BAs >13:52:58,924 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_ExclOwners >13:52:58,936 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_PotOwners >13:52:58,960 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Recipients >13:52:58,972 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: PeopleAssignments_Stakeholders >13:52:58,984 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceInfo >13:52:58,996 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: ProcessInstanceLog >13:52:59,008 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment >13:52:59,020 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Reassignment_potentialOwners >13:52:59,032 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: RequestInfo >13:52:59,044 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: SessionInfo >13:52:59,057 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: Task >13:52:59,075 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskDef >13:52:59,082 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: TaskEvent >13:52:59,093 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: VariableInstanceLog >13:52:59,109 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: WorkItemInfo >13:52:59,121 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: email_header >13:52:59,133 INFO [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 64) HHH000262: Table not found: task_comment >13:53:01,530 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Attachment >13:53:01,530 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, attachmentcontentid, taskdata_attachments_id, attachment_size, attachedby_id, name, attachedat, contenttype, accesstype] >13:53:01,531 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_7ndpfa311i50bq7hy18q05va3, fk_hqupx569krp0f0sgu9kh87513] >13:53:01,531 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__attachme__3213e83fc202b867] >13:53:01,542 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.BAMTaskSummary >13:53:01,542 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [duration, startdate, status, taskid, userid, createddate, taskname, enddate, optlock, processinstanceid, pk] >13:53:01,542 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,542 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__bamtasks__321403cf7a6a2ae9] >13:53:01,551 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.BooleanExpression >13:53:01,551 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [expression, id, escalation_constraints_id, type] >13:53:01,552 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_394nf2qoc0k9ok6omgd6jtpso] >13:53:01,552 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__booleane__3213e83fc1be08c1] >13:53:01,559 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Content >13:53:01,560 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [content, id] >13:53:01,560 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,560 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__content__3213e83f03227461] >13:53:01,568 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.ContextMappingInfo >13:53:01,568 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [context_id, mappingid, optlock, ksession_id] >13:53:01,568 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,569 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__contextm__f8a9097e62cbb36b] >13:53:01,578 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.CorrelationKeyInfo >13:53:01,578 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [keyid, name, optlock, processinstanceid] >13:53:01,578 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,578 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__correlat__6079c1b8551316ca] >13:53:01,589 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.CorrelationPropertyInfo >13:53:01,589 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [name, value, correlationkey_keyid, optlock, propertyid] >13:53:01,590 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_hrmx1m882cejwj9c04ixh50i4] >13:53:01,590 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__correlat__9c0b8c7deafd0482] >13:53:01,599 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Deadline >13:53:01,599 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, escalated, deadlines_startdeadline_id, deadlines_enddeadline_id, deadline_date] >13:53:01,599 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_68w742sge00vco2cq3jhbvmgx, fk_euoohoelbqvv94d8a8rcg8s5n] >13:53:01,599 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__deadline__3213e83fc0fc9023] >13:53:01,618 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Delegation_delegates >13:53:01,618 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,618 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_fajq6kossbsqwr3opkrctxei3, fk_gn7ula51sk55wj1o1m57guqxb] >13:53:01,618 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,627 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.ErrorInfo >13:53:01,627 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [timestamp, message, id, request_id, stacktrace] >13:53:01,627 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_cms0met37ggfw5p5gci3otaq0] >13:53:01,627 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__errorinf__3213e83f4948517b] >13:53:01,635 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Escalation >13:53:01,635 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, deadline_escalation_id, name] >13:53:01,635 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_ay2gd4fvl9yaapviyxudwuvfg] >13:53:01,635 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__escalati__3213e83f000bf8d0] >13:53:01,643 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.EventTypes >13:53:01,643 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [element, instanceid] >13:53:01,643 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_nrecj4617iwxlc65ij6m7lsl1] >13:53:01,643 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,657 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.I18NText >13:53:01,657 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [text, notification_names_id, notification_subjects_id, task_descriptions_id, id, task_names_id, notification_descriptions_id, reassignment_documentation_id, deadline_documentation_id, language, shorttext, notification_documentation_id, task_subjects_id] >13:53:01,657 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_g1trxri8w64enudw2t1qahhk5, fk_fd9uk6hemv2dx1ojovo7ms3vp, fk_bw8vmpekejxt1ei2ge26gdsry, fk_qoce92c70adem3ccb3i7lec8x, fk_o84rkh69r47ti8uv4eyj7bmo2, fk_k16jpgrh67ti9uedf6konsu1p, fk_21qvifarxsvuxeaw5sxwh473w, fk_pqarjvvnwfjpeyb87yd7m0bfi, fk_4eyfp69ucrron2hr7qx4np2fp] >13:53:01,657 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__i18ntext__3213e83f5487cd67] >13:53:01,668 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.NodeInstanceLog >13:53:01,668 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, connection, externalid, nodetype, workitemid, nodename, log_date, nodeinstanceid, type, processinstanceid, processid, nodeid] >13:53:01,668 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,668 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__nodeinst__3213e83fde5da6fc] >13:53:01,676 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Notification >13:53:01,677 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, escalation_notifications_id, priority, dtype] >13:53:01,677 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_bdbeml3768go5im41cgfpyso9] >13:53:01,677 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__notifica__3213e83f57283bc6] >13:53:01,693 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Notification_BAs >13:53:01,694 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,694 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_fc0uuy76t2bvxaxqysoo8xts7, fk_mfbsnbrhth4rjhqc2ud338s4i] >13:53:01,694 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,710 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Notification_Recipients >13:53:01,710 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,710 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_blf9jsrumtrthdaqnpwxt25eu, fk_3l244pj8sh78vtn9imaymrg47] >13:53:01,710 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,728 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Notification_email_header >13:53:01,728 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [emailheaders_id, notification_id, mapkey] >13:53:01,728 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_eth4nvxn21fk1vnju85vkjrai, fk_ptaka5kost68h7l3wflv7w6y8] >13:53:01,728 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__notifica__e94a0320793d5a28, uk_ptaka5kost68h7l3wflv7w6y8] >13:53:01,736 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.OrganizationalEntity >13:53:01,736 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, dtype] >13:53:01,736 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,736 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__organiza__3213e83f2074bf99] >13:53:01,752 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.PeopleAssignments_BAs >13:53:01,752 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,753 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_t38xbkrq6cppifnxequhvjsl2, fk_omjg5qh7uv8e9bolbaq7hv6oh] >13:53:01,753 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,769 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.PeopleAssignments_ExclOwners >13:53:01,769 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,769 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_pth28a73rj6bxtlfc69kmqo0a, fk_b8owuxfrdng050ugpk0pdowa7] >13:53:01,769 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,786 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.PeopleAssignments_PotOwners >13:53:01,786 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,786 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_4dv2oji7pr35ru0w45trix02x, fk_tee3ftir7xs6eo3fdvi3xw026] >13:53:01,786 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,803 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.PeopleAssignments_Recipients >13:53:01,803 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,803 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_4g7y3wx6gnokf6vycgpxs83d6, fk_enhk831fghf6akjilfn58okl4] >13:53:01,803 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,820 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.PeopleAssignments_Stakeholders >13:53:01,820 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,820 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_4bh3ay74x6ql9usunubttfdf1, fk_met63inaep6cq4ofb3nnxi4tm] >13:53:01,821 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,831 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.ProcessInstanceInfo >13:53:01,831 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [startdate, lastreaddate, state, processinstancebytearray, optlock, instanceid, processid, lastmodificationdate] >13:53:01,831 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,831 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__processi__5c51994f155b4c02] >13:53:01,842 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.ProcessInstanceLog >13:53:01,842 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, processname, end_date, duration, externalid, status, processversion, parentprocessinstanceid, start_date, outcome, user_identity, processinstanceid, processid] >13:53:01,842 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,842 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__processi__3213e83fca827ba5] >13:53:01,849 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Reassignment >13:53:01,849 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [escalation_reassignments_id, id] >13:53:01,850 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_pnpeue9hs6kx2ep0sp16b6kfd] >13:53:01,850 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__reassign__3213e83f826f9064] >13:53:01,867 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Reassignment_potentialOwners >13:53:01,867 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [task_id, entity_id] >13:53:01,867 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_8frl6la7tgparlnukhp8xmody, fk_qbega5ncu6b9yigwlw55aeijn] >13:53:01,867 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [] >13:53:01,879 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.RequestInfo >13:53:01,879 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [timestamp, message, businesskey, executions, id, responsedata, requestdata, deploymentid, status, retries, commandname] >13:53:01,879 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,879 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__requesti__3213e83f268a5c4b] >13:53:01,888 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.SessionInfo >13:53:01,889 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [rulesbytearray, id, startdate, optlock, lastmodificationdate] >13:53:01,889 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,889 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__sessioni__3213e83ff9371f50] >13:53:01,907 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.Task >13:53:01,907 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [tasktype, outputcontentid, parentid, optlock, expirationtime, previousstatus, id, faultname, createdby_id, skipable, deploymentid, archived, priority, faulttype, taskinitiator_id, documentcontentid, faultaccesstype, outputaccesstype, allowedtodelegate, status, documentaccesstype, outputtype, createdon, activationtime, faultcontentid, processinstanceid, processsessionid, actualowner_id, workitemid, formname, documenttype, subtaskstrategy, processid] >13:53:01,907 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_nh9nnt47f3l61qjlyedqt05rf, fk_dpk0f9ucm14c78bsxthh7h8yh, fk_k02og0u71obf1uxgcdjx9rcjc] >13:53:01,907 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__task__3213e83f2abd59d1] >13:53:01,915 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.TaskDef >13:53:01,915 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, priority, name] >13:53:01,915 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,916 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__taskdef__3213e83f2319f0b8] >13:53:01,925 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.TaskEvent >13:53:01,925 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, logtime, taskid, userid, type, optlock] >13:53:01,925 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,926 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__taskeven__3213e83f7c163627] >13:53:01,936 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.VariableInstanceLog >13:53:01,936 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, externalid, variableinstanceid, oldvalue, variableid, value, log_date, processinstanceid, processid] >13:53:01,936 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,937 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__variable__3213e83fa976353e] >13:53:01,945 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.WorkItemInfo >13:53:01,946 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [workitembytearray, name, workitemid, state, optlock, creationdate, processinstanceid] >13:53:01,946 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,946 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__workitem__836b1232dcb95888] >13:53:01,963 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.email_header >13:53:01,963 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, body, replytoaddress, subject, fromaddress, language] >13:53:01,963 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [] >13:53:01,963 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__email_he__3213e83ffb952243] >13:53:01,980 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000261: Table found: dballo03.dbo.task_comment >13:53:01,980 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000037: Columns: [id, taskdata_comments_id, text, addedat, addedby_id] >13:53:01,980 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000108: Foreign keys: [fk_aax378yjnsmw9kb9vsu994jjv, fk_1ws9jdmhtey6mxu7jb0r0ufvs] >13:53:01,981 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 64) HHH000126: Indexes: [pk__task_com__3213e83fe16913d4] >13:53:01,985 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Attachment (id bigint identity not null, accessType int, attachedAt datetime2, attachmentContentId bigint not null, contentType varchar(255), name varchar(255), attachment_size int, attachedBy_id varchar(255), TaskData_Attachments_Id bigint, primary key (id)) >13:53:01,985 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Attachment' in the database. >13:53:01,994 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table BAMTaskSummary (pk bigint identity not null, createdDate datetime2, duration bigint, endDate datetime2, processInstanceId bigint not null, startDate datetime2, status varchar(255), taskId bigint not null, taskName varchar(255), userId varchar(255), OPTLOCK int, primary key (pk)) >13:53:01,994 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'BAMTaskSummary' in the database. >13:53:01,996 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table BooleanExpression (id bigint identity not null, expression varchar(MAX), type varchar(255), Escalation_Constraints_Id bigint, primary key (id)) >13:53:01,996 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'BooleanExpression' in the database. >13:53:01,997 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Content (id bigint identity not null, content varbinary(MAX), primary key (id)) >13:53:01,997 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Content' in the database. >13:53:01,999 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table ContextMappingInfo (mappingId bigint identity not null, CONTEXT_ID varchar(255) not null, KSESSION_ID int not null, OPTLOCK int, primary key (mappingId)) >13:53:01,999 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'ContextMappingInfo' in the database. >13:53:02,001 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table CorrelationKeyInfo (keyId bigint identity not null, name varchar(255), processInstanceId bigint not null, OPTLOCK int, primary key (keyId)) >13:53:02,001 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'CorrelationKeyInfo' in the database. >13:53:02,002 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table CorrelationPropertyInfo (propertyId bigint identity not null, name varchar(255), value varchar(255), OPTLOCK int, correlationKey_keyId bigint, primary key (propertyId)) >13:53:02,003 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'CorrelationPropertyInfo' in the database. >13:53:02,004 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Deadline (id bigint identity not null, deadline_date datetime2, escalated smallint, Deadlines_StartDeadLine_Id bigint, Deadlines_EndDeadLine_Id bigint, primary key (id)) >13:53:02,004 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Deadline' in the database. >13:53:02,006 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Delegation_delegates (task_id bigint not null, entity_id varchar(255) not null) >13:53:02,006 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Delegation_delegates' in the database. >13:53:02,008 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table ErrorInfo (id bigint identity not null, message varchar(255), stacktrace varchar(5000), timestamp datetime2, REQUEST_ID bigint not null, primary key (id)) >13:53:02,008 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'ErrorInfo' in the database. >13:53:02,009 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Escalation (id bigint identity not null, name varchar(255), Deadline_Escalation_Id bigint, primary key (id)) >13:53:02,009 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Escalation' in the database. >13:53:02,011 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table EventTypes (InstanceId bigint not null, element varchar(255)) >13:53:02,011 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'EventTypes' in the database. >13:53:02,013 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table I18NText (id bigint identity not null, language varchar(255), shortText varchar(255), text varchar(MAX), 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)) >13:53:02,013 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'I18NText' in the database. >13:53:02,015 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table NodeInstanceLog (id bigint identity not null, connection varchar(255), log_date datetime2, externalId varchar(255), nodeId varchar(255), nodeInstanceId varchar(255), nodeName varchar(255), nodeType varchar(255), processId varchar(255), processInstanceId bigint not null, type int not null, workItemId bigint, primary key (id)) >13:53:02,015 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'NodeInstanceLog' in the database. >13:53:02,020 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Notification (DTYPE varchar(31) not null, id bigint identity not null, priority int not null, Escalation_Notifications_Id bigint, primary key (id)) >13:53:02,020 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Notification' in the database. >13:53:02,022 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) >13:53:02,022 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Notification_BAs' in the database. >13:53:02,023 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) >13:53:02,023 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Notification_Recipients' in the database. >13:53:02,025 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)) >13:53:02,025 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Notification_email_header' in the database. >13:53:02,027 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)) >13:53:02,027 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'OrganizationalEntity' in the database. >13:53:02,028 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) >13:53:02,028 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'PeopleAssignments_BAs' in the database. >13:53:02,030 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) >13:53:02,030 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'PeopleAssignments_ExclOwners' in the database. >13:53:02,032 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) >13:53:02,032 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'PeopleAssignments_PotOwners' in the database. >13:53:02,033 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) >13:53:02,034 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'PeopleAssignments_Recipients' in the database. >13:53:02,035 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) >13:53:02,035 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'PeopleAssignments_Stakeholders' in the database. >13:53:02,037 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table ProcessInstanceInfo (InstanceId bigint identity not null, lastModificationDate datetime2, lastReadDate datetime2, processId varchar(255), processInstanceByteArray varbinary(MAX), startDate datetime2, state int not null, OPTLOCK int, primary key (InstanceId)) >13:53:02,037 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'ProcessInstanceInfo' in the database. >13:53:02,039 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table ProcessInstanceLog (id bigint identity not null, duration bigint, end_date datetime2, externalId varchar(255), user_identity varchar(255), outcome varchar(255), parentProcessInstanceId bigint, processId varchar(255), processInstanceId bigint not null, processName varchar(255), processVersion varchar(255), start_date datetime2, status int, primary key (id)) >13:53:02,039 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'ProcessInstanceLog' in the database. >13:53:02,040 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Reassignment (id bigint identity not null, Escalation_Reassignments_Id bigint, primary key (id)) >13:53:02,041 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Reassignment' in the database. >13:53:02,042 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Reassignment_potentialOwners (task_id bigint not null, entity_id varchar(255) not null) >13:53:02,042 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Reassignment_potentialOwners' in the database. >13:53:02,044 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table RequestInfo (id bigint identity not null, commandName varchar(255), deploymentId varchar(255), executions int not null, businessKey varchar(255), message varchar(255), requestData varbinary(MAX), responseData varbinary(MAX), retries int not null, status varchar(255), timestamp datetime2, primary key (id)) >13:53:02,044 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'RequestInfo' in the database. >13:53:02,046 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table SessionInfo (id int identity not null, lastModificationDate datetime2, rulesByteArray varbinary(MAX), startDate datetime2, OPTLOCK int, primary key (id)) >13:53:02,046 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'SessionInfo' in the database. >13:53:02,048 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table Task (id bigint identity not null, archived smallint, allowedToDelegate varchar(255), formName varchar(255), priority int not null, subTaskStrategy varchar(255), activationTime datetime2, createdOn datetime2, deploymentId varchar(255), documentAccessType int, documentContentId bigint not null, documentType varchar(255), expirationTime datetime2, faultAccessType int, faultContentId bigint not null, faultName varchar(255), faultType varchar(255), outputAccessType int, outputContentId bigint not null, outputType varchar(255), parentId bigint not null, previousStatus int, processId varchar(255), processInstanceId bigint not null, processSessionId int not null, skipable bit not null, status varchar(255), workItemId bigint not null, taskType varchar(255), OPTLOCK int, taskInitiator_id varchar(255), actualOwner_id varchar(255), createdBy_id varchar(255), primary key (id)) >13:53:02,048 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'Task' in the database. >13:53:02,050 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table TaskDef (id bigint identity not null, name varchar(255), priority int not null, primary key (id)) >13:53:02,050 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'TaskDef' in the database. >13:53:02,052 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table TaskEvent (id bigint identity not null, logTime datetime2, taskId bigint, type varchar(255), userId varchar(255), OPTLOCK int, primary key (id)) >13:53:02,052 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'TaskEvent' in the database. >13:53:02,054 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table VariableInstanceLog (id bigint identity not null, log_date datetime2, externalId varchar(255), oldValue varchar(255), processId varchar(255), processInstanceId bigint not null, value varchar(255), variableId varchar(255), variableInstanceId varchar(255), primary key (id)) >13:53:02,054 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'VariableInstanceLog' in the database. >13:53:02,062 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table WorkItemInfo (workItemId bigint identity not null, creationDate datetime2, name varchar(255), processInstanceId bigint not null, state bigint not null, OPTLOCK int, workItemByteArray varbinary(MAX), primary key (workItemId)) >13:53:02,062 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'WorkItemInfo' in the database. >13:53:02,064 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table email_header (id bigint identity not null, body varchar(MAX), fromAddress varchar(255), language varchar(255), replyToAddress varchar(255), subject varchar(255), primary key (id)) >13:53:02,064 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'email_header' in the database. >13:53:02,066 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000388: Unsuccessful: create table task_comment (id bigint identity not null, addedAt datetime2, text varchar(MAX), addedBy_id varchar(255), TaskData_Comments_Id bigint, primary key (id)) >13:53:02,066 ERROR [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) There is already an object named 'task_comment' in the database. >13:53:02,066 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 64) HHH000232: Schema update complete >13:53:03,285 INFO [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-4) Solder Config XML provider starting... >13:53:03,286 INFO [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-4) Loading XmlDocumentProvider: org.jboss.solder.config.xml.bootstrap.ResourceLoaderXmlDocumentProvider >13:53:03,301 INFO [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-4) 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 >13:53:03,308 INFO [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-4) Reading XML file: jar:file:/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/modules/system/layers/bpms/org/jbpm/main/jbpm-kie-services-6.0.2-redhat-4.jar!/META-INF/beans.xml >13:53:03,310 INFO [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-4) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/uberfire-api-0.3.2-redhat-4.jar/META-INF/beans.xml >13:53:03,312 INFO [org.jboss.solder.config.xml.bootstrap.XmlConfigExtension] (MSC service thread 1-4) Reading XML file: vfs:/content/business-central.war/WEB-INF/lib/uberfire-security-server-0.3.2-redhat-4.jar/META-INF/beans.xml >13:53:03,314 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) starting errai cdi ... >13:53:03,325 INFO [org.jboss.solder.Version] (MSC service thread 1-4) Solder 3.2.1.Final (build id: 3.2.1.Final) >13:53:03,541 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.config.ResourceConfigServiceImpl >13:53:03,575 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.version.VersionServiceImpl >13:53:03,615 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@Service class org.guvnor.common.services.backend.file.RenameServiceImpl >13:53:03,635 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.metadata.MetadataServiceImpl >13:53:03,644 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@Service class org.guvnor.common.services.backend.file.CopyServiceImpl >13:53:03,648 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.config.AppConfigServiceImpl >13:53:03,666 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@Service class org.guvnor.common.services.backend.file.DeleteServiceImpl >13:53:03,700 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.metadata.CategoryServiceImpl >13:53:03,755 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.backend.rulenames.RuleNamesServiceImpl >13:53:03,804 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.formModeler.panels.modeler.backend.FormModelerServiceImpl >13:53:04,193 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.inbox.backend.server.InboxServiceImpl >13:53:04,240 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@Service @SessionScoped class org.jbpm.formModeler.renderer.backend.service.FormRenderingServiceImpl >13:53:04,243 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.formModeler.renderer.backend.service.FormRendererIncluderServiceImpl >13:53:04,271 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.testscenario.backend.server.ScenarioTestEditorServiceImpl >13:53:04,402 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.globals.backend.server.GlobalsEditorServiceImpl >13:53:04,469 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.dtablexls.backend.server.DecisionTableXLSServiceImpl >13:53:04,515 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.FormModelerProcessStarterEntryPointImpl >13:53:04,519 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.TypeRoleServiceEntryPointImpl >13:53:04,531 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.TaskServiceEntryPointImpl >13:53:04,542 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.FormServiceEntryPointImpl >13:53:04,546 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.ht.backend.server.UserServiceEntryPointImpl >13:53:04,705 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.explorer.backend.server.ExplorerServiceImpl >13:53:04,805 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.project.backend.server.ProjectServiceImpl >13:53:04,808 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.project.backend.server.KModuleServiceImpl >13:53:04,821 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.project.backend.server.POMServiceImpl >13:53:04,893 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.datamodel.backend.server.IncrementalDataModelServiceImpl >13:53:05,334 INFO [org.jboss.errai.common.metadata.MetaDataScanner] (Thread-96) added class scanning extensions: org.jboss.errai.common.metadata.JbossVFsTypeHandler >13:53:06,777 INFO [org.jboss.errai.reflections.Reflections] (Thread-96) Reflections took 1440 ms to scan 95 urls, producing 2096 keys and 10015 values [using 2 cores] >13:53:07,499 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.bd.backend.server.DataServiceEntryPointImpl >13:53:07,503 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.bd.backend.server.KieSessionEntryPointImpl >13:53:07,529 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.bd.backend.server.DeploymentManagerEntryPointImpl >13:53:08,053 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.console.ng.es.backend.server.ExecutorServiceEntryPointImpl >13:53:08,066 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.enums.backend.server.EnumServiceImpl >13:53:08,077 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.search.backend.server.SearchServiceImpl >13:53:08,090 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.scorecard.backend.server.GuidedScoreCardEditorServiceImpl >13:53:08,128 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.rule.backend.server.GuidedRuleEditorServiceImpl >13:53:08,142 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.rule.backend.server.EnumDropdownServiceImpl >13:53:08,163 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.scorecardxls.backend.server.ScoreCardXLSServiceImpl >13:53:08,671 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.dtable.backend.server.GuidedDecisionTableEditorServiceImpl >13:53:08,688 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.FileNavigatorServiceImpl >13:53:08,702 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.WorkbenchServicesImpl >13:53:08,728 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.VFSServicesServerImpl >13:53:08,755 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.plugin.RuntimePluginsServiceServerImpl >13:53:08,758 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.repositories.RepositoryServiceEditorImpl >13:53:08,768 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.organizationalunit.OrganizationalUnitServiceImpl >13:53:08,772 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.uberfire.backend.server.repositories.RepositoryServiceImpl >13:53:08,799 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.dashboard.renderer.service.impl.DashboardRendererServiceImpl >13:53:08,811 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.dsltext.backend.server.DSLTextEditorServiceImpl >13:53:09,104 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.m2repo.backend.server.M2RepoServiceImpl >13:53:09,452 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.jbpm.designer.server.service.DefaultDesignerAssetService >13:53:09,619 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.defaulteditor.backend.server.DefaultEditorServiceImpl >13:53:09,830 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.projecteditor.backend.server.ProjectScreenServiceImpl >13:53:09,854 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.drltext.backend.server.DRLTextEditorServiceImpl >13:53:09,965 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.guided.template.server.GuidedRuleTemplateEditorServiceImpl >13:53:09,992 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.screens.datamodeller.backend.server.DataModelerServiceImpl >13:53:10,040 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.backend.source.SourceServicesImpl >13:53:10,045 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.backend.security.KieWorkbenchSecurityServiceImpl >13:53:10,047 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.kie.workbench.common.services.backend.validation.ValidationServiceImpl >13:53:10,078 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.guvnor.common.services.builder.BuildServiceImpl >13:53:10,097 INFO [org.jboss.errai.cdi.server.CDIExtensionPoints] (MSC service thread 1-4) discovered errai service: public@ApplicationScoped @Service class org.drools.workbench.screens.workitems.backend.server.WorkItemsEditorServiceImpl >13:53:10,267 INFO [org.drools.compiler.kie.builder.impl.ClasspathKieProject] (MSC service thread 1-4) Found kmodule: vfs:/content/business-central.war/WEB-INF/lib/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar/META-INF/kmodule.xml >13:53:10,268 INFO [org.drools.compiler.kie.builder.impl.ClasspathKieProject] (MSC service thread 1-4) Virtual file physical path = /mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/tmp/vfs/deploymente29a5d7fc550c1c5/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar-bb8eb4483f0888d0/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar >13:53:10,307 INFO [org.drools.compiler.kie.builder.impl.KieRepositoryImpl] (MSC service thread 1-4) KieModule was added:ZipKieModule[ ReleaseId=org.drools:drools-wb-rest-defaultapprover:6.0.2-redhat-4file=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/domain/servers/server-two/tmp/vfs/deploymente29a5d7fc550c1c5/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar-bb8eb4483f0888d0/drools-wb-rest-defaultapprover-6.0.2-redhat-4.jar] >13:53:15,433 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-4) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.guvnor.inbox.backend.server.InboxServiceImpl.identity >13:53:15,533 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-4) 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 >13:53:15,533 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-4) 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 >13:53:15,583 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-4) 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 >13:53:15,643 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-4) WELD-001440 Scope type @javax.enterprise.context.SessionScoped() used on injection point [field] @Inject @SessionScoped private org.uberfire.backend.server.UserServicesImpl.identity >13:53:15,678 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-4) WELD-001440 Scope type @javax.enterprise.context.RequestScoped() used on injection point [field] @Inject @RequestScoped private org.jbpm.designer.repository.vfs.VFSRepository.descriptor >13:53:15,827 INFO [org.apache.helix.controller.GenericHelixController] (MSC service thread 1-4) createDefaultRegistry >13:53:15,841 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-4) Create a zk-based cluster manager. zkSvr: localhost:2181, clusterName: bpms-cluster, instanceName: nodeTwo_12346, type: PARTICIPANT >13:53:15,843 INFO [org.apache.helix.HelixManagerProperties] (MSC service thread 1-4) load helix-manager properties: {minimum_supported_version.batch_message=0.6.1, clustermanager.version=0.6.2-incubating, minimum_supported_version.participant=0.4} >13:53:15,854 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-4) Added msg-factory for type: TASK_REPLY, threadpool size 40 >13:53:15,889 INFO [org.apache.sshd.common.util.SecurityUtils] (MSC service thread 1-4) BouncyCastle not registered, using the default JCE provider >13:53:16,518 INFO [org.guvnor.m2repo.backend.server.GuvnorM2Repository] (MSC service thread 1-4) Maven Repository root set to: repositories/kie >13:53:16,716 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'type' to 'kjar'. >13:53:16,716 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'drools.dateformat' to 'dd-MMM-yyyy'. >13:53:16,717 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'drools.defaultlanguage' to 'en'. >13:53:16,717 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'rule-modeller-onlyShowDSLStatements' to 'false'. >13:53:16,717 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'designer.context' to 'designer'. >13:53:16,717 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'drools.defaultcountry' to 'US'. >13:53:16,717 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'drools.datetimeformat' to 'dd-MMM-yyyy hh:mm:ss'. >13:53:16,717 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'build.enable-incremental' to 'true'. >13:53:16,717 INFO [org.guvnor.common.services.backend.config.ApplicationPreferencesLoader] (MSC service thread 1-4) Setting preference 'designer.profile' to 'jbpm'. >13:53:16,723 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-4) ClusterManager.connect() >13:53:16,736 INFO [org.I0Itec.zkclient.ZkEventThread] (ZkClient-EventThread-197-localhost:2181) Starting ZkClient event thread. >13:53:16,742 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:zookeeper.version=3.3.3-1203054, built on 11/17/2011 05:47 GMT >13:53:16,742 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:host.name=dev114.mw.lab.eng.bos.redhat.com >13:53:16,742 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:java.version=1.7.0_45 >13:53:16,742 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:java.vendor=Oracle Corporation >13:53:16,742 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:java.home=/qa/tools/opt/jdk1.7.0_45/jre >13:53:16,742 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:java.class.path=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1/jboss-modules.jar >13:53:16,742 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:java.library.path=/qa/tools/opt/jdk1.7.0_45/jre/lib/i386/server:/qa/tools/opt/jdk1.7.0_45/jre/lib/i386:/qa/tools/opt/jdk1.7.0_45/jre/../lib/i386:/qa/tools/opt/jdk1.6.0_45/jre/lib/i386/server:/qa/tools/opt/jdk1.6.0_45/jre/lib/i386:/qa/tools/opt/jdk1.6.0_45/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib >13:53:16,742 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:java.io.tmpdir=/tmp >13:53:16,743 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:java.compiler=<NA> >13:53:16,743 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:os.name=Linux >13:53:16,743 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:os.arch=i386 >13:53:16,743 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:os.version=2.6.18-371.3.1.el5PAE >13:53:16,743 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:user.name=hudson >13:53:16,743 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:user.home=/home/hudson >13:53:16,743 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Client environment:user.dir=/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/jboss-eap-6.1 >13:53:16,744 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-4) Initiating client connection, connectString=localhost:2181 sessionTimeout=30000 watcher=org.apache.helix.manager.zk.ZkClient@1d2230e >13:53:16,753 INFO [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-4-SendThread()) Opening socket connection to server localhost/127.0.0.1:2181 >13:53:16,755 INFO [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-4-SendThread(localhost:2181)) Socket connection established to localhost/127.0.0.1:2181, initiating session >13:53:16,768 INFO [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-4-SendThread(localhost:2181)) Session establishment complete on server localhost/127.0.0.1:2181, sessionid = 0x144b76b6dce0007, negotiated timeout = 30000 >13:53:16,770 INFO [org.I0Itec.zkclient.ZkClient] (MSC service thread 1-4-EventThread) zookeeper state changed (SyncConnected) >13:53:16,784 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-4) KeeperState: SyncConnected, zookeeper:State:CONNECTED Timeout:30000 sessionid:0x144b76b6dce0007 local:/127.0.0.1:46070 remoteserver:localhost/127.0.0.1:2181 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0 >13:53:16,785 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-4) Handling new session, session id: 144b76b6dce0007, instance: nodeTwo_12346, instanceTye: PARTICIPANT, cluster: bpms-cluster, zkconnection: State:CONNECTED Timeout:30000 sessionid:0x144b76b6dce0007 local:/127.0.0.1:46070 remoteserver:localhost/127.0.0.1:2181 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0 >13:53:16,785 WARN [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-4) ParticipantHealthReportTimerTask already stopped >13:53:16,872 INFO [org.apache.helix.manager.zk.ParticipantManagerHelper] (MSC service thread 1-4) instance: nodeTwo_12346 auto-joining bpms-cluster is false >13:53:16,900 INFO [org.apache.helix.messaging.DefaultMessagingService] (MSC service thread 1-4) registering msg factory for type STATE_TRANSITION >13:53:16,928 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-4) Added msg-factory for type: STATE_TRANSITION, threadpool size 40 >13:53:16,961 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-4) 14 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:53:16,961 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-4) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:53:16,982 WARN [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-4) Fail to create msg-handler because cannot find stateModelFactory for model: LeaderStandby using factoryName: DEFAULT for resource: vfs-repo >13:53:16,982 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-4) Dropping NO-OP message. mid: c106d34c-e7fb-43dc-bacc-a9afbd6d1d0c, from: nodeTwo_12346 >13:53:16,991 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-4) 14 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 29ms >13:53:16,991 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-4) Added listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 for type: MESSAGES to path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES >13:53:16,991 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-4) 14 START:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor >13:53:16,991 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-4) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/CONTROLLER, listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@69ad0b >13:53:16,994 INFO [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-4) Controller has changed >13:53:17,002 INFO [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-4) _zkPropertyTransferSvcUrl : null Controller dev114.mw.lab.eng.bos.redhat.com-CONTROLLER >13:53:17,003 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-4) 14 END:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor Took: 12ms >13:53:17,003 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-4) Added listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@69ad0b for type: CONTROLLER to path: /bpms-cluster/CONTROLLER >13:53:17,003 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:53:17,003 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:53:17,006 INFO [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-4) Register state model factory for state model SchedulerTaskQueue using factory name DEFAULT with org.apache.helix.participant.statemachine.ScheduledTaskStateModelFactory@e9aefd >13:53:17,010 WARN [org.apache.helix.participant.HelixStateMachineEngine] (ZkClient-EventThread-197-localhost:2181) Fail to create msg-handler because cannot find stateModelFactory for model: LeaderStandby using factoryName: DEFAULT for resource: vfs-repo >13:53:17,010 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 7ms >13:53:17,016 INFO [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-4) Send NO_OP message to nodeTwo_12346, msgId: f1625df0-5be2-49ab-9d32-21736243fac5 >13:53:17,022 INFO [org.apache.helix.manager.zk.ParticipantManagerHelper] (MSC service thread 1-4) Created healthcheck info path /bpms-cluster/INSTANCES/nodeTwo_12346/HEALTHREPORT >13:53:17,022 INFO [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-4) Start HealthCheckInfoReportingTask >13:53:17,022 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:53:17,023 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:53:17,027 WARN [org.apache.helix.participant.HelixStateMachineEngine] (ZkClient-EventThread-197-localhost:2181) Fail to create msg-handler because cannot find stateModelFactory for model: LeaderStandby using factoryName: DEFAULT for resource: vfs-repo >13:53:17,027 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Dropping NO-OP message. mid: f1625df0-5be2-49ab-9d32-21736243fac5, from: nodeTwo_12346 >13:53:17,034 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms >13:53:17,035 WARN [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-4) Skip processing callbacks for listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22, path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, expected types: [CALLBACK, FINALIZE] but was INIT >13:53:17,035 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-4) init handler: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:53:17,035 WARN [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-4) Skip processing callbacks for listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@69ad0b, path: /bpms-cluster/CONTROLLER, expected types: [CALLBACK, FINALIZE] but was INIT >13:53:17,036 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-4) init handler: /bpms-cluster/CONTROLLER, org.apache.helix.manager.zk.ZKHelixDataAccessor@69ad0b >13:53:17,036 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:53:17,036 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:53:17,036 INFO [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-4) Register state model factory for state model LeaderStandby using factory name DEFAULT with org.uberfire.io.impl.cluster.helix.LockTransitionalFactory@100c191 >13:53:17,082 INFO [org.apache.helix.participant.HelixStateMachineEngine] (MSC service thread 1-4) Send NO_OP message to nodeTwo_12346, msgId: 03fc0651-03f2-4d1b-82e7-1e95eb119ce6 >13:53:17,083 INFO [org.apache.helix.messaging.DefaultMessagingService] (MSC service thread 1-4) registering msg factory for type USER_DEFINE_MSG >13:53:17,101 WARN [org.apache.helix.manager.zk.ZkAsyncCallbacks] (MSC service thread 1-4-EventThread) org.apache.helix.manager.zk.ZkAsyncCallbacks$CreateCallbackHandler@b25c54, rc:NONODE, path: /bpms-cluster/INSTANCES/nodeTwo_12346/CURRENTSTATES/144b76b6dce0007/vfs-repo >13:53:17,112 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-4) Added msg-factory for type: USER_DEFINE_MSG, threadpool size 40 >13:53:17,185 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 02ce0464-b148-4ef4-b5e6-07449649f4fd >13:53:17,186 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 02ce0464-b148-4ef4-b5e6-07449649f4fd handling task scheduled >13:53:17,187 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 151ms >13:53:17,187 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-1) handling task: 02ce0464-b148-4ef4-b5e6-07449649f4fd begin, at: 1394646797187 >13:53:17,188 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:53:17,188 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:53:17,194 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Dropping NO-OP message. mid: 03fc0651-03f2-4d1b-82e7-1e95eb119ce6, from: nodeTwo_12346 >13:53:17,202 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Dropping NO-OP message. mid: 60cc1381-6410-4f69-9a5c-84edd9db6a49, from: nodeTwo_12346 >13:53:17,203 INFO [org.jbpm.executor.impl.ExecutorImpl] (MSC service thread 1-4) Starting Executor Component ... > - Thread Pool Size: 1 > - Interval: 3 Seconds > - Retries per Request: 3 > >13:53:17,208 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-1) Message 02ce0464-b148-4ef4-b5e6-07449649f4fd completed. >13:53:17,213 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 25ms >13:53:17,222 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:53:17,222 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:53:17,227 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:53:17,228 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 6ms >13:53:17,249 INFO [org.apache.helix.monitoring.ParticipantMonitor] (pool-15-thread-1) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=OFFLINE--STANDBY >13:53:17,252 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-1) message finished: 02ce0464-b148-4ef4-b5e6-07449649f4fd, took 64 >13:53:17,253 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-1) msg: 02ce0464-b148-4ef4-b5e6-07449649f4fd handling task completed, results:true, at: 1394646797253, took:66 >13:53:17,257 INFO [org.kie.services.remote.jms.RetryTrackerSingleton] (ServerService Thread Pool -- 69) JMS message retry limit set to 2 >13:53:17,276 INFO [org.jboss.web] (ServerService Thread Pool -- 69) JBAS018210: Register web context: /business-central >13:53:17,454 INFO [solder-servlet] (ServerService Thread Pool -- 69) Catch Integration for Servlets enabled >13:53:17,602 INFO [org.jboss.errai.bus.server.cluster.noop.NoopClusteringProvider] (ServerService Thread Pool -- 69) clustering support not configured. >13:53:17,608 INFO [org.jboss.errai.bus.server.service.bootstrap.OrderedBootstrap] (ServerService Thread Pool -- 69) starting errai bus ... >13:53:17,610 INFO [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 69) authentication adapter configured: org.jboss.errai.bus.server.security.auth.JAASAdapter >13:53:17,615 INFO [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 69) using dispatcher implementation: org.jboss.errai.bus.server.SimpleDispatcher >13:53:17,618 INFO [org.jboss.errai.bus.server.service.bootstrap.DefaultComponents] (ServerService Thread Pool -- 69) using session provider implementation: org.jboss.errai.bus.server.HttpSessionProvider >13:53:17,631 INFO [org.jboss.errai.bus.server.service.bootstrap.LoadExtensions] (ServerService Thread Pool -- 69) searching for errai extensions ... >13:53:17,641 INFO [org.jboss.errai.bus.server.service.bootstrap.OrderedBootstrap] (ServerService Thread Pool -- 69) errai bus started. >13:53:17,918 INFO [org.jboss.as.server] (host-controller-connection-threads - 1) JBAS018559: Deployed "business-central.war" (runtime-name : "business-central.war") >13:54:45,459 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:45,459 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:45,470 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: f8220e7b-59bd-47ae-bbf2-2719d6a08d8c >13:54:45,471 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-1) handling task: f8220e7b-59bd-47ae-bbf2-2719d6a08d8c begin, at: 1394646885471 >13:54:45,471 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message starts with timeout 30000 MsgId: f8220e7b-59bd-47ae-bbf2-2719d6a08d8c >13:54:45,473 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: f8220e7b-59bd-47ae-bbf2-2719d6a08d8c handling task scheduled >13:54:45,473 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 14ms >13:54:49,777 ERROR [org.uberfire.io.impl.cluster.helix.ClusterServiceHelix] (pool-16-thread-1) Error while processing cluster message: java.lang.NullPointerException > at org.uberfire.io.impl.cluster.IOServiceClusterImpl$NewFileSystemMessageHandler.handleMessage(IOServiceClusterImpl.java:894) [uberfire-io-0.3.2-redhat-4.jar:0.3.2-redhat-4] > at org.uberfire.io.impl.cluster.helix.ClusterServiceHelix$MessageHandlerResolverWrapper$1$1.handleMessage(ClusterServiceHelix.java:294) [uberfire-io-0.3.2-redhat-4.jar:0.3.2-redhat-4] > at org.apache.helix.messaging.handling.HelixTask.call(HelixTask.java:93) [helix-core-0.6.2-incubating.jar:0.6.2-incubating] > at org.apache.helix.messaging.handling.HelixTask.call(HelixTask.java:50) [helix-core-0.6.2-incubating.jar:0.6.2-incubating] > at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_45] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_45] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45] > >13:54:49,781 ERROR [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-1) Message execution failed. msgId: f8220e7b-59bd-47ae-bbf2-2719d6a08d8c, errorMsg: null >13:54:49,913 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-1) Sending reply for message 4c8072e2-8f66-4b93-92c2-11942942bd21 >13:54:49,914 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:49,914 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:49,943 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-16-thread-1) message finished: f8220e7b-59bd-47ae-bbf2-2719d6a08d8c, took 4471 >13:54:49,943 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-1) msg: f8220e7b-59bd-47ae-bbf2-2719d6a08d8c handling task completed, results:false, at: 1394646889943, took:4472 >13:54:49,943 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:49,943 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 29ms >13:54:50,129 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:50,129 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:50,140 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 97a35c87-0b03-44d6-9c1f-9cc3c7c463ec >13:54:50,141 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 97a35c87-0b03-44d6-9c1f-9cc3c7c463ec handling task scheduled >13:54:50,141 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms >13:54:50,141 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-2) handling task: 97a35c87-0b03-44d6-9c1f-9cc3c7c463ec begin, at: 1394646890141 >13:54:50,201 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-2) Message 97a35c87-0b03-44d6-9c1f-9cc3c7c463ec completed. >13:54:50,212 INFO [org.apache.helix.monitoring.ParticipantMonitor] (pool-15-thread-2) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--LEADER >13:54:50,213 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-2) message finished: 97a35c87-0b03-44d6-9c1f-9cc3c7c463ec, took 71 >13:54:50,213 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-2) msg: 97a35c87-0b03-44d6-9c1f-9cc3c7c463ec handling task completed, results:true, at: 1394646890213, took:72 >13:54:50,236 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:50,236 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:50,238 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:50,238 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms >13:54:50,267 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:50,274 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:50,305 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: bc1cef51-11f6-4191-878d-7f653f15aab4 >13:54:50,305 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: bc1cef51-11f6-4191-878d-7f653f15aab4 handling task scheduled >13:54:50,305 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 38ms >13:54:50,305 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-3) handling task: bc1cef51-11f6-4191-878d-7f653f15aab4 begin, at: 1394646890305 >13:54:50,332 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-3) Message bc1cef51-11f6-4191-878d-7f653f15aab4 completed. >13:54:50,350 INFO [org.apache.helix.monitoring.ParticipantMonitor] (pool-15-thread-3) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=LEADER--STANDBY >13:54:50,350 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-3) message finished: bc1cef51-11f6-4191-878d-7f653f15aab4, took 44 >13:54:50,350 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-3) msg: bc1cef51-11f6-4191-878d-7f653f15aab4 handling task completed, results:true, at: 1394646890350, took:45 >13:54:50,374 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:50,382 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:50,384 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:50,384 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 10ms >13:54:50,585 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:50,585 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:50,649 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 841fd61e-e47b-4824-a187-8614e5fc040f >13:54:50,655 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 841fd61e-e47b-4824-a187-8614e5fc040f handling task scheduled >13:54:50,655 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-2) handling task: 841fd61e-e47b-4824-a187-8614e5fc040f begin, at: 1394646890655 >13:54:50,655 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 70ms >13:54:50,693 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:50,693 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:50,752 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 6aa53662-7deb-4e7f-84a1-f3404d49d39e >13:54:50,753 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 6aa53662-7deb-4e7f-84a1-f3404d49d39e handling task scheduled >13:54:50,753 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 60ms >13:54:50,753 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-3) handling task: 6aa53662-7deb-4e7f-84a1-f3404d49d39e begin, at: 1394646890753 >13:54:50,901 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-2) Message 841fd61e-e47b-4824-a187-8614e5fc040f completed. >13:54:50,967 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-16-thread-2) message finished: 841fd61e-e47b-4824-a187-8614e5fc040f, took 311 >13:54:50,968 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:50,976 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-2) msg: 841fd61e-e47b-4824-a187-8614e5fc040f handling task completed, results:true, at: 1394646890975, took:320 >13:54:50,976 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,020 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-3) Message 6aa53662-7deb-4e7f-84a1-f3404d49d39e completed. >13:54:51,033 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-16-thread-3) message finished: 6aa53662-7deb-4e7f-84a1-f3404d49d39e, took 279 >13:54:51,033 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-3) msg: 6aa53662-7deb-4e7f-84a1-f3404d49d39e handling task completed, results:true, at: 1394646891033, took:280 >13:54:51,069 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: a00241fc-3e20-4b00-9f85-67074b4a14b1 >13:54:51,073 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: a00241fc-3e20-4b00-9f85-67074b4a14b1 handling task scheduled >13:54:51,073 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 105ms >13:54:51,074 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-4) handling task: a00241fc-3e20-4b00-9f85-67074b4a14b1 begin, at: 1394646891074 >13:54:51,075 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,076 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,110 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-4) Message a00241fc-3e20-4b00-9f85-67074b4a14b1 completed. >13:54:51,147 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 72ms >13:54:51,171 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-4) message finished: a00241fc-3e20-4b00-9f85-67074b4a14b1, took 96 >13:54:51,172 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-4) msg: a00241fc-3e20-4b00-9f85-67074b4a14b1 handling task completed, results:true, at: 1394646891172, took:98 >13:54:51,172 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,172 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,195 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:51,195 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 23ms >13:54:51,219 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,220 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,254 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 99ebedeb-1e54-4b4d-b7a6-03a6ee5df7f8 >13:54:51,255 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 99ebedeb-1e54-4b4d-b7a6-03a6ee5df7f8 handling task scheduled >13:54:51,255 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 36ms >13:54:51,255 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-5) handling task: 99ebedeb-1e54-4b4d-b7a6-03a6ee5df7f8 begin, at: 1394646891255 >13:54:51,267 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-5) Message 99ebedeb-1e54-4b4d-b7a6-03a6ee5df7f8 completed. >13:54:51,291 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-5) message finished: 99ebedeb-1e54-4b4d-b7a6-03a6ee5df7f8, took 35 >13:54:51,291 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-5) msg: 99ebedeb-1e54-4b4d-b7a6-03a6ee5df7f8 handling task completed, results:true, at: 1394646891291, took:36 >13:54:51,291 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,292 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,309 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:51,314 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 23ms >13:54:51,370 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,370 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,392 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: b01d49d4-3d69-406a-aa10-07e34c79468d >13:54:51,392 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: b01d49d4-3d69-406a-aa10-07e34c79468d handling task scheduled >13:54:51,393 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 23ms >13:54:51,392 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-6) handling task: b01d49d4-3d69-406a-aa10-07e34c79468d begin, at: 1394646891392 >13:54:51,404 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-6) Message b01d49d4-3d69-406a-aa10-07e34c79468d completed. >13:54:51,416 INFO [org.apache.helix.monitoring.ParticipantMonitor] (pool-15-thread-6) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--OFFLINE >13:54:51,416 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-6) message finished: b01d49d4-3d69-406a-aa10-07e34c79468d, took 23 >13:54:51,416 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-6) msg: b01d49d4-3d69-406a-aa10-07e34c79468d handling task completed, results:true, at: 1394646891416, took:24 >13:54:51,417 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,417 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,418 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:51,418 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 1ms >13:54:51,435 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,435 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,458 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: ea9bef15-b698-4b38-89de-f7e1d0d608fa >13:54:51,465 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: ea9bef15-b698-4b38-89de-f7e1d0d608fa handling task scheduled >13:54:51,465 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-7) handling task: ea9bef15-b698-4b38-89de-f7e1d0d608fa begin, at: 1394646891465 >13:54:51,465 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 30ms >13:54:51,495 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-7) Message ea9bef15-b698-4b38-89de-f7e1d0d608fa completed. >13:54:51,507 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-7) message finished: ea9bef15-b698-4b38-89de-f7e1d0d608fa, took 41 >13:54:51,507 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-7) msg: ea9bef15-b698-4b38-89de-f7e1d0d608fa handling task completed, results:true, at: 1394646891507, took:42 >13:54:51,507 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,507 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,509 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:51,509 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms >13:54:51,524 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,524 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,536 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 34b873cc-cba6-4f32-940e-c6643daeda78 >13:54:51,536 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 34b873cc-cba6-4f32-940e-c6643daeda78 handling task scheduled >13:54:51,536 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms >13:54:51,537 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-8) handling task: 34b873cc-cba6-4f32-940e-c6643daeda78 begin, at: 1394646891537 >13:54:51,554 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-8) Message 34b873cc-cba6-4f32-940e-c6643daeda78 completed. >13:54:51,568 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-8) message finished: 34b873cc-cba6-4f32-940e-c6643daeda78, took 30 >13:54:51,568 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-8) msg: 34b873cc-cba6-4f32-940e-c6643daeda78 handling task completed, results:true, at: 1394646891568, took:31 >13:54:51,578 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,578 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,579 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:51,579 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 1ms >13:54:51,596 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,596 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,633 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: fc757934-d58a-4555-9753-9237a70a0bd0 >13:54:51,634 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: fc757934-d58a-4555-9753-9237a70a0bd0 handling task scheduled >13:54:51,634 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 38ms >13:54:51,634 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-9) handling task: fc757934-d58a-4555-9753-9237a70a0bd0 begin, at: 1394646891634 >13:54:51,644 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-9) Message fc757934-d58a-4555-9753-9237a70a0bd0 completed. >13:54:51,656 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-9) message finished: fc757934-d58a-4555-9753-9237a70a0bd0, took 21 >13:54:51,657 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-9) msg: fc757934-d58a-4555-9753-9237a70a0bd0 handling task completed, results:true, at: 1394646891657, took:23 >13:54:51,658 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,658 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,660 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:51,660 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms >13:54:51,674 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,674 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,685 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: bc096cd3-79c7-4f10-938b-c1e02a543211 >13:54:51,686 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: bc096cd3-79c7-4f10-938b-c1e02a543211 handling task scheduled >13:54:51,686 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms >13:54:51,686 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-10) handling task: bc096cd3-79c7-4f10-938b-c1e02a543211 begin, at: 1394646891686 >13:54:51,698 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-10) Message bc096cd3-79c7-4f10-938b-c1e02a543211 completed. >13:54:51,710 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-10) message finished: bc096cd3-79c7-4f10-938b-c1e02a543211, took 23 >13:54:51,710 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-10) msg: bc096cd3-79c7-4f10-938b-c1e02a543211 handling task completed, results:true, at: 1394646891710, took:24 >13:54:51,710 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:54:51,711 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:54:51,712 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:54:51,712 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms >13:55:11,608 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:11,608 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:11,619 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 059e1255-b440-476a-b6e9-578c7d72c068 >13:55:11,620 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 059e1255-b440-476a-b6e9-578c7d72c068 handling task scheduled >13:55:11,620 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-4) handling task: 059e1255-b440-476a-b6e9-578c7d72c068 begin, at: 1394646911620 >13:55:11,620 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms >13:55:11,680 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:11,680 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:11,742 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 9b739eb0-d82c-4be6-b691-8c36ceb285d1 >13:55:11,742 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 9b739eb0-d82c-4be6-b691-8c36ceb285d1 handling task scheduled >13:55:11,742 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-5) handling task: 9b739eb0-d82c-4be6-b691-8c36ceb285d1 begin, at: 1394646911742 >13:55:11,743 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 63ms >13:55:11,815 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-4) Message 059e1255-b440-476a-b6e9-578c7d72c068 completed. >13:55:11,877 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-16-thread-4) message finished: 059e1255-b440-476a-b6e9-578c7d72c068, took 256 >13:55:11,878 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:11,882 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-4) msg: 059e1255-b440-476a-b6e9-578c7d72c068 handling task completed, results:true, at: 1394646911882, took:262 >13:55:11,882 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:11,891 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 13ms >13:55:11,923 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-5) Message 9b739eb0-d82c-4be6-b691-8c36ceb285d1 completed. >13:55:11,967 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-16-thread-5) message finished: 9b739eb0-d82c-4be6-b691-8c36ceb285d1, took 224 >13:55:11,968 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:11,973 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:11,973 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-5) msg: 9b739eb0-d82c-4be6-b691-8c36ceb285d1 handling task completed, results:true, at: 1394646911973, took:231 >13:55:11,974 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:55:11,974 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 6ms >13:55:12,069 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:12,069 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:12,080 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: b95be6ba-0fd1-4631-9d92-78ded4d3ef18 >13:55:12,081 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: b95be6ba-0fd1-4631-9d92-78ded4d3ef18 handling task scheduled >13:55:12,081 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms >13:55:12,081 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-11) handling task: b95be6ba-0fd1-4631-9d92-78ded4d3ef18 begin, at: 1394646912081 >13:55:12,124 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-11) Message b95be6ba-0fd1-4631-9d92-78ded4d3ef18 completed. >13:55:12,135 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-11) message finished: b95be6ba-0fd1-4631-9d92-78ded4d3ef18, took 53 >13:55:12,135 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-11) msg: b95be6ba-0fd1-4631-9d92-78ded4d3ef18 handling task completed, results:true, at: 1394646912135, took:54 >13:55:12,135 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:12,135 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:12,136 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:55:12,136 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 1ms >13:55:12,153 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:12,153 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:12,158 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 5ece11db-056e-4308-8cd3-6ef0dd70d8bb >13:55:12,159 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 5ece11db-056e-4308-8cd3-6ef0dd70d8bb handling task scheduled >13:55:12,159 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-12) handling task: 5ece11db-056e-4308-8cd3-6ef0dd70d8bb begin, at: 1394646912159 >13:55:12,159 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 6ms >13:55:12,166 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-12) Message 5ece11db-056e-4308-8cd3-6ef0dd70d8bb completed. >13:55:12,171 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-12) message finished: 5ece11db-056e-4308-8cd3-6ef0dd70d8bb, took 10 >13:55:12,171 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-12) msg: 5ece11db-056e-4308-8cd3-6ef0dd70d8bb handling task completed, results:true, at: 1394646912171, took:12 >13:55:12,177 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:12,177 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:12,178 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:55:12,178 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 1ms >13:55:12,246 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:12,246 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:12,254 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 61debaa5-6090-407d-9bf3-a9f6182574b3 >13:55:12,255 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 61debaa5-6090-407d-9bf3-a9f6182574b3 handling task scheduled >13:55:12,255 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-13) handling task: 61debaa5-6090-407d-9bf3-a9f6182574b3 begin, at: 1394646912255 >13:55:12,255 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 9ms >13:55:12,266 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-13) Message 61debaa5-6090-407d-9bf3-a9f6182574b3 completed. >13:55:12,296 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-13) message finished: 61debaa5-6090-407d-9bf3-a9f6182574b3, took 40 >13:55:12,297 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:12,305 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-13) msg: 61debaa5-6090-407d-9bf3-a9f6182574b3 handling task completed, results:true, at: 1394646912305, took:50 >13:55:12,305 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:12,350 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 529433ba-81c1-4f02-a8ce-b55b00e840f1 >13:55:12,350 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 529433ba-81c1-4f02-a8ce-b55b00e840f1 handling task scheduled >13:55:12,350 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-14) handling task: 529433ba-81c1-4f02-a8ce-b55b00e840f1 begin, at: 1394646912350 >13:55:12,351 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 54ms >13:55:12,353 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:12,353 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:12,374 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-14) Message 529433ba-81c1-4f02-a8ce-b55b00e840f1 completed. >13:55:12,376 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 23ms >13:55:12,410 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-14) message finished: 529433ba-81c1-4f02-a8ce-b55b00e840f1, took 59 >13:55:12,411 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:12,419 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-14) msg: 529433ba-81c1-4f02-a8ce-b55b00e840f1 handling task completed, results:true, at: 1394646912419, took:69 >13:55:12,419 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:12,420 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:55:12,420 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 9ms >13:55:34,404 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:34,405 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:34,422 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 04fa859e-2879-42d9-b07a-df3b89e405c4 >13:55:34,422 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 04fa859e-2879-42d9-b07a-df3b89e405c4 handling task scheduled >13:55:34,422 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-6) handling task: 04fa859e-2879-42d9-b07a-df3b89e405c4 begin, at: 1394646934422 >13:55:34,422 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 18ms >13:55:34,518 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:34,518 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:34,547 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: c6d0c4fb-32d5-4e6d-b519-63f4d86b0913 >13:55:34,548 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: c6d0c4fb-32d5-4e6d-b519-63f4d86b0913 handling task scheduled >13:55:34,548 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-7) handling task: c6d0c4fb-32d5-4e6d-b519-63f4d86b0913 begin, at: 1394646934548 >13:55:34,548 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 30ms >13:55:34,615 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-6) Message 04fa859e-2879-42d9-b07a-df3b89e405c4 completed. >13:55:34,667 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-16-thread-6) message finished: 04fa859e-2879-42d9-b07a-df3b89e405c4, took 244 >13:55:34,668 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:34,671 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-6) msg: 04fa859e-2879-42d9-b07a-df3b89e405c4 handling task completed, results:true, at: 1394646934671, took:249 >13:55:34,671 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:34,730 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-7) Message c6d0c4fb-32d5-4e6d-b519-63f4d86b0913 completed. >13:55:34,787 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-16-thread-7) message finished: c6d0c4fb-32d5-4e6d-b519-63f4d86b0913, took 239 >13:55:34,787 WARN [org.apache.helix.manager.zk.ZkAsyncCallbacks] (MSC service thread 1-4-EventThread) org.apache.helix.manager.zk.ZkAsyncCallbacks$GetDataCallbackHandler@1bf4ac1, rc:NONODE, path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES/c6d0c4fb-32d5-4e6d-b519-63f4d86b0913 >13:55:34,793 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:55:34,793 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 125ms >13:55:34,793 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-16-thread-7) msg: c6d0c4fb-32d5-4e6d-b519-63f4d86b0913 handling task completed, results:true, at: 1394646934793, took:245 >13:55:34,824 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:34,824 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:34,877 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: f92488a5-30d3-4f40-a0d1-d500857588e6 >13:55:34,878 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: f92488a5-30d3-4f40-a0d1-d500857588e6 handling task scheduled >13:55:34,878 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-15) handling task: f92488a5-30d3-4f40-a0d1-d500857588e6 begin, at: 1394646934878 >13:55:34,878 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 55ms >13:55:34,913 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-15) Message f92488a5-30d3-4f40-a0d1-d500857588e6 completed. >13:55:34,937 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-15) message finished: f92488a5-30d3-4f40-a0d1-d500857588e6, took 59 >13:55:34,937 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:34,943 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-15) msg: f92488a5-30d3-4f40-a0d1-d500857588e6 handling task completed, results:true, at: 1394646934943, took:65 >13:55:34,943 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:34,967 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: 0ff6d73a-0b16-4a6e-94ce-594d4ef7bfc1 >13:55:34,967 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: 0ff6d73a-0b16-4a6e-94ce-594d4ef7bfc1 handling task scheduled >13:55:34,967 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 30ms >13:55:34,967 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-16) handling task: 0ff6d73a-0b16-4a6e-94ce-594d4ef7bfc1 begin, at: 1394646934967 >13:55:34,968 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:34,968 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:34,991 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-16) Message 0ff6d73a-0b16-4a6e-94ce-594d4ef7bfc1 completed. >13:55:35,003 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-16) message finished: 0ff6d73a-0b16-4a6e-94ce-594d4ef7bfc1, took 35 >13:55:35,003 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 35ms >13:55:35,003 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-16) msg: 0ff6d73a-0b16-4a6e-94ce-594d4ef7bfc1 handling task completed, results:true, at: 1394646935003, took:36 >13:55:35,003 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:35,003 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:35,005 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:55:35,005 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms >13:55:35,057 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:35,057 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:35,068 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: eaa74d52-69c9-4c2a-abf4-0af486d0365f >13:55:35,069 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: eaa74d52-69c9-4c2a-abf4-0af486d0365f handling task scheduled >13:55:35,069 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-17) handling task: eaa74d52-69c9-4c2a-abf4-0af486d0365f begin, at: 1394646935069 >13:55:35,069 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 12ms >13:55:35,075 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-17) Message eaa74d52-69c9-4c2a-abf4-0af486d0365f completed. >13:55:35,099 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-17) message finished: eaa74d52-69c9-4c2a-abf4-0af486d0365f, took 29 >13:55:35,099 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-17) msg: eaa74d52-69c9-4c2a-abf4-0af486d0365f handling task completed, results:true, at: 1394646935099, took:30 >13:55:35,100 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:35,100 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:35,101 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:55:35,101 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 1ms >13:55:35,111 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:35,111 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:35,161 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Scheduling message: c60e2617-e589-43dc-bed6-73ee769a9708 >13:55:35,161 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) Message: c60e2617-e589-43dc-bed6-73ee769a9708 handling task scheduled >13:55:35,161 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 50ms >13:55:35,162 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-18) handling task: c60e2617-e589-43dc-bed6-73ee769a9708 begin, at: 1394646935162 >13:55:35,171 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-18) Message c60e2617-e589-43dc-bed6-73ee769a9708 completed. >13:55:35,182 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (pool-15-thread-18) message finished: c60e2617-e589-43dc-bed6-73ee769a9708, took 20 >13:55:35,183 INFO [org.apache.helix.messaging.handling.HelixTask] (pool-15-thread-18) msg: c60e2617-e589-43dc-bed6-73ee769a9708 handling task completed, results:true, at: 1394646935183, took:21 >13:55:35,183 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >13:55:35,183 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) nodeTwo_12346 subscribes child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >13:55:35,184 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (ZkClient-EventThread-197-localhost:2181) No Messages to process >13:55:35,185 INFO [org.apache.helix.manager.zk.CallbackHandler] (ZkClient-EventThread-197-localhost:2181) 197 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms >13:55:36,545 ERROR [stderr] (pool-13-thread-1) line 1:57 no viable alternative at character '\' >13:55:36,958 INFO [org.drools.compiler.kie.builder.impl.KieRepositoryImpl] (pool-13-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] >13:55:37,480 ERROR [stderr] (pool-13-thread-1) line 1:57 no viable alternative at character '\' >13:55:37,851 INFO [org.quartz.core.SchedulerSignalerImpl] (pool-13-thread-1) Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl >13:55:37,852 INFO [org.quartz.core.QuartzScheduler] (pool-13-thread-1) Quartz Scheduler v.1.8.5 created. >13:55:37,853 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (pool-13-thread-1) Detected usage of MSSQLDelegate class - defaulting 'selectWithLockSQL' to 'SELECT * FROM {0}LOCKS UPDLOCK WHERE LOCK_NAME = ?'. >13:55:37,853 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (pool-13-thread-1) Using db table-based data access locking (synchronization). >13:55:37,855 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (pool-13-thread-1) JobStoreCMT initialized. >13:55:37,856 INFO [org.quartz.core.QuartzScheduler] (pool-13-thread-1) Scheduler meta-data: Quartz Scheduler (v1.8.5) 'jBPMClusteredScheduler' with instanceId 'dev114.mw.lab.eng.bos.redhat.com1394646937835' > 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. > >13:55:37,856 INFO [org.quartz.impl.StdSchedulerFactory] (pool-13-thread-1) Quartz scheduler 'jBPMClusteredScheduler' initialized from specified file: '/mnt/hudson_workspace/workspace/bpms-clustering-matrix/e2a8916d/quartz.properties' >13:55:37,856 INFO [org.quartz.impl.StdSchedulerFactory] (pool-13-thread-1) Quartz scheduler version: 1.8.5 >13:55:37,900 INFO [org.quartz.core.QuartzScheduler] (pool-13-thread-1) Scheduler jBPMClusteredScheduler_$_dev114.mw.lab.eng.bos.redhat.com1394646937835 started. >13:56:15,957 WARN [org.hornetq.core.client] (Thread-4 (HornetQ-client-global-threads-17936203)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED] >13:56:15,957 WARN [org.hornetq.core.client] (Thread-6 (HornetQ-client-global-threads-17936203)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED] >13:56:15,958 WARN [org.hornetq.core.client] (Thread-1 (HornetQ-client-global-threads-17936203)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED] >13:56:15,962 INFO [org.hornetq.core.server] (Thread-15 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221029: stopped bridge sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410 >13:56:30,570 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) java.lang.NullPointerException >13:56:30,570 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:30,570 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:30,571 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:30,571 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:30,571 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:31,572 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-1) Job jbpm.1_2_START threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:31,573 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) java.lang.NullPointerException >13:56:31,573 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:31,573 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:31,574 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:31,574 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:31,574 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:33,575 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-1) Job jbpm.1_2_START threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:33,575 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) java.lang.NullPointerException >13:56:33,575 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:33,575 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:33,576 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:33,576 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:33,576 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:33,734 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) java.lang.NullPointerException >13:56:33,734 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:33,734 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:33,734 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:33,734 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:33,734 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:34,736 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-2) Job jbpm.1_1_END threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:34,736 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) java.lang.NullPointerException >13:56:34,736 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:34,736 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:34,737 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:34,737 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:34,737 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:36,577 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-1) Job jbpm.1_2_START threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:36,577 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) java.lang.NullPointerException >13:56:36,577 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:36,578 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:36,578 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:36,578 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:36,578 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:36,738 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-2) Job jbpm.1_1_END threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:36,738 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) java.lang.NullPointerException >13:56:36,738 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:36,739 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:36,739 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:36,739 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:36,739 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:39,741 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-2) Job jbpm.1_1_END threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:39,741 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) java.lang.NullPointerException >13:56:39,742 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:39,742 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:39,742 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:39,742 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:39,742 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:40,580 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-1) Job jbpm.1_2_START threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:40,581 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) java.lang.NullPointerException >13:56:40,581 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:40,581 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:40,581 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:40,581 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:40,581 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:43,743 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-2) Job jbpm.1_1_END threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:43,744 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) java.lang.NullPointerException >13:56:43,744 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:43,744 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:43,744 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:43,744 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:43,744 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:45,582 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-1) Job jbpm.1_2_START threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:45,583 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) java.lang.NullPointerException >13:56:45,583 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:45,583 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:45,583 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:45,583 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:45,583 ERROR [stderr] (jBPMClusteredScheduler_Worker-1) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:45,584 ERROR [org.jbpm.process.core.timer.impl.QuartzSchedulerService] (jBPMClusteredScheduler_Worker-1) Timer execution failed 5 times in a roll, unscheduling (jbpm.1_2_START) >13:56:48,746 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-2) Job jbpm.1_1_END threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:48,756 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) java.lang.NullPointerException >13:56:48,756 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) >13:56:48,756 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) >13:56:48,756 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) >13:56:48,756 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.core.JobRunShell.run(JobRunShell.java:216) >13:56:48,756 ERROR [stderr] (jBPMClusteredScheduler_Worker-2) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) >13:56:48,756 ERROR [org.jbpm.process.core.timer.impl.QuartzSchedulerService] (jBPMClusteredScheduler_Worker-2) Timer execution failed 5 times in a roll, unscheduling (jbpm.1_1_END) >13:56:51,586 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-1) Job jbpm.1_2_START threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:54,758 INFO [org.quartz.core.JobRunShell] (jBPMClusteredScheduler_Worker-2) Job jbpm.1_1_END threw a JobExecutionException: : org.quartz.JobExecutionException: Exception when executing scheduled job [See nested exception: java.lang.NullPointerException] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:292) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.quartz.core.JobRunShell.run(JobRunShell.java:216) [quartz-1.8.5.jar:] > at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:549) [quartz-1.8.5.jar:] >Caused by: java.lang.NullPointerException > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:62) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.persistence.timer.GlobalJpaTimerJobInstance.call(GlobalJpaTimerJobInstance.java:42) [jbpm-persistence-jpa-6.0.2-redhat-4.jar:6.0.2-redhat-4] > at org.jbpm.process.core.timer.impl.QuartzSchedulerService$QuartzJob.execute(QuartzSchedulerService.java:273) [jbpm-flow-6.0.2-redhat-4.jar:6.0.2-redhat-4] > ... 2 more > >13:56:57,915 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (QuartzScheduler_jBPMClusteredScheduler-dev114.mw.lab.eng.bos.redhat.com1394646937835_ClusterManager) ClusterManager: detected 1 failed or restarted instances. >13:56:57,915 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (QuartzScheduler_jBPMClusteredScheduler-dev114.mw.lab.eng.bos.redhat.com1394646937835_ClusterManager) ClusterManager: Scanning for instance "dev114.mw.lab.eng.bos.redhat.com1394646934024"'s failed in-progress jobs. >13:57:18,128 INFO [org.jboss.resteasy.cdi.CdiInjectorFactory] (http-/127.0.0.1:8280-1) Found BeanManager at java:comp/BeanManager >13:57:22,557 WARN [org.hornetq.core.client] (hornetq-discovery-group-thread-dg-group1) HQ212034: There are more than one servers on the network broadcasting the same node id. You will see this message exactly once (per node) if a node is restarted, in which case it can be safely ignored. But if it is logged continuously it means you really do have more than one node on the same network active concurrently with the same node id. This could occur if you have a backup node active at the same time as its live node. nodeID=f1591cea-aa0e-11e3-af85-bf8028971410 >13:57:24,059 INFO [org.hornetq.core.server] (Thread-28 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221027: Bridge ClusterConnectionBridge@1ed549b [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@1ed549b [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@15348729[nodeUUID=f295bbfd-aa0e-11e3-909c-299a077080d4, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost-localdomain, address=jms, server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]] is connected >13:57:48,928 WARN [org.hornetq.core.client] (Thread-1 (HornetQ-client-global-threads-17936203)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED] >13:57:48,929 WARN [org.hornetq.core.server] (Thread-1 (HornetQ-client-global-threads-17936203)) HQ222095: Connection failed with failedOver=false: HornetQException[errorType=DISCONNECTED message=HQ119015: The connection was disconnected because of server shutdown] > at org.hornetq.core.client.impl.ClientSessionFactoryImpl$CloseRunnable.run(ClientSessionFactoryImpl.java:1631) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:106) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_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] > >13:57:48,932 INFO [org.hornetq.core.server] (Thread-24 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221029: stopped bridge sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410 >13:57:55,280 WARN [org.hornetq.core.client] (hornetq-discovery-group-thread-dg-group1) HQ212034: There are more than one servers on the network broadcasting the same node id. You will see this message exactly once (per node) if a node is restarted, in which case it can be safely ignored. But if it is logged continuously it means you really do have more than one node on the same network active concurrently with the same node id. This could occur if you have a backup node active at the same time as its live node. nodeID=f1591cea-aa0e-11e3-af85-bf8028971410 >13:57:59,118 INFO [org.hornetq.core.server] (Thread-28 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221027: Bridge ClusterConnectionBridge@561aaf [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@561aaf [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@15348729[nodeUUID=f295bbfd-aa0e-11e3-909c-299a077080d4, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost-localdomain, address=jms, server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]] is connected >13:58:17,929 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (QuartzScheduler_jBPMClusteredScheduler-dev114.mw.lab.eng.bos.redhat.com1394646937835_ClusterManager) ClusterManager: detected 1 failed or restarted instances. >13:58:17,929 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (QuartzScheduler_jBPMClusteredScheduler-dev114.mw.lab.eng.bos.redhat.com1394646937835_ClusterManager) ClusterManager: Scanning for instance "dev114.mw.lab.eng.bos.redhat.com1394647066240"'s failed in-progress jobs. >13:58:22,324 WARN [org.hornetq.core.client] (Thread-6 (HornetQ-client-global-threads-17936203)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED] >13:58:22,324 WARN [org.hornetq.core.server] (Thread-6 (HornetQ-client-global-threads-17936203)) HQ222095: Connection failed with failedOver=false: HornetQException[errorType=DISCONNECTED message=HQ119015: The connection was disconnected because of server shutdown] > at org.hornetq.core.client.impl.ClientSessionFactoryImpl$CloseRunnable.run(ClientSessionFactoryImpl.java:1631) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:106) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_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] > >13:58:22,327 INFO [org.hornetq.core.server] (Thread-3 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221029: stopped bridge sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410 >13:58:28,561 WARN [org.hornetq.core.client] (hornetq-discovery-group-thread-dg-group1) HQ212034: There are more than one servers on the network broadcasting the same node id. You will see this message exactly once (per node) if a node is restarted, in which case it can be safely ignored. But if it is logged continuously it means you really do have more than one node on the same network active concurrently with the same node id. This could occur if you have a backup node active at the same time as its live node. nodeID=f1591cea-aa0e-11e3-af85-bf8028971410 >13:58:29,023 INFO [org.hornetq.core.server] (Thread-29 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221027: Bridge ClusterConnectionBridge@6d37ef [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@6d37ef [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@15348729[nodeUUID=f295bbfd-aa0e-11e3-909c-299a077080d4, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost-localdomain, address=jms, server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]] is connected >13:58:56,883 WARN [org.hornetq.core.client] (Thread-4 (HornetQ-client-global-threads-17936203)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED] >13:58:56,884 WARN [org.hornetq.core.server] (Thread-4 (HornetQ-client-global-threads-17936203)) HQ222095: Connection failed with failedOver=false: HornetQException[errorType=DISCONNECTED message=HQ119015: The connection was disconnected because of server shutdown] > at org.hornetq.core.client.impl.ClientSessionFactoryImpl$CloseRunnable.run(ClientSessionFactoryImpl.java:1631) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:106) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_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] > >13:58:56,886 INFO [org.hornetq.core.server] (Thread-3 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221029: stopped bridge sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410 >13:59:03,387 WARN [org.hornetq.core.client] (hornetq-discovery-group-thread-dg-group1) HQ212034: There are more than one servers on the network broadcasting the same node id. You will see this message exactly once (per node) if a node is restarted, in which case it can be safely ignored. But if it is logged continuously it means you really do have more than one node on the same network active concurrently with the same node id. This could occur if you have a backup node active at the same time as its live node. nodeID=f1591cea-aa0e-11e3-af85-bf8028971410 >13:59:03,989 INFO [org.hornetq.core.server] (Thread-12 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221027: Bridge ClusterConnectionBridge@f44ab6 [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@f44ab6 [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@15348729[nodeUUID=f295bbfd-aa0e-11e3-909c-299a077080d4, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost-localdomain, address=jms, server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]] is connected >13:59:30,125 WARN [org.hornetq.core.client] (Thread-6 (HornetQ-client-global-threads-17936203)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED] >13:59:30,127 INFO [org.hornetq.core.server] (Thread-14 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221029: stopped bridge sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410 >13:59:36,804 WARN [org.hornetq.core.client] (hornetq-discovery-group-thread-dg-group1) HQ212034: There are more than one servers on the network broadcasting the same node id. You will see this message exactly once (per node) if a node is restarted, in which case it can be safely ignored. But if it is logged continuously it means you really do have more than one node on the same network active concurrently with the same node id. This could occur if you have a backup node active at the same time as its live node. nodeID=f1591cea-aa0e-11e3-af85-bf8028971410 >13:59:39,165 INFO [org.hornetq.core.server] (Thread-2 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221027: Bridge ClusterConnectionBridge@6d824e [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@6d824e [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@15348729[nodeUUID=f295bbfd-aa0e-11e3-909c-299a077080d4, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost-localdomain, address=jms, server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]] is connected >13:59:57,947 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (QuartzScheduler_jBPMClusteredScheduler-dev114.mw.lab.eng.bos.redhat.com1394646937835_ClusterManager) ClusterManager: detected 1 failed or restarted instances. >13:59:57,948 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (QuartzScheduler_jBPMClusteredScheduler-dev114.mw.lab.eng.bos.redhat.com1394646937835_ClusterManager) ClusterManager: Scanning for instance "dev114.mw.lab.eng.bos.redhat.com1394647166811"'s failed in-progress jobs. >14:00:04,273 WARN [org.hornetq.core.client] (hornetq-failure-check-thread) HQ212037: Connection failure has been detected: HQ119014: Did not receive data from /127.0.0.1:44411. It is likely the client has exited or crashed without closing its connection, or the network between the server and client has failed. You also might have configured connection-ttl and client-failure-check-period incorrectly. Please check user manual for more information. The connection will now be closed. [code=CONNECTION_TIMEDOUT] >14:00:04,490 WARN [org.hornetq.core.client] (Thread-1 (HornetQ-client-global-threads-17936203)) HQ212037: Connection failure has been detected: HQ119015: The connection was disconnected because of server shutdown [code=DISCONNECTED] >14:00:04,491 WARN [org.hornetq.core.server] (Thread-1 (HornetQ-client-global-threads-17936203)) HQ222095: Connection failed with failedOver=false: HornetQException[errorType=DISCONNECTED message=HQ119015: The connection was disconnected because of server shutdown] > at org.hornetq.core.client.impl.ClientSessionFactoryImpl$CloseRunnable.run(ClientSessionFactoryImpl.java:1631) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:106) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_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] > >14:00:04,496 INFO [org.hornetq.core.server] (Thread-25 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221029: stopped bridge sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410 >14:00:37,951 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (QuartzScheduler_jBPMClusteredScheduler-dev114.mw.lab.eng.bos.redhat.com1394646937835_ClusterManager) ClusterManager: detected 1 failed or restarted instances. >14:00:37,951 INFO [org.quartz.impl.jdbcjobstore.JobStoreCMT] (QuartzScheduler_jBPMClusteredScheduler-dev114.mw.lab.eng.bos.redhat.com1394646937835_ClusterManager) ClusterManager: Scanning for instance "dev114.mw.lab.eng.bos.redhat.com1394647200810"'s failed in-progress jobs. >14:00:40,066 WARN [org.hornetq.core.client] (hornetq-discovery-group-thread-dg-group1) HQ212034: There are more than one servers on the network broadcasting the same node id. You will see this message exactly once (per node) if a node is restarted, in which case it can be safely ignored. But if it is logged continuously it means you really do have more than one node on the same network active concurrently with the same node id. This could occur if you have a backup node active at the same time as its live node. nodeID=f1591cea-aa0e-11e3-af85-bf8028971410 >14:00:44,518 INFO [org.hornetq.core.server] (Thread-18 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221027: Bridge ClusterConnectionBridge@1c64452 [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl (identity=(Cluster-connection-bridge::ClusterConnectionBridge@1c64452 [name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, queue=QueueImpl[name=sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410, postOffice=PostOfficeImpl [server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4]]@7d4540 targetConnector=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]]::ClusterConnectionImpl@15348729[nodeUUID=f295bbfd-aa0e-11e3-909c-299a077080d4, connector=TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5645&host=localhost-localdomain, address=jms, server=HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4])) [initialConnectors=[TransportConfiguration(name=netty, factory=org-hornetq-core-remoting-impl-netty-NettyConnectorFactory) ?port=5445&host=localhost-localdomain], discoveryGroupConfiguration=null]] is connected >14:01:06,814 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-16) JBAS010409: Unbound data source [java:jboss/datasources/ExampleDS] >14:01:06,815 INFO [org.apache.coyote.http11] (MSC service thread 1-16) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost.localdomain/127.0.0.1:8280 >14:01:06,815 INFO [org.apache.coyote.http11] (MSC service thread 1-16) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost.localdomain/127.0.0.1:8280 >14:01:06,825 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-14) JBAS010409: Unbound data source [java:jboss/datasources/brms-jdbc-dsQuartz] >14:01:06,826 INFO [org.jboss.jaxr] (MSC service thread 1-10) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory >14:01:06,826 INFO [org.apache.catalina.core] (MSC service thread 1-15) JBWEB001079: Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started >14:01:06,827 INFO [org.jboss.modcluster] (ServerService Thread Pool -- 94) MODCLUSTER000002: Initiating mod_cluster shutdown >14:01:06,834 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 92) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/queue/KIE.SESSION >14:01:06,834 INFO [org.jboss.web] (ServerService Thread Pool -- 99) JBAS018224: Unregister web context: /business-central >14:01:06,836 INFO [org.apache.coyote.ajp] (MSC service thread 1-14) JBWEB003048: Pausing Coyote AJP/1.3 on ajp-localhost.localdomain/127.0.0.1:8209 >14:01:06,837 INFO [org.apache.coyote.ajp] (MSC service thread 1-14) JBWEB003051: Stopping Coyote AJP/1.3 on ajp-localhost.localdomain/127.0.0.1:8209 >14:01:06,959 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 96) JBAS011403: Stopping Persistence Unit Service 'business-central.war#org.jbpm.domain' >14:01:06,960 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-8) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA] >14:01:06,961 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-8) JBAS010409: Unbound data source [java:jboss/datasources/brms-jdbc-ds] >14:01:06,961 INFO [org.jboss.weld.deployer] (MSC service thread 1-2) JBAS016009: Stopping weld service for deployment business-central.war >14:01:06,967 INFO [org.hornetq.ra] (MSC service thread 1-3) HQ151003: HornetQ resource adaptor stopped >14:01:06,973 ERROR [org.hornetq.core.server] (Thread-11 (HornetQ-client-global-threads-17936203)) HQ224037: cluster connection Failed to handle message: java.lang.IllegalStateException: Cannot find binding for jms.queue.KIE.SESSIONf1591cea-aa0e-11e3-af85-bf8028971410 > at org.hornetq.core.server.cluster.impl.ClusterConnectionImpl$MessageFlowRecordImpl.doConsumerClosed(ClusterConnectionImpl.java:1567) [hornetq-server-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.core.server.cluster.impl.ClusterConnectionImpl$MessageFlowRecordImpl.onMessage(ClusterConnectionImpl.java:1285) [hornetq-server-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:1114) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.core.client.impl.ClientConsumerImpl.access$500(ClientConsumerImpl.java:57) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:1249) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:106) [hornetq-core-client-2.3.5.Final-redhat-2.jar:2.3.5.Final-redhat-2] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_45] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45] > >14:01:07,000 INFO [org.hornetq.core.server] (Thread-17 (HornetQ-server-HornetQServerImpl::serverUUID=f295bbfd-aa0e-11e3-909c-299a077080d4-30353690)) HQ221029: stopped bridge sf.my-cluster.f1591cea-aa0e-11e3-af85-bf8028971410 >14:01:07,018 INFO [org.jboss.errai.cdi.server.events.ShutdownEventObserver] (MSC service thread 1-2) Shutting down CDI-to-ErraiBus event bridge >14:01:07,023 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-2) disconnect nodeTwo_12346(PARTICIPANT) from bpms-cluster >14:01:07,023 INFO [org.apache.helix.healthcheck.ParticipantHealthReportTask] (MSC service thread 1-2) Stop ParticipantHealthReportTimerTask >14:01:07,023 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) shutting down TaskExecutor >14:01:07,024 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) 0 tasks are still in the threadpool for msgType USER_DEFINE_MSG >14:01:07,024 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) 0 tasks are still in the threadpool for msgType STATE_TRANSITION >14:01:07,024 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) 0 tasks are still in the threadpool for msgType TASK_REPLY >14:01:07,034 INFO [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-2) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--OFFLINE >14:01:07,034 INFO [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-2) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=OFFLINE--STANDBY >14:01:07,034 INFO [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-2) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=LEADER--STANDBY >14:01:07,035 INFO [org.apache.helix.monitoring.ParticipantMonitor] (MSC service thread 1-2) Registering bean: Cluster=bpms-cluster,Resource=vfs-repo,Transition=STANDBY--LEADER >14:01:07,035 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) shutdown finished >14:01:07,035 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-2) 12 START:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor >14:01:07,035 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-2) nodeTwo_12346 unsubscribe child-change. path: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >14:01:07,036 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) Get FINALIZE notification >14:01:07,037 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) 0 tasks never executed for msgType: USER_DEFINE_MSG. tasks: [] >14:01:07,037 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) 0 tasks never executed for msgType: STATE_TRANSITION. tasks: [] >14:01:07,037 WARN [org.apache.helix.participant.statemachine.StateModel] (MSC service thread 1-2) Default reset method invoked. Either because the process longer own this resource or session timedout >14:01:07,037 INFO [org.apache.helix.messaging.handling.HelixTaskExecutor] (MSC service thread 1-2) 0 tasks never executed for msgType: TASK_REPLY. tasks: [] >14:01:07,037 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-2) 12 END:INVOKE /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 2ms >14:01:07,038 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-2) reset handler: /bpms-cluster/INSTANCES/nodeTwo_12346/MESSAGES, org.apache.helix.messaging.handling.HelixTaskExecutor@125fd22 >14:01:07,038 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-2) 12 START:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor >14:01:07,038 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-2) nodeTwo_12346 unsubscribe child-change. path: /bpms-cluster/CONTROLLER, listener: org.apache.helix.manager.zk.ZKHelixDataAccessor@69ad0b >14:01:07,038 INFO [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-2) Controller has changed >14:01:07,039 INFO [org.apache.helix.manager.zk.ZKHelixDataAccessor] (MSC service thread 1-2) _zkPropertyTransferSvcUrl : null Controller dev114.mw.lab.eng.bos.redhat.com-CONTROLLER >14:01:07,040 INFO [org.apache.helix.manager.zk.CallbackHandler] (MSC service thread 1-2) 12 END:INVOKE /bpms-cluster/CONTROLLER listener:org.apache.helix.manager.zk.ZKHelixDataAccessor Took: 2ms >14:01:07,040 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-2) reset handler: /bpms-cluster/CONTROLLER, org.apache.helix.manager.zk.ZKHelixDataAccessor@69ad0b >14:01:07,040 INFO [org.apache.helix.manager.zk.ZkClient] (MSC service thread 1-2) Closing zkclient: State:CONNECTED Timeout:30000 sessionid:0x144b76b6dce0007 local:/127.0.0.1:46070 remoteserver:localhost/127.0.0.1:2181 lastZxid:448 xid:602 sent:641 recv:692 queuedpkts:0 pendingresp:0 queuedevents:0 >14:01:07,040 INFO [org.I0Itec.zkclient.ZkEventThread] (ZkClient-EventThread-197-localhost:2181) Terminate ZkClient event thread. >14:01:07,062 INFO [org.apache.zookeeper.ZooKeeper] (MSC service thread 1-2) Session: 0x144b76b6dce0007 closed >14:01:07,062 INFO [org.apache.helix.manager.zk.ZkClient] (MSC service thread 1-2) Closed zkclient >14:01:07,062 INFO [org.apache.helix.manager.zk.ZKHelixManager] (MSC service thread 1-2) Cluster manager: nodeTwo_12346 disconnected >14:01:07,064 INFO [org.apache.zookeeper.ClientCnxn] (MSC service thread 1-4-EventThread) EventThread shut down >14:01:07,069 INFO [org.jbpm.executor.impl.ExecutorImpl] (MSC service thread 1-2) >>>>> Destroying Executor !!! >14:01:07,079 INFO [org.hornetq.core.server] (MSC service thread 1-14) HQ221002: HornetQ Server version 2.3.5.Final-redhat-2 (Monster Bee, 123) [f295bbfd-aa0e-11e3-909c-299a077080d4] stopped >14:01:07,413 INFO [org.jboss.as.server.deployment] (MSC service thread 1-11) JBAS015877: Stopped deployment business-central.war (runtime-name: business-central.war) in 611ms >14:01:07,425 INFO [org.jboss.as] (MSC service thread 1-6) JBAS015950: JBoss BPM Suite 6.0.1.GA (AS 7.2.1.Final-redhat-10) stopped in 619ms
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1076036
:
873950
| 873951