Flow node instance with id 440107 not found - Appears randomly
Hi community!
I have a process that is start externally with a batch ( when a file appear in a specific folder a java application launch this process ).
So, if lot a file are present in this specific folder, process will be start for each file.
This is my process:
My process bug when the node : 'saveSpace' is call.
Obviously, the first time, the process working fine, but, the second times and each other time, process bugging.
That's the log error/
2020-09-08 17:32:52.698 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=180 | HOSTNAME=cprocess-demo | TENANT_ID=1 | The work [ExecuteFlowNodeWork: flowNodeInstanceId: 440107 (37, false, false, false)] failed. The failure will be handled.
2020-09-08 17:32:52.701 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=180 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Unexpected error while executing work [ExecuteFlowNodeWork: flowNodeInstanceId: 440107 (37, false, false, false)]. You may consider restarting the system. This will restart all works.
org.bonitasoft.engine.transaction.STransactionCommitException: javax.transaction.RollbackException: ARJUNA016053: Could not commit transaction.
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.complete(JTATransactionServiceImpl.java:180)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:284)
at org.bonitasoft.engine.execution.work.TxBonitaWork.work(TxBonitaWork.java:42)
at org.bonitasoft.engine.execution.work.LockProcessInstanceWork.work(LockProcessInstanceWork.java:63)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.work(InSessionBonitaWork.java:59)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$submit$1(BonitaThreadPoolExecutor.java:132)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: javax.transaction.RollbackException: ARJUNA016053: Could not commit transaction.
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1300)
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:126)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.complete(JTATransactionServiceImpl.java:176)
... 11 more
Caused by: org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1
at org.hibernate.jdbc.Expectations$BasicExpectation.checkBatched(Expectations.java:81)
at org.hibernate.jdbc.Expectations$BasicExpectation.verifyOutcome(Expectations.java:73)
at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:63)
at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:3281)
at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(AbstractEntityPersister.java:3183)
at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:3525)
at org.hibernate.action.internal.EntityUpdateAction.execute(EntityUpdateAction.java:159)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:465)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:351)
at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:350)
at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:56)
at org.hibernate.internal.SessionImpl.flush(SessionImpl.java:1258)
at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:425)
at org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorNonTrackingImpl.beforeCompletion(SynchronizationCallbackCoordinatorNonTrackingImpl.java:114)
at org.hibernate.engine.transaction.synchronization.internal.RegisteredSynchronization.beforeCompletion(RegisteredSynchronization.java:50)
at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:76)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:360)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:91)
at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162)
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1288)
... 13 more2020-09-08 17:32:52.701 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=180 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Unable to handle the failure.
org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 440107 not found
at org.bonitasoft.engine.core.process.instance.impl.FlowNodeInstancesServiceImpl.getFlowNodeInstance(FlowNodeInstancesServiceImpl.java:228)
at org.bonitasoft.engine.execution.work.failurewrapping.FlowNodeDefinitionAndInstanceContextWork.setExceptionContext(FlowNodeDefinitionAndInstanceContextWork.java:51)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork$1.call(TxInHandleFailureWrappingWork.java:54)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork$1.call(TxInHandleFailureWrappingWork.java:50)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.handleFailure(TxInHandleFailureWrappingWork.java:50)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.handleFailureWrappedWork(InSessionBonitaWork.java:103)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.handleFailure(InSessionBonitaWork.java:79)
at org.bonitasoft.engine.work.WorkExecutorServiceImpl.onFailure(WorkExecutorServiceImpl.java:78)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$submit$1(BonitaThreadPoolExecutor.java:136)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Environment:
- PostgresSQL
- CentOS
I don't know how and why my node is deleted by Bonita.
Any help will be appreciated ^^
1 answer
Hi Bastien,
Could you please open an ISSUE in our JIRA tracker? https://bonita.atlassian.net/jira/software/c/projects/BBPMC/issues
It seems we need to investigate further on this issue and it will be easier to follow it in an issue.
Merci.
Comments
Hi Delphine,
Ticket open : https://bonita.atlassian.net/browse/BBPMC-582
Hope i have send all you need to investigate.
Regards,
Comments
Hi Bastien,
In order to be able to understand where the problem may come from, could you please provide:
SELECT * FROM arch_flownode_instance WHERE sourceobjectid = 440107;
Regards,
Unai
Hello,
What is your version of Bonita platform?
How often does this happens? do you have other exceptions print in your logs?
Hi,
I'm using Bonita 7.10.
It's happens more than 10 time a day.. This is the log:
2020-09-18 16:10:16.716 +0200 SEVERE: org.bonitasoft.groovy.script.converter Creation de fichier à uploader
2020-09-18 16:10:17.086 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=216 | HOSTNAME=cprocess-demo | TENANT_ID=1 | The work [ExecuteConnectorOfActivity: flowNodeInstanceId = 868838, connectorDefinitionName = saveSpace] failed. The failure will be handled.
2020-09-18 16:10:17.088 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=216 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Unexpected error while executing work [ExecuteConnectorOfActivity: flowNodeInstanceId = 868838, connectorDefinitionName = saveSpace]. You may consider restarting the system. This will restart all works.
org.bonitasoft.engine.core.connector.exception.SConnectorException: org.bonitasoft.engine.core.operation.exception.SOperationExecutionException: org.bonitasoft.engine.data.instance.exception.SDataInstanceNotFoundException: org.bonitasoft.engine.commons.exceptions.SObjectNotFoundException: org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 868838 not found
at org.bonitasoft.engine.core.connector.impl.ConnectorServiceImpl.executeOutputOperation(ConnectorServiceImpl.java:194)
at org.bonitasoft.engine.connector.ConnectorServiceDecorator.executeOutputOperation(ConnectorServiceDecorator.java:109)
at org.bonitasoft.engine.execution.work.ExecuteConnectorWork.evaluateOutput(ExecuteConnectorWork.java:127)
at org.bonitasoft.engine.execution.work.ExecuteConnectorOfActivity.evaluateOutput(ExecuteConnectorOfActivity.java:75)
at org.bonitasoft.engine.execution.work.ExecuteConnectorWork.lambda$executeOutputOperationsAndContinue$1(ExecuteConnectorWork.java:187)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
at org.bonitasoft.engine.execution.work.ExecuteConnectorWork.executeOutputOperationsAndContinue(ExecuteConnectorWork.java:186)
at org.bonitasoft.engine.execution.work.ExecuteConnectorWork.lambda$work$0(ExecuteConnectorWork.java:157)
at java.util.concurrent.CompletableFuture.uniAccept(CompletableFuture.java:670)
at java.util.concurrent.CompletableFuture$UniAccept.tryFire(CompletableFuture.java:646)
at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:488)
at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1609)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.bonitasoft.engine.core.operation.exception.SOperationExecutionException: org.bonitasoft.engine.data.instance.exception.SDataInstanceNotFoundException: org.bonitasoft.engine.commons.exceptions.SObjectNotFoundException: org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 868838 not found
at org.bonitasoft.engine.operation.DataLeftOperandHandler.updateDataInstance(DataLeftOperandHandler.java:97)
at org.bonitasoft.engine.operation.DataLeftOperandHandler.update(DataLeftOperandHandler.java:55)
at org.bonitasoft.engine.core.operation.impl.OperationServiceImpl.updateLeftOperands(OperationServiceImpl.java:144)
at org.bonitasoft.engine.core.operation.impl.OperationServiceImpl.execute(OperationServiceImpl.java:97)
at org.bonitasoft.engine.core.connector.impl.ConnectorServiceImpl.executeOutputOperation(ConnectorServiceImpl.java:191)
... 14 more
Caused by: org.bonitasoft.engine.data.instance.exception.SDataInstanceNotFoundException: org.bonitasoft.engine.commons.exceptions.SObjectNotFoundException: org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 868838 not found
at org.bonitasoft.engine.data.instance.api.impl.DataInstanceServiceImpl.getSDatainstanceOfContainers(DataInstanceServiceImpl.java:165)
at org.bonitasoft.engine.data.instance.api.impl.DataInstanceServiceImpl.getDataInstance(DataInstanceServiceImpl.java:114)
at org.bonitasoft.engine.operation.DataLeftOperandHandler.getDataInstance(DataLeftOperandHandler.java:147)
at org.bonitasoft.engine.operation.DataLeftOperandHandler.updateDataInstance(DataLeftOperandHandler.java:91)
... 18 more
Caused by: org.bonitasoft.engine.commons.exceptions.SObjectNotFoundException: org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 868838 not found
at org.bonitasoft.engine.data.ParentContainerResolverImpl.getContainerHierarchy(ParentContainerResolverImpl.java:82)
at org.bonitasoft.engine.data.ParentContainerResolverImpl.getContainerHierarchy(ParentContainerResolverImpl.java:60)
at org.bonitasoft.engine.data.instance.api.impl.DataInstanceServiceImpl.getSDatainstanceOfContainers(DataInstanceServiceImpl.java:163)
... 21 more
Caused by: org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 868838 not found
at org.bonitasoft.engine.core.process.instance.impl.FlowNodeInstancesServiceImpl.getFlowNodeInstance(FlowNodeInstancesServiceImpl.java:228)
at org.bonitasoft.engine.data.ParentContainerResolverImpl.getsFlowNodeInstance(ParentContainerResolverImpl.java:149)
at org.bonitasoft.engine.data.ParentContainerResolverImpl.getsFlowNodeInstance(ParentContainerResolverImpl.java:165)
at org.bonitasoft.engine.data.ParentContainerResolverImpl.getNextContainer(ParentContainerResolverImpl.java:90)
at org.bonitasoft.engine.data.ParentContainerResolverImpl.getContainerHierarchy(ParentContainerResolverImpl.java:78)
... 23 more
2020-09-18 16:10:17.088 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=216 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Unable to handle the failure.
org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 868838 not found
at org.bonitasoft.engine.core.process.instance.impl.FlowNodeInstancesServiceImpl.getFlowNodeInstance(FlowNodeInstancesServiceImpl.java:228)
at org.bonitasoft.engine.execution.work.failurewrapping.FlowNodeDefinitionAndInstanceContextWork.setExceptionContext(FlowNodeDefinitionAndInstanceContextWork.java:51)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork$1.call(TxInHandleFailureWrappingWork.java:54)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork$1.call(TxInHandleFailureWrappingWork.java:50)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.handleFailure(TxInHandleFailureWrappingWork.java:50)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.handleFailureWrappedWork(InSessionBonitaWork.java:103)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.handleFailure(InSessionBonitaWork.java:79)
at org.bonitasoft.engine.work.WorkExecutorServiceImpl.onFailure(WorkExecutorServiceImpl.java:78)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$null$0(BonitaThreadPoolExecutor.java:147)
at java.util.concurrent.CompletableFuture.uniHandle(CompletableFuture.java:836)
at java.util.concurrent.CompletableFuture$UniHandle.tryFire(CompletableFuture.java:811)
at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:488)
at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1609)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
regards,
When i trying the query:
select * from arch_flownode_instance
where id = 440107 OR sourceobjectid=440107;
Nothing apear
Do you have any other stack traces before that `SFlowNodeNotFoundException` ?
Is that process called by another process? programmatically?
According yo you stack traces, it seems that the flownode instance that you are referring to is deleted
while the output operation of the connector is being executed.
The first stack trace says that it fails because some rows that needed to be updated were not (org.hibernate.StaleStateException: Batch update returned unexpected row count)
And then fail to put the element as failed because it does not exist anymore
The second stack trace show almost the same thing: While retrieving the data of the flow node, we are unable to find it in the database
Then it fails to put the element as failed because it does not exist anymore
Hi,
I don't have any stack strace before this one.
For information, this process is started by a 'cron' in an external java application.
In this process, i'm calling a subprocess ( who's throw this stack ). By the way, i finish my sub task by a 'end event' ( the empty red circle ).
What could possibly happen is that the calling process aborts that subprocess while it's being executed.
To understand what is really happening you might need to activate some debug logs on the following classes:
org.bonitasoft.engine.execution.ProcessInstanceInterruptor org.bonitasoft.engine.work.WorkExecutorServiceImpl org.bonitasoft.engine.persistence.TenantHibernatePersistenceService
If you are able to reproduce that on a smaller/sharable process, it would be nice, If you are not, having those logs in debug level should help understand.
Hello,
thx for your reply. I have added logs. We can see that the process 'destroy' flownodes.
You can find file here : https://we.tl/t-YyI3oNcpzM
2020-10-08 17:56:07.805 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=133 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@d35fa26[uuid=9b41a114-603d-41b7-800d-90b998eb23f3,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71088, stateCanceling=false, stateExecuting=true, stateId=4, stateAborting=false, flowNodeInstanceId=1480367},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:56:07.799Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.870 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=212 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@368dac77[uuid=d98e6041-de1a-4632-8e1b-4e6beacc4f43,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71088, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480367},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:56:07.857Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.870 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=212 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@d35fa26[uuid=9b41a114-603d-41b7-800d-90b998eb23f3,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71088, stateCanceling=false, stateExecuting=true, stateId=4, stateAborting=false, flowNodeInstanceId=1480367},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:56:07.799Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.873 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=190 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class STaskContractData with id=39071
2020-10-08 17:56:07.873 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=190 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class STaskContractData with id=39072
2020-10-08 17:56:07.873 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=190 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class STaskContractData with id=39073
2020-10-08 17:56:07.874 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=190 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SPendingActivityMapping with id=144080
2020-10-08 17:56:07.876 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=190 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SUserTaskInstance with id=1480367
2020-10-08 17:56:07.881 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=190 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@32308d43[uuid=3fb5d230-ca46-4454-bb28-8f7053179ff8,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71088, stateCanceling=false, stateExecuting=false, stateId=32, stateAborting=false, flowNodeInstanceId=1480848},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:56:07.876Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.881 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=190 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@368dac77[uuid=d98e6041-de1a-4632-8e1b-4e6beacc4f43,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71088, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480367},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:56:07.857Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.909 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=189 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39074
2020-10-08 17:56:07.909 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=189 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39075
2020-10-08 17:56:07.909 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=189 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39076
2020-10-08 17:56:07.909 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=189 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39077
2020-10-08 17:56:07.909 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=189 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39078
2020-10-08 17:56:07.909 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=189 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39079
2020-10-08 17:56:07.939 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=189 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@5807f7aa[uuid=9b84b6a3-0698-4040-970d-92154940c318,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71207, stateCanceling=false, stateExecuting=false, stateId=61, stateAborting=false, flowNodeInstanceId=1480849},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:56:07.909Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.939 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=189 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@32308d43[uuid=3fb5d230-ca46-4454-bb28-8f7053179ff8,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71088, stateCanceling=false, stateExecuting=false, stateId=32, stateAborting=false, flowNodeInstanceId=1480848},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:56:07.876Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.944 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@337f71e5[uuid=f1daaf95-d555-49e9-a449-7ea08606bdcf,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71207, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480849},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:56:07.942Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.944 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@5807f7aa[uuid=9b84b6a3-0698-4040-970d-92154940c318,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71207, stateCanceling=false, stateExecuting=false, stateId=61, stateAborting=false, flowNodeInstanceId=1480849},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:56:07.909Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.947 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=223 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SStartEventInstance with id=1480849
2020-10-08 17:56:07.951 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@4ee09715[uuid=27ec243c-c0c0-462a-bcf9-e87dd5c691b1,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71207, stateCanceling=false, stateExecuting=false, stateId=37, stateAborting=false, flowNodeInstanceId=1480850},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:56:07.948Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.951 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@337f71e5[uuid=f1daaf95-d555-49e9-a449-7ea08606bdcf,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71207, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480849},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:56:07.942Z,abnormalExecutionDetected=false]
2020-10-08 17:56:07.995 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SAutomaticTaskInstance with id=1480850
2020-10-08 17:56:07.997 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=553608
2020-10-08 17:56:07.997 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=553609
2020-10-08 17:56:07.998 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=553610
2020-10-08 17:56:07.998 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553611
2020-10-08 17:56:07.998 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553612
2020-10-08 17:56:07.998 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553613
2020-10-08 17:56:07.998 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553614
2020-10-08 17:56:07.999 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553615
2020-10-08 17:56:07.999 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553616
2020-10-08 17:56:08.002 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@335e276[uuid=949bedc3-7871-46a3-b3f4-1ea6afe182e9,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71207, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480850},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:56:07.984Z,abnormalExecutionDetected=false]
2020-10-08 17:56:08.002 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@4ee09715[uuid=27ec243c-c0c0-462a-bcf9-e87dd5c691b1,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71207, stateCanceling=false, stateExecuting=false, stateId=37, stateAborting=false, flowNodeInstanceId=1480850},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:56:07.948Z,abnormalExecutionDetected=false]
2020-10-08 17:56:08.005 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=223 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SAutomaticTaskInstance with id=1480850
2020-10-08 17:56:08.020 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessInstance with id=71207
2020-10-08 17:56:08.020 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SCallActivityInstance with id=1480848
2020-10-08 17:56:08.022 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551820
2020-10-08 17:56:08.022 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551821
2020-10-08 17:56:08.022 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551822
2020-10-08 17:56:08.023 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551823
2020-10-08 17:56:08.023 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SBooleanDataInstance with id=551824
2020-10-08 17:56:08.023 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551825
2020-10-08 17:56:08.023 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551826
2020-10-08 17:56:08.023 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551827
2020-10-08 17:56:08.024 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551828
2020-10-08 17:56:08.024 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551829
2020-10-08 17:56:08.024 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551830
2020-10-08 17:56:08.024 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551831
2020-10-08 17:56:08.024 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551832
2020-10-08 17:56:08.024 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551833
2020-10-08 17:56:08.030 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SMappedDocument with id=3682
2020-10-08 17:56:08.033 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SSystemComment with id=800158
2020-10-08 17:56:08.034 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SSystemComment with id=800162
2020-10-08 17:56:08.035 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessInstance with id=71088
2020-10-08 17:56:08.139 +0200 ERROR: org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorNonTrackingImpl HHH000346: Error during managed flush [Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1]
2020-10-08 17:56:08.140 +0200 WARN: com.arjuna.ats.arjuna ARJUNA012125: TwoPhaseCoordinator.beforeCompletion - failed for SynchronizationImple< 0:ffff0a840feb:991f:5f7f2fe4:13f2a, org.hibernate.engine.transaction.synchronization.internal.RegisteredSynchronization@3211e75e >
org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1
at org.hibernate.jdbc.Expectations$BasicExpectation.checkBatched(Expectations.java:81)
at org.hibernate.jdbc.Expectations$BasicExpectation.verifyOutcome(Expectations.java:73)
at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:63)
at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3400)
at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3630)
at org.hibernate.action.internal.EntityDeleteAction.execute(EntityDeleteAction.java:114)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:465)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:351)
at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:350)
at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:56)
at org.hibernate.internal.SessionImpl.flush(SessionImpl.java:1258)
at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:425)
at org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorNonTrackingImpl.beforeCompletion(SynchronizationCallbackCoordinatorNonTrackingImpl.java:114)
at org.hibernate.engine.transaction.synchronization.internal.RegisteredSynchronization.beforeCompletion(RegisteredSynchronization.java:50)
at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:76)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:360)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:91)
at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162)
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1288)
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:126)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.complete(JTATransactionServiceImpl.java:176)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:284)
at org.bonitasoft.engine.execution.work.TxBonitaWork.work(TxBonitaWork.java:42)
at org.bonitasoft.engine.execution.work.LockProcessInstanceWork.work(LockProcessInstanceWork.java:63)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.work(InSessionBonitaWork.java:59)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$submit$1(BonitaThreadPoolExecutor.java:132)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
2020-10-08 17:56:08.144 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | The work [NotifyChildFinishedWork flowNodeInstanceId: 1480850] failed. The failure will be handled.
2020-10-08 17:56:08.147 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Unexpected error while executing work [NotifyChildFinishedWork flowNodeInstanceId: 1480850]. You may consider restarting the system. This will restart all works.
org.bonitasoft.engine.transaction.STransactionCommitException: javax.transaction.RollbackException: ARJUNA016053: Could not commit transaction.
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.complete(JTATransactionServiceImpl.java:180)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:284)
at org.bonitasoft.engine.execution.work.TxBonitaWork.work(TxBonitaWork.java:42)
at org.bonitasoft.engine.execution.work.LockProcessInstanceWork.work(LockProcessInstanceWork.java:63)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.work(InSessionBonitaWork.java:59)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$submit$1(BonitaThreadPoolExecutor.java:132)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: javax.transaction.RollbackException: ARJUNA016053: Could not commit transaction.
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1300)
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:126)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.complete(JTATransactionServiceImpl.java:176)
... 11 more
Caused by: org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1
at org.hibernate.jdbc.Expectations$BasicExpectation.checkBatched(Expectations.java:81)
at org.hibernate.jdbc.Expectations$BasicExpectation.verifyOutcome(Expectations.java:73)
at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:63)
at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3400)
at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3630)
at org.hibernate.action.internal.EntityDeleteAction.execute(EntityDeleteAction.java:114)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:465)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:351)
at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:350)
at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:56)
at org.hibernate.internal.SessionImpl.flush(SessionImpl.java:1258)
at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:425)
at org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorNonTrackingImpl.beforeCompletion(SynchronizationCallbackCoordinatorNonTrackingImpl.java:114)
at org.hibernate.engine.transaction.synchronization.internal.RegisteredSynchronization.beforeCompletion(RegisteredSynchronization.java:50)
at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:76)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:360)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:91)
at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162)
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1288)
... 13 more
2020-10-08 17:56:08.147 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=223 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Unable to handle the failure.
org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 1480850 not found
at org.bonitasoft.engine.core.process.instance.impl.FlowNodeInstancesServiceImpl.getFlowNodeInstance(FlowNodeInstancesServiceImpl.java:228)
at org.bonitasoft.engine.execution.work.failurewrapping.FlowNodeDefinitionAndInstanceContextWork.setExceptionContext(FlowNodeDefinitionAndInstanceContextWork.java:51)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork$1.call(TxInHandleFailureWrappingWork.java:54)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork$1.call(TxInHandleFailureWrappingWork.java:50)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.handleFailure(TxInHandleFailureWrappingWork.java:50)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.handleFailureWrappedWork(InSessionBonitaWork.java:103)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.handleFailure(InSessionBonitaWork.java:79)
at org.bonitasoft.engine.work.WorkExecutorServiceImpl.onFailure(WorkExecutorServiceImpl.java:78)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$submit$1(BonitaThreadPoolExecutor.java:136)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
2020-10-08 17:58:29.598 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=136 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@1f29d189[uuid=968894d7-b624-42c2-b904-a3cc18fcb2c9,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71089, stateCanceling=false, stateExecuting=true, stateId=4, stateAborting=false, flowNodeInstanceId=1480370},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:58:29.592Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.606 +0200 SEVERE: org.bonitasoft.groovy.script.converter Creation du mapping du formulaire avec docushare
2020-10-08 17:58:29.606 +0200 SEVERE: org.bonitasoft.groovy.script.converter form = [documentDate:null, amount:1233, endDate:2020-10-08T00:00:00.000Z, docType:contratFournisseur, deadlineDate:null, purchaseSegment:services, contractNumber:1234, documentCategory:, handle:Collection-837, title:1, provider:ecervo, purchaseNature:informatique, startDate:2020-10-08T00:00:00.000Z, concernService:si, desc:]
2020-10-08 17:58:29.606 +0200 SEVERE: org.bonitasoft.groovy.script.converter docType = contratFournisseur
2020-10-08 17:58:29.607 +0200 SEVERE: org.bonitasoft.groovy.script.converter docushareAttributes = [classe:contratFournisseur, natureAchat:informatique, dateDebut:2020-10-08T11:00:00+01:00, numChrono:20201008 - 71089, segmentAchat:services, numContrat:1234, serviceContrat:si, fournisseur:ecervo, montant:1233.0, dateFin:2020-10-08T11:00:00+01:00, title:1]
2020-10-08 17:58:29.635 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=228 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@245265e5[uuid=3abe2378-ed31-4288-aa3c-bf429ca92201,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71089, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480370},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:58:29.623Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.636 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=228 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@1f29d189[uuid=968894d7-b624-42c2-b904-a3cc18fcb2c9,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71089, stateCanceling=false, stateExecuting=true, stateId=4, stateAborting=false, flowNodeInstanceId=1480370},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:58:29.592Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.640 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class STaskContractData with id=39080
2020-10-08 17:58:29.640 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class STaskContractData with id=39081
2020-10-08 17:58:29.640 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class STaskContractData with id=39082
2020-10-08 17:58:29.641 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SPendingActivityMapping with id=144079
2020-10-08 17:58:29.644 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SUserTaskInstance with id=1480370
2020-10-08 17:58:29.650 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=213 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@71ac4761[uuid=3da9270c-890b-44f5-ad02-b31cd25519fa,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71089, stateCanceling=false, stateExecuting=false, stateId=32, stateAborting=false, flowNodeInstanceId=1480852},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:58:29.644Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.650 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=213 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@245265e5[uuid=3abe2378-ed31-4288-aa3c-bf429ca92201,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71089, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480370},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:58:29.623Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.679 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39083
2020-10-08 17:58:29.679 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39084
2020-10-08 17:58:29.679 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39085
2020-10-08 17:58:29.679 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39086
2020-10-08 17:58:29.680 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39087
2020-10-08 17:58:29.680 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=213 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessContractData with id=39088
2020-10-08 17:58:29.720 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=213 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@69194481[uuid=6993c5f0-f867-4ae4-896b-8604859c4304,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71208, stateCanceling=false, stateExecuting=false, stateId=61, stateAborting=false, flowNodeInstanceId=1480853},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:58:29.680Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.721 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=213 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@71ac4761[uuid=3da9270c-890b-44f5-ad02-b31cd25519fa,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=6850668989552313704, processInstanceId=71089, stateCanceling=false, stateExecuting=false, stateId=32, stateAborting=false, flowNodeInstanceId=1480852},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:58:29.644Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.727 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=213 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@52114fa[uuid=e4363d20-f765-4100-8ba0-c88ef128ee99,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71208, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480853},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:58:29.725Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.727 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=213 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@69194481[uuid=6993c5f0-f867-4ae4-896b-8604859c4304,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71208, stateCanceling=false, stateExecuting=false, stateId=61, stateAborting=false, flowNodeInstanceId=1480853},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:58:29.680Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.730 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=197 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SStartEventInstance with id=1480853
2020-10-08 17:58:29.734 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=197 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@6570a701[uuid=3e241df2-2299-42db-baaf-57d4630ad564,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71208, stateCanceling=false, stateExecuting=false, stateId=37, stateAborting=false, flowNodeInstanceId=1480854},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:58:29.731Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.735 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=197 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@52114fa[uuid=e4363d20-f765-4100-8ba0-c88ef128ee99,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71208, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480853},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:58:29.725Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.757 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SAutomaticTaskInstance with id=1480854
2020-10-08 17:58:29.759 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=553617
2020-10-08 17:58:29.759 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=553618
2020-10-08 17:58:29.759 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=553619
2020-10-08 17:58:29.760 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553620
2020-10-08 17:58:29.760 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553621
2020-10-08 17:58:29.760 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553622
2020-10-08 17:58:29.760 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553623
2020-10-08 17:58:29.760 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=197 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Submitted work org.bonitasoft.engine.work.WorkDescriptor@7a190761[uuid=ac359a1e-2f37-4675-b142-350afa05a761,type=FINISH_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71208, stateCanceling=false, stateExecuting=false, stateId=2, stateAborting=false, flowNodeInstanceId=1480854},retryCount=0,executionThreshold=,executionCount=0,registrationDate=2020-10-08T15:58:29.744Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.760 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553624
2020-10-08 17:58:29.761 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=553625
2020-10-08 17:58:29.761 +0200 FINE: org.bonitasoft.engine.work.WorkExecutorServiceImpl THREAD_ID=197 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Completed work org.bonitasoft.engine.work.WorkDescriptor@6570a701[uuid=3e241df2-2299-42db-baaf-57d4630ad564,type=EXECUTE_FLOWNODE,tenantId=1,parameters={processDefinitionId=5376027906635258567, processInstanceId=71208, stateCanceling=false, stateExecuting=false, stateId=37, stateAborting=false, flowNodeInstanceId=1480854},retryCount=0,executionThreshold=,executionCount=1,registrationDate=2020-10-08T15:58:29.731Z,abnormalExecutionDetected=false]
2020-10-08 17:58:29.764 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=197 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SAutomaticTaskInstance with id=1480854
2020-10-08 17:58:29.766 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessInstance with id=71208
2020-10-08 17:58:29.766 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SCallActivityInstance with id=1480852
2020-10-08 17:58:29.767 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551835
2020-10-08 17:58:29.767 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551836
2020-10-08 17:58:29.767 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551837
2020-10-08 17:58:29.768 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551838
2020-10-08 17:58:29.768 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SBooleanDataInstance with id=551839
2020-10-08 17:58:29.768 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551840
2020-10-08 17:58:29.768 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551841
2020-10-08 17:58:29.768 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551842
2020-10-08 17:58:29.769 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551843
2020-10-08 17:58:29.769 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551844
2020-10-08 17:58:29.769 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551845
2020-10-08 17:58:29.769 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SLongTextDataInstance with id=551846
2020-10-08 17:58:29.769 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551847
2020-10-08 17:58:29.770 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SXMLObjectDataInstance with id=551848
2020-10-08 17:58:29.776 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SMappedDocument with id=3681
2020-10-08 17:58:29.778 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SSystemComment with id=800159
2020-10-08 17:58:29.778 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SSystemComment with id=800163
2020-10-08 17:58:29.780 +0200 FINE: org.bonitasoft.engine.persistence.TenantHibernatePersistenceService THREAD_ID=176 | HOSTNAME=cprocess-demo | [Tenant] Deleting instance of class SProcessInstance with id=71089
2020-10-08 17:58:29.795 +0200 ERROR: org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorNonTrackingImpl HHH000346: Error during managed flush [Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1]
2020-10-08 17:58:29.795 +0200 WARN: com.arjuna.ats.arjuna ARJUNA012125: TwoPhaseCoordinator.beforeCompletion - failed for SynchronizationImple< 0:ffff0a840feb:991f:5f7f2fe4:14561, org.hibernate.engine.transaction.synchronization.internal.RegisteredSynchronization@2ce9329f >
org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1
at org.hibernate.jdbc.Expectations$BasicExpectation.checkBatched(Expectations.java:81)
at org.hibernate.jdbc.Expectations$BasicExpectation.verifyOutcome(Expectations.java:73)
at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:63)
at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3400)
at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3630)
at org.hibernate.action.internal.EntityDeleteAction.execute(EntityDeleteAction.java:114)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:465)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:351)
at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:350)
at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:56)
at org.hibernate.internal.SessionImpl.flush(SessionImpl.java:1258)
at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:425)
at org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorNonTrackingImpl.beforeCompletion(SynchronizationCallbackCoordinatorNonTrackingImpl.java:114)
at org.hibernate.engine.transaction.synchronization.internal.RegisteredSynchronization.beforeCompletion(RegisteredSynchronization.java:50)
at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:76)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:360)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:91)
at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162)
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1288)
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:126)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.complete(JTATransactionServiceImpl.java:176)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:284)
at org.bonitasoft.engine.execution.work.TxBonitaWork.work(TxBonitaWork.java:42)
at org.bonitasoft.engine.execution.work.LockProcessInstanceWork.work(LockProcessInstanceWork.java:63)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.work(InSessionBonitaWork.java:59)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$submit$1(BonitaThreadPoolExecutor.java:132)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
2020-10-08 17:58:29.796 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=197 | HOSTNAME=cprocess-demo | TENANT_ID=1 | The work [NotifyChildFinishedWork flowNodeInstanceId: 1480854] failed. The failure will be handled.
2020-10-08 17:58:29.797 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=197 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Unexpected error while executing work [NotifyChildFinishedWork flowNodeInstanceId: 1480854]. You may consider restarting the system. This will restart all works.
org.bonitasoft.engine.transaction.STransactionCommitException: javax.transaction.RollbackException: ARJUNA016053: Could not commit transaction.
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.complete(JTATransactionServiceImpl.java:180)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:284)
at org.bonitasoft.engine.execution.work.TxBonitaWork.work(TxBonitaWork.java:42)
at org.bonitasoft.engine.execution.work.LockProcessInstanceWork.work(LockProcessInstanceWork.java:63)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.work(TxInHandleFailureWrappingWork.java:41)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.work(InSessionBonitaWork.java:59)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$submit$1(BonitaThreadPoolExecutor.java:132)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: javax.transaction.RollbackException: ARJUNA016053: Could not commit transaction.
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1300)
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:126)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.complete(JTATransactionServiceImpl.java:176)
... 11 more
Caused by: org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1
at org.hibernate.jdbc.Expectations$BasicExpectation.checkBatched(Expectations.java:81)
at org.hibernate.jdbc.Expectations$BasicExpectation.verifyOutcome(Expectations.java:73)
at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:63)
at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3400)
at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3630)
at org.hibernate.action.internal.EntityDeleteAction.execute(EntityDeleteAction.java:114)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:465)
at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:351)
at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:350)
at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:56)
at org.hibernate.internal.SessionImpl.flush(SessionImpl.java:1258)
at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:425)
at org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorNonTrackingImpl.beforeCompletion(SynchronizationCallbackCoordinatorNonTrackingImpl.java:114)
at org.hibernate.engine.transaction.synchronization.internal.RegisteredSynchronization.beforeCompletion(RegisteredSynchronization.java:50)
at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:76)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:360)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:91)
at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162)
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1288)
... 13 more
2020-10-08 17:58:29.797 +0200 SEVERE: org.bonitasoft.engine.execution.work.InSessionBonitaWork THREAD_ID=197 | HOSTNAME=cprocess-demo | TENANT_ID=1 | Unable to handle the failure.
org.bonitasoft.engine.core.process.instance.api.exceptions.SFlowNodeNotFoundException: Flow node instance with id 1480854 not found
at org.bonitasoft.engine.core.process.instance.impl.FlowNodeInstancesServiceImpl.getFlowNodeInstance(FlowNodeInstancesServiceImpl.java:228)
at org.bonitasoft.engine.execution.work.failurewrapping.FlowNodeDefinitionAndInstanceContextWork.setExceptionContext(FlowNodeDefinitionAndInstanceContextWork.java:51)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork$1.call(TxInHandleFailureWrappingWork.java:54)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork$1.call(TxInHandleFailureWrappingWork.java:50)
at org.bonitasoft.engine.transaction.JTATransactionServiceImpl.executeInTransaction(JTATransactionServiceImpl.java:274)
at org.bonitasoft.engine.execution.work.failurewrapping.TxInHandleFailureWrappingWork.handleFailure(TxInHandleFailureWrappingWork.java:50)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.handleFailureWrappedWork(InSessionBonitaWork.java:103)
at org.bonitasoft.engine.execution.work.InSessionBonitaWork.handleFailure(InSessionBonitaWork.java:79)
at org.bonitasoft.engine.work.WorkExecutorServiceImpl.onFailure(WorkExecutorServiceImpl.java:78)
at org.bonitasoft.engine.work.BonitaThreadPoolExecutor.lambda$submit$1(BonitaThreadPoolExecutor.java:136)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
<p>Hi Bastien, </p>
<p>Could you share the process? or at least the groovy connector script and its output?</p>
<p>Could you check if when you have these errors there are other instances running in parallel?</p>
<p>Thanks</p>
I can't share my process dur to my company privacy.
My task that fail, it's just a 'rest POST' connector. Nothing extraordinary. In output, i just receive a JAVA Object in 'BodyAsObject'.
Hi Bastien,
Thank you for the extended logs. What the new logs show seems to go along the line of what Baptiste was saying, that is:
There seems to be something (being executed by the THREAD_ID=176) that is deleting several processes (or sub-processes, for that matter), while many of those processes are still being executed. As a result, the latter fail because by the time they need to update the flownode that is being executed, the flownode has already been deleted.
So it could well be that:
Regards,
Unai
ticket open : https://bonita.atlassian.net/browse/BBPMC-582
Thx