container-node_0 2015/10/23 13:01:15,901 GMT+0000 MONIT [Petals.Container.Components.petals-bc-soap] : traceCode = 'provideFlowStepBegin', flowInstanceId = '250d5f02-7986-11e5-94ed-0242ac11000e', flowStepId = '250f5ad1-7986-11e5-a72e-0242ac110012', flowStepInterfaceName = '{http:container-node_0 2015/10/23 13:01:15,903 GMT+0000 WARNING [Petals.Container.Components.petals-bc-soap] : An error occured during message processing, let's send it back since the exchange was active before.
java.lang.NullPointerException
at org.ow2.petals.binding.soap.listener.outgoing.SOAPCaller.call(SOAPCaller.java:110)
at org.ow2.petals.binding.soap.listener.outgoing.JBIListener.onJBIMessage(JBIListener.java:59)
at org.ow2.petals.component.framework.process.MessageExchangeProcessor.invokeJBIListener(MessageExchangeProcessor.java:469)
at org.ow2.petals.component.framework.process.MessageExchangeProcessor.processAsProvider(MessageExchangeProcessor.java:408)
at org.ow2.petals.component.framework.process.MessageExchangeProcessor.process(MessageExchangeProcessor.java:275)
at org.ow2.petals.component.framework.process.MessageExchangeProcessor.run(MessageExchangeProcessor.java:199)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
container-node_0 2015/10/23 13:01:15,934 GMT+0000 MONIT [Petals.Container.Components.petals-bc-soap] : traceCode = 'provideFlowStepFailure', flowInstanceId = '250d5f02-7986-11e5-94ed-0242ac11000e', flowStepId = '250f5ad1-7986-11e5-a72e-0242ac110012', failureMessage = 'A unknown error occurs (org.ow2.petals.binding.soap.listener.outgoing.SOAPCaller.call(SOAPCaller.java:110))'
...
container-node_0 2015/10/23 13:01:16,092 GMT+0000 MONIT [Petals.Container.Components.petals-bc-soap] : traceCode = 'provideFlowStepBegin', flowInstanceId = '25286110-7986-11e5-94ed-0242ac11000e', flowStepId = '252c7fc1-7986-11e5-a72e-0242ac110012', flowStepInterfaceName = '{http:container-node_0 2015/10/23 13:01:16,277 GMT+0000 MONIT [Petals.Container.Components.petals-bc-soap.edpt-23367c20-7986-11e5-a72e-0242ac110012] : traceCode = 'provideExtFlowStepBegin', flowInstanceId = '25286110-7986-11e5-94ed-0242ac11000e', flowStepId = '2548ba50-7986-11e5-a72e-0242ac110012', flowPreviousStepId = '252c7fc1-7986-11e5-a72e-0242ac110012', requestedURL = 'http:container-node_0 2015/10/23 13:01:16,554 GMT+0000 MONIT [Petals.Container.Components.petals-bc-soap.edpt-23367c20-7986-11e5-a72e-0242ac110012] : traceCode = 'provideExtFlowStepEnd', flowInstanceId = '25286110-7986-11e5-94ed-0242ac11000e', flowStepId = '2548ba50-7986-11e5-a72e-0242ac110012'
container-node_0 2015/10/23 13:01:16,563 GMT+0000 WARNING [Petals.Container.Components.petals-bc-soap.edpt-23367c20-7986-11e5-a72e-0242ac110012] : Exception on the WS invocation
javax.jbi.messaging.MessagingException: Can't find the Axis service client's pool: this should never happen! Key: org.ow2.petals.binding.soap.listener.outgoing.ServiceClientKey@5641699b
at org.ow2.petals.binding.soap.SoapComponentContext.returnServiceClient(SoapComponentContext.java:528)
at org.ow2.petals.binding.soap.listener.outgoing.SOAPCaller.call(SOAPCaller.java:216)
at org.ow2.petals.binding.soap.listener.outgoing.JBIListener.onJBIMessage(JBIListener.java:59)
at org.ow2.petals.component.framework.process.MessageExchangeProcessor.invokeJBIListener(MessageExchangeProcessor.java:469)
at org.ow2.petals.component.framework.process.MessageExchangeProcessor.processAsProvider(MessageExchangeProcessor.java:408)
at org.ow2.petals.component.framework.process.MessageExchangeProcessor.process(MessageExchangeProcessor.java:275)
at org.ow2.petals.component.framework.process.MessageExchangeProcessor.run(MessageExchangeProcessor.java:199)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
container-node_0 2015/10/23 13:01:16,567 GMT+0000 MONIT [Petals.Container.Components.petals-bc-soap] : traceCode = 'provideFlowStepFailure', flowInstanceId = '25286110-7986-11e5-94ed-0242ac11000e', flowStepId = '252c7fc1-7986-11e5-a72e-0242ac110012', failureMessage = 'Can't find the Axis service client's pool: this should never happen! Key: org.ow2.petals.binding.soap.listener.outgoing.ServiceClientKey@5641699b'
In log of the replicated container, we can see that a transporter NIO connection is established just after the SU deployment:
container-node_0 2015/10/28 11:12:18,881 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Deploy Service Unit 'su-SOAP-notifyVacationService-provide' container-node_0 2015/10/28 11:12:24,951 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : New Service Endpoint deployed : {http://petals.ow2.org/samples/se-bpmn/notifyVacationService}notifyV acationService ->edpt-c22ed980-7d64-11e5-bdb0-0242ac11001f (INTERNAL):roboconf-demo-1/container-node_0/petals-bc-soap container-node_0 2015/10/28 11:12:24,982 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Service Unit 'su-SOAP-notifyVacationService-provide' deployed container-node_0 2015/10/28 11:12:24,989 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42063,localport=7800] container-node_0 2015/10/28 11:12:25,000 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42064,localport=7800] container-node_0 2015/10/28 11:12:25,001 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42065,localport=7800] container-node_0 2015/10/28 11:12:25,005 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42067,localport=7800] container-node_0 2015/10/28 11:12:25,008 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42068,localport=7800] container-node_0 2015/10/28 11:12:25,062 GMT+0000 INFO [Petals.JBI-Management.DeploymentService] : Service Assembly 'sa-su-SOAP-notifyVacationService-provide' deployed container-node_0 2015/10/28 11:12:25,232 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Placeholders reloading. container-node_0 2015/10/28 11:12:25,233 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Placeholders reloaded. container-node_0 2015/10/28 11:12:25,255 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Init Service Unit 'su-SOAP-notifyVacationService-provide' container-node_0 2015/10/28 11:12:25,255 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Start Service Unit 'su-SOAP-notifyVacationService-provide'and it seems that no more message acceptor is available after few times:
These errors should be solved by
PETALSCDK-123.container-node_0 2015/10/28 11:12:18,881 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Deploy Service Unit 'su-SOAP-notifyVacationService-provide' container-node_0 2015/10/28 11:12:24,951 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : New Service Endpoint deployed : {http://petals.ow2.org/samples/se-bpmn/notifyVacationService}notifyV acationService ->edpt-c22ed980-7d64-11e5-bdb0-0242ac11001f (INTERNAL):roboconf-demo-1/container-node_0/petals-bc-soap container-node_0 2015/10/28 11:12:24,982 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Service Unit 'su-SOAP-notifyVacationService-provide' deployed container-node_0 2015/10/28 11:12:24,989 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42063,localport=7800] container-node_0 2015/10/28 11:12:25,000 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42064,localport=7800] container-node_0 2015/10/28 11:12:25,001 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42065,localport=7800] container-node_0 2015/10/28 11:12:25,005 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42067,localport=7800] container-node_0 2015/10/28 11:12:25,008 GMT+0000 INFO [Petals.Transporter.NioTransportProtocol.NioServerAgent] : A connection is accepted: Socket[addr=/172.17.0.15,port=42068,localport=7800] container-node_0 2015/10/28 11:12:25,062 GMT+0000 INFO [Petals.JBI-Management.DeploymentService] : Service Assembly 'sa-su-SOAP-notifyVacationService-provide' deployed container-node_0 2015/10/28 11:12:25,232 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Placeholders reloading. container-node_0 2015/10/28 11:12:25,233 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Placeholders reloaded. container-node_0 2015/10/28 11:12:25,255 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Init Service Unit 'su-SOAP-notifyVacationService-provide' container-node_0 2015/10/28 11:12:25,255 GMT+0000 INFO [Petals.Container.Components.petals-bc-soap] : Start Service Unit 'su-SOAP-notifyVacationService-provide'PETALSCDK-123.