Uploaded image for project: 'jBPM'
  1. jBPM
  2. JBPM-9402

o.k.s.s.b.a.a.r.AuditDataReplicationKieServerTest is in probably in deadlock

    XMLWordPrintable

Details

    • False
    • False
    • NEW
    • NEW
    • Undefined
    • Hide

      if I run the testsuite locally i can get the same result with "deadlock"

      Run

      mvn clean install -Dmaven.test.failure.ignore=true -Dcontainer=wildfly -Dintegration-tests=true -Dfull=true -Dcontainer.profile=wildfly -Prun-code-coverage -B -e -nsu -fae -Pwildfly
      Show
      if I run the testsuite locally i can get the same result with "deadlock" Run mvn clean install -Dmaven.test.failure.ignore=true -Dcontainer=wildfly -Dintegration-tests=true -Dfull=true -Dcontainer.profile=wildfly -Prun-code-coverage -B -e -nsu -fae -Pwildfly
    • ---
    • ---

    Description

      https://github.com/kiegroup/droolsjbpm-integration/pull/2256/checks?check_run_id=1193130387

       

      2020-10-01T14:46:22.0404498Z 
      2020-10-01T14:46:22.0408368Z   .   ____          _            __ _ _
      2020-10-01T14:46:22.0412195Z  /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
      2020-10-01T14:46:22.0416187Z ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
      2020-10-01T14:46:22.0420595Z  \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
      2020-10-01T14:46:22.0424102Z   '  |____| .__|_| |_|_| |_\__, | / / / /
      2020-10-01T14:46:22.0427437Z  =========|_|==============|___/=/_/_/_/
      2020-10-01T14:46:22.0449663Z  :: Spring Boot ::        (v2.2.6.RELEASE)
      2020-10-01T14:46:22.0452420Z 
      2020-10-01T14:46:22.3027962Z 14:46:22.300 [main] INFO  o.k.s.s.b.a.a.r.AuditDataReplicationKieServerTest - Starting AuditDataReplicationKieServerTest on fv-az32 with PID 30532 (started by runner in /home/runner/work/droolsjbpm-integration/droolsjbpm-integration/droolsjbpm_integration/kie-spring-boot/kie-spring-boot-autoconfiguration/kie-server-spring-boot-autoconfiguration-audit-replication)
      2020-10-01T14:46:22.3036321Z 14:46:22.302 [main] INFO  o.k.s.s.b.a.a.r.AuditDataReplicationKieServerTest - No active profile set, falling back to default profiles: default
      2020-10-01T14:46:24.8173464Z 14:46:24.816 [main] WARN  com.arjuna.ats.common - ARJUNA048002: Could not find configuration file, URL was: null
      2020-10-01T14:46:24.8806711Z 14:46:24.878 [main] INFO  com.arjuna.ats.jbossatx - ARJUNA032010: JBossTS Recovery Service (tag: 92f28891fd5f29d0381da214d4f3a2917f46e301) - JBoss Inc.
      2020-10-01T14:46:24.9407173Z 14:46:24.935 [main] INFO  com.arjuna.ats.jbossatx - ARJUNA032013: Starting transaction recovery manager
      2020-10-01T14:46:25.0933167Z 14:46:25.092 [main] INFO  o.k.s.s.b.a.a.r.AuditDataReplicationKieServerAutoConfiguration - Adding OverrideId to the persistence Unit processor
      2020-10-01T14:46:25.1579625Z 14:46:25.157 [main] INFO  o.h.jpa.internal.util.LogHelper - HHH000204: Processing PersistenceUnitInfo [
      2020-10-01T14:46:25.1580499Z 	name: org.jbpm.audit
      2020-10-01T14:46:25.1580776Z 	...]
      2020-10-01T14:46:25.3024384Z 14:46:25.300 [main] INFO  org.hibernate.Version - HHH000412: Hibernate Core {5.3.17.Final}
      2020-10-01T14:46:25.3036564Z 14:46:25.302 [main] INFO  org.hibernate.cfg.Environment - HHH000206: hibernate.properties not found
      2020-10-01T14:46:25.5571492Z 14:46:25.555 [main] INFO  o.h.annotations.common.Version - HCANN000001: Hibernate Commons Annotations {5.0.5.Final}
      2020-10-01T14:46:26.6686779Z 14:46:26.663 [Thread-0 (ActiveMQ-server-org.apache.activemq.artemis.core.server.impl.ActiveMQServerImpl$6@7095e778)] WARN  o.a.activemq.artemis.core.server - AMQ222210: Storage usage is beyond max-disk-usage. System will start blocking producers.
      2020-10-01T14:46:26.7579707Z 14:46:26.757 [main] INFO  org.hibernate.dialect.Dialect - HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
      2020-10-01T14:46:27.3317514Z 14:46:27.330 [main] INFO  org.hibernate.orm.beans - HHH10005002: No explicit CDI BeanManager reference was passed to Hibernate, but CDI is available on the Hibernate ClassLoader.
      2020-10-01T14:46:28.5548612Z 14:46:28.553 [main] INFO  o.s.o.j.LocalContainerEntityManagerFactoryBean - Initialized JPA EntityManagerFactory for persistence unit 'org.jbpm.audit'
      2020-10-01T14:46:28.6246110Z 14:46:28.623 [main] INFO  o.d.p.api.TransactionManagerFactory - Using org.kie.spring.persistence.KieSpringTransactionManagerFactory@5583e3b9
      2020-10-01T14:46:28.6858944Z 14:46:28.684 [main] INFO  o.h.jpa.internal.util.LogHelper - HHH000204: Processing PersistenceUnitInfo [
      2020-10-01T14:46:28.6860272Z 	name: org.jbpm.domain
      2020-10-01T14:46:28.6860837Z 	...]
      2020-10-01T14:46:28.8463959Z 14:46:28.845 [main] INFO  org.hibernate.dialect.Dialect - HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
      2020-10-01T14:46:29.2385525Z 14:46:29.237 [main] INFO  org.hibernate.orm.beans - HHH10005002: No explicit CDI BeanManager reference was passed to Hibernate, but CDI is available on the Hibernate ClassLoader.
      2020-10-01T14:46:30.5536913Z 14:46:30.552 [main] INFO  o.h.h.i.QueryTranslatorFactoryInitiator - HHH000397: Using ASTQueryTranslatorFactory
      2020-10-01T14:46:31.1263403Z 14:46:31.125 [main] WARN  o.h.d.function.TemplateRenderer - HHH000174: Function template anticipated 4 arguments, but 1 arguments encountered
      2020-10-01T14:46:31.1300931Z 14:46:31.129 [main] WARN  o.h.d.function.TemplateRenderer - HHH000174: Function template anticipated 4 arguments, but 1 arguments encountered
      2020-10-01T14:46:31.2433882Z 14:46:31.242 [main] INFO  o.s.o.j.LocalContainerEntityManagerFactoryBean - Initialized JPA EntityManagerFactory for persistence unit 'org.jbpm.domain'
      2020-10-01T14:46:31.6875176Z 14:46:31.684 [main] WARN  o.s.b.a.o.j.JpaBaseConfiguration$JpaWebConfiguration - spring.jpa.open-in-view is enabled by default. Therefore, database queries may be performed during view rendering. Explicitly configure spring.jpa.open-in-view to disable this warning
      2020-10-01T14:46:31.9361844Z 14:46:31.935 [main] INFO  o.s.s.c.ThreadPoolTaskExecutor - Initializing ExecutorService 'applicationTaskExecutor'
      2020-10-01T14:46:32.4002420Z 14:46:32.399 [main] INFO  o.m.p.jms.JmsPoolConnectionFactory - Provided ConnectionFactory implementation is JMS 2.0+ capable.
      2020-10-01T14:46:32.7635055Z 14:46:32.762 [main] INFO  com.arjuna.ats.arjuna - ARJUNA012170: TransactionStatusManager started on port 42449 and host 127.0.0.1 with service com.arjuna.ats.arjuna.recovery.ActionStatusService
      2020-10-01T14:46:33.4541491Z 14:46:33.453 [main] INFO  o.k.s.s.a.KieServerAutoConfiguration - KieServer (id business-application-service (name business-application-service)) started initialization process
      2020-10-01T14:46:33.4927350Z 14:46:33.491 [main] INFO  o.k.s.services.impl.KieServerImpl - Starting server in 'DEVELOPMENT' mode.
      2020-10-01T14:46:33.4992304Z 14:46:33.498 [main] INFO  o.k.s.services.impl.KieServerImpl - Selected startup strategy ControllerBasedStartupStrategy - deploys kie containers given by controller ignoring locally defined
      2020-10-01T14:46:33.5011259Z 14:46:33.500 [main] INFO  o.k.s.services.impl.KieServerImpl - Configured 'KieServerStateFileRepository' server state repository
      2020-10-01T14:46:33.5208835Z 14:46:33.520 [main] INFO  o.k.s.services.impl.KieServerImpl - Server Default Extension has been successfully registered as server extension
      2020-10-01T14:46:33.5237515Z 14:46:33.523 [main] INFO  o.k.s.services.impl.KieServerImpl - Drools KIE Server extension has been successfully registered as server extension
      2020-10-01T14:46:33.5674818Z 14:46:33.565 [main] INFO  o.k.s.a.m.MarshallerFactory - Marshaller extensions init
      2020-10-01T14:46:33.5969349Z 14:46:33.596 [main] INFO  o.k.s.services.impl.KieServerImpl - jBPM KIE Server extension has been successfully registered as server extension
      2020-10-01T14:46:33.6013391Z 14:46:33.600 [main] INFO  o.k.s.services.impl.KieServerImpl - Case-Mgmt KIE Server extension has been successfully registered as server extension
      2020-10-01T14:46:33.7768224Z 14:46:33.775 [main] INFO  o.k.s.s.j.u.f.r.BootstrapFormRenderer - Boostrap Form renderer templates loaded successfully.
      2020-10-01T14:46:33.7809937Z 14:46:33.780 [main] INFO  o.k.s.s.j.u.f.r.PatternflyFormRenderer - patternfly Form renderer templates loaded successfully.
      2020-10-01T14:46:33.7849255Z 14:46:33.784 [main] INFO  o.k.s.s.j.u.f.r.PatternflyFormRenderer - workbench Form renderer templates loaded successfully.
      2020-10-01T14:46:33.7875316Z 14:46:33.786 [main] INFO  o.k.s.services.impl.KieServerImpl - jBPM-UI KIE Server extension has been successfully registered as server extension
      2020-10-01T14:46:33.7896704Z 14:46:33.789 [main] INFO  o.k.s.services.impl.KieServerImpl - DMN KIE Server extension has been successfully registered as server extension
      2020-10-01T14:46:33.7942054Z 14:46:33.793 [main] INFO  o.k.s.s.impl.policy.PolicyManager - Registered KeepLatestContainerOnlyPolicy{interval=0 ms} policy under name KeepLatestOnly
      2020-10-01T14:46:33.7955010Z 14:46:33.794 [main] INFO  o.k.s.s.impl.policy.PolicyManager - Policy manager started successfully, activated policies are []
      2020-10-01T14:46:33.8028353Z 14:46:33.801 [main] INFO  o.k.s.services.impl.ContainerManager - About to install containers on kie server 
      2020-10-01T14:46:33.8030708Z 	KieServer{id='business-application-service'name='business-application-service'version='7.45.0-SNAPSHOT'location='http://localhost:8090/rest/server'}:
      2020-10-01T14:46:33.8032166Z 	
      2020-10-01T14:46:33.8093441Z 14:46:33.808 [main] INFO  o.k.s.services.impl.KieServerImpl - KieServer business-application-service is ready to receive requests
      2020-10-01T14:46:33.8817776Z 14:46:33.880 [main] INFO  o.k.s.s.a.KieServerAutoConfiguration - KieServer (id business-application-service) started successfully
      2020-10-01T14:46:34.4381490Z 14:46:34.437 [main] INFO  org.apache.cxf.endpoint.ServerImpl - Setting the server's publish address to be /
      2020-10-01T14:46:34.7380913Z 14:46:34.737 [main] INFO  o.k.s.s.b.a.a.r.AuditDataReplicationKieServerAutoConfiguration - Adding AuditDataReplicationProcessEvent from data replication
      2020-10-01T14:46:34.7497932Z 14:46:34.748 [main] INFO  o.k.s.s.b.a.a.r.AuditDataReplicationKieServerAutoConfiguration - Adding AuditDataReplicationTaskLifeCycleEventListenerProducer from data replication
      2020-10-01T14:46:34.7584329Z 14:46:34.757 [main] INFO  o.k.s.s.b.a.a.r.AuditDataReplicationKieServerAutoConfiguration - Adding AuditDataReplicationBAMTaskSumaryListenerProducer from data replication
      2020-10-01T14:46:34.7640929Z 14:46:34.763 [main] INFO  o.k.s.s.b.a.a.r.AuditDataReplicationKieServerAutoConfiguration - Adding auditDataReplicationConsumer from data replication
      2020-10-01T14:46:35.5033595Z 14:46:35.498 [main] INFO  o.s.s.web.DefaultSecurityFilterChain - Creating filter chain: any request, [org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter@76aaeb98, org.springframework.security.web.context.SecurityContextPersistenceFilter@300165a2, org.springframework.security.web.header.HeaderWriterFilter@51eff91f, org.springframework.security.web.authentication.logout.LogoutFilter@24126489, org.springframework.security.web.authentication.www.BasicAuthenticationFilter@1a996733, org.springframework.security.web.savedrequest.RequestCacheAwareFilter@7a3b8669, org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter@adaf216, org.springframework.security.web.authentication.AnonymousAuthenticationFilter@109f1ef9, org.springframework.security.web.session.SessionManagementFilter@2373e3ae, org.springframework.security.web.access.ExceptionTranslationFilter@300a5f81, org.springframework.security.web.access.intercept.FilterSecurityInterceptor@6a7cbdf7]
      2020-10-01T14:46:35.6071399Z 14:46:35.606 [main] INFO  o.s.b.a.h.H2ConsoleAutoConfiguration - H2 console available at '/h2-console'. Database available at 'jdbc:h2:mem:kieserver'
      2020-10-01T14:46:35.8341675Z 14:46:35.833 [main] INFO  o.k.s.s.b.a.a.r.AuditDataReplicationKieServerTest - Started AuditDataReplicationKieServerTest in 14.1 seconds (JVM running for 28.068)
      2020-10-01T14:46:36.7405431Z 14:46:36.739 [AmqpProvider :(1):[amqp://localhost:10022]] INFO  o.a.q.jms.sasl.SaslMechanismFinder - Best match for SASL auth was: SASL-ANONYMOUS
      2020-10-01T14:46:36.9121809Z 14:46:36.911 [AmqpProvider :(1):[amqp://localhost:10022]] INFO  org.apache.qpid.jms.JmsConnection - Connection ID:99cc473f-709c-4826-8a10-1b16edb9e867:1 connected to remote Broker: amqp://localhost:10022
      2020-10-01T14:46:37.2612988Z 14:46:37.260 [main] INFO  o.j.r.m.impl.AbstractRuntimeManager - PerProcessInstanceRuntimeManager is created for test
      2020-10-01T14:46:37.4948056Z 14:46:37.493 [main] INFO  o.k.s.services.impl.KieServerImpl - Container test (for release id org.kie:spring-boot-kjar-test:1.0.0-SNAPSHOT) successfully started
      2020-10-01T14:46:37.4987667Z 14:46:37.498 [main] WARN  o.k.s.s.i.c.DefaultRestControllerImpl - No controllers found to update to new status org.kie.server.api.model.KieServerStateInfo@15ced914
      2020-10-01T14:47:37.8103219Z 14:47:37.809 [Transaction Reaper] WARN  com.arjuna.ats.arjuna - ARJUNA012117: TransactionReaper::check timeout for TX 0:ffff0a010004:8519:5f75ebc0:47 in state  RUN
      2020-10-01T14:47:37.8111088Z 14:47:37.810 [Transaction Reaper Worker 0] WARN  com.arjuna.ats.arjuna - ARJUNA012095: Abort of action id 0:ffff0a010004:8519:5f75ebc0:47 invoked while multiple threads active within it.
      2020-10-01T14:47:37.8153918Z 14:47:37.811 [Transaction Reaper Worker 0] WARN  com.arjuna.ats.arjuna - ARJUNA012381: Action id 0:ffff0a010004:8519:5f75ebc0:47 completed with multiple threads - thread main was in progress with java.lang.Object.wait(Native Method)
      2020-10-01T14:47:37.8155794Z java.lang.Object.wait(Object.java:502)
      2020-10-01T14:47:37.8157779Z org.apache.qpid.jms.provider.ConservativeProviderFuture.sync(ConservativeProviderFuture.java:116)
      2020-10-01T14:47:37.8159752Z org.apache.qpid.jms.JmsConnection.send(JmsConnection.java:773)
      2020-10-01T14:47:37.8161457Z org.apache.qpid.jms.JmsNoTxTransactionContext.send(JmsNoTxTransactionContext.java:37)
      2020-10-01T14:47:37.8163115Z org.apache.qpid.jms.JmsSession.send(JmsSession.java:968)
      2020-10-01T14:47:37.8164234Z org.apache.qpid.jms.JmsSession.send(JmsSession.java:847)
      2020-10-01T14:47:37.8165718Z org.apache.qpid.jms.JmsMessageProducer.sendMessage(JmsMessageProducer.java:252)
      2020-10-01T14:47:37.8167769Z org.apache.qpid.jms.JmsMessageProducer.send(JmsMessageProducer.java:200)
      2020-10-01T14:47:37.8169981Z org.messaginghub.pooled.jms.JmsPoolMessageProducer.sendMessage(JmsPoolMessageProducer.java:182)
      2020-10-01T14:47:37.8173206Z org.messaginghub.pooled.jms.JmsPoolMessageProducer.send(JmsPoolMessageProducer.java:90)
      2020-10-01T14:47:37.8176378Z org.messaginghub.pooled.jms.JmsPoolMessageProducer.send(JmsPoolMessageProducer.java:79)
      2020-10-01T14:47:37.8204490Z org.springframework.jms.core.JmsTemplate.doSend(JmsTemplate.java:634)
      2020-10-01T14:47:37.8206290Z org.springframework.jms.core.JmsTemplate.doSend(JmsTemplate.java:608)
      2020-10-01T14:47:37.8207973Z org.springframework.jms.core.JmsTemplate.lambda$send$3(JmsTemplate.java:586)
      2020-10-01T14:47:37.8209566Z org.springframework.jms.core.JmsTemplate$$Lambda$916/1306672022.doInJms(Unknown Source)
      2020-10-01T14:47:37.8210939Z org.springframework.jms.core.JmsTemplate.execute(JmsTemplate.java:504)
      2020-10-01T14:47:37.8212373Z org.springframework.jms.core.JmsTemplate.send(JmsTemplate.java:584)
      2020-10-01T14:47:37.8214813Z org.kie.server.spring.boot.autoconfiguration.audit.replication.JMSSender.sendMessage(JMSSender.java:50)
      2020-10-01T14:47:37.8220291Z org.kie.server.spring.boot.autoconfiguration.audit.replication.AuditDataReplicationProcessEventProducer.lambda$variableChanged$0(AuditDataReplicationProcessEventProducer.java:97)
      2020-10-01T14:47:37.8229810Z org.kie.server.spring.boot.autoconfiguration.audit.replication.AuditDataReplicationProcessEventProducer$$Lambda$914/1654822880.accept(Unknown Source)
      2020-10-01T14:47:37.8233660Z java.util.ArrayList.forEach(ArrayList.java:1259)
      2020-10-01T14:47:37.8238401Z org.kie.server.spring.boot.autoconfiguration.audit.replication.AuditDataReplicationProcessEventProducer.variableChanged(AuditDataReplicationProcessEventProducer.java:97)
      2020-10-01T14:47:37.8247738Z org.jbpm.process.audit.AbstractAuditLoggerAdapter.afterVariableChanged(AbstractAuditLoggerAdapter.java:122)
      2020-10-01T14:47:37.8251171Z org.drools.core.event.ProcessEventSupport.fireAfterVariableChanged(ProcessEventSupport.java:158)
      2020-10-01T14:47:37.8254142Z org.jbpm.process.instance.context.variable.VariableScopeInstance.setVariable(VariableScopeInstance.java:120)
      2020-10-01T14:47:37.8260786Z org.jbpm.process.instance.AbstractProcessInstanceFactory.createProcessInstance(AbstractProcessInstanceFactory.java:62)
      2020-10-01T14:47:37.8264394Z org.jbpm.process.instance.ProcessRuntimeImpl.startProcess(ProcessRuntimeImpl.java:286)
      2020-10-01T14:47:37.8266751Z org.jbpm.process.instance.ProcessRuntimeImpl.createProcessInstance(ProcessRuntimeImpl.java:268)
      2020-10-01T14:47:37.8269479Z org.jbpm.process.instance.ProcessRuntimeImpl.createProcessInstance(ProcessRuntimeImpl.java:203)
      2020-10-01T14:47:37.8274676Z org.jbpm.process.instance.ProcessRuntimeImpl.startProcess(ProcessRuntimeImpl.java:193)
      2020-10-01T14:47:37.8277737Z org.jbpm.process.instance.ProcessRuntimeImpl.startProcess(ProcessRuntimeImpl.java:188)
      2020-10-01T14:47:37.8280135Z org.drools.core.impl.StatefulKnowledgeSessionImpl.startProcess(StatefulKnowledgeSessionImpl.java:1881)
      2020-10-01T14:47:37.8282789Z org.drools.core.command.runtime.process.StartProcessCommand.execute(StartProcessCommand.java:122)
      2020-10-01T14:47:37.8316367Z org.drools.core.command.runtime.process.StartProcessCommand.execute(StartProcessCommand.java:40)
      2020-10-01T14:47:37.8318818Z org.drools.core.fluent.impl.PseudoClockRunner.executeBatch(PseudoClockRunner.java:102)
      2020-10-01T14:47:37.8320986Z org.drools.core.fluent.impl.PseudoClockRunner.executeBatches(PseudoClockRunner.java:69)
      2020-10-01T14:47:37.8323028Z org.drools.core.fluent.impl.PseudoClockRunner.execute(PseudoClockRunner.java:61)
      2020-10-01T14:47:37.8324902Z org.drools.core.fluent.impl.PseudoClockRunner.execute(PseudoClockRunner.java:39)
      2020-10-01T14:47:37.8327644Z org.drools.core.command.impl.AbstractInterceptor.executeNext(AbstractInterceptor.java:39)
      2020-10-01T14:47:37.8329758Z org.drools.persistence.PersistableRunner$TransactionInterceptor.execute(PersistableRunner.java:605)
      2020-10-01T14:47:37.8331621Z org.drools.persistence.PersistableRunner$TransactionInterceptor.execute(PersistableRunner.java:565)
      2020-10-01T14:47:37.8333669Z org.drools.core.command.impl.AbstractInterceptor.executeNext(AbstractInterceptor.java:39)
      2020-10-01T14:47:37.8336542Z org.drools.persistence.jpa.OptimisticLockRetryInterceptor.internalExecute(OptimisticLockRetryInterceptor.java:102)
      2020-10-01T14:47:37.8339731Z org.drools.persistence.jpa.OptimisticLockRetryInterceptor.execute(OptimisticLockRetryInterceptor.java:83)
      2020-10-01T14:47:37.8342664Z org.drools.persistence.jpa.OptimisticLockRetryInterceptor.execute(OptimisticLockRetryInterceptor.java:44)
      2020-10-01T14:47:37.8345249Z org.drools.core.command.impl.AbstractInterceptor.executeNext(AbstractInterceptor.java:39)
      2020-10-01T14:47:37.8347865Z org.drools.persistence.jta.TransactionLockInterceptor.execute(TransactionLockInterceptor.java:73)
      2020-10-01T14:47:37.8350411Z org.drools.persistence.jta.TransactionLockInterceptor.execute(TransactionLockInterceptor.java:45)
      2020-10-01T14:47:37.8353093Z org.drools.core.command.impl.AbstractInterceptor.executeNext(AbstractInterceptor.java:39)
      2020-10-01T14:47:37.8356318Z org.jbpm.runtime.manager.impl.error.ExecutionErrorHandlerInterceptor.internalExecute(ExecutionErrorHandlerInterceptor.java:66)
      2020-10-01T14:47:37.8363469Z org.jbpm.runtime.manager.impl.error.ExecutionErrorHandlerInterceptor.execute(ExecutionErrorHandlerInterceptor.java:52)
      2020-10-01T14:47:37.8367103Z org.jbpm.runtime.manager.impl.error.ExecutionErrorHandlerInterceptor.execute(ExecutionErrorHandlerInterceptor.java:29)
      2020-10-01T14:47:37.8371072Z org.drools.persistence.PersistableRunner.execute(PersistableRunner.java:400)
      2020-10-01T14:47:37.8372778Z org.drools.persistence.PersistableRunner.execute(PersistableRunner.java:68)
      2020-10-01T14:47:37.8374529Z org.drools.core.runtime.InternalLocalRunner.execute(InternalLocalRunner.java:37)
      2020-10-01T14:47:37.8377589Z org.drools.core.runtime.InternalLocalRunner.execute(InternalLocalRunner.java:41)
      2020-10-01T14:47:37.8380515Z org.drools.core.command.impl.CommandBasedStatefulKnowledgeSession.startProcess(CommandBasedStatefulKnowledgeSession.java:268)
      2020-10-01T14:47:37.8384545Z org.jbpm.kie.services.impl.ProcessServiceImpl.startProcess(ProcessServiceImpl.java:151)
      2020-10-01T14:47:37.8390670Z org.kie.server.spring.boot.autoconfiguration.audit.replication.AuditDataReplicationKieServerTest.testSimpleHumanTaskExitProcess(AuditDataReplicationKieServerTest.java:165)
      2020-10-01T14:47:37.8395978Z sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      2020-10-01T14:47:37.8397453Z sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      2020-10-01T14:47:37.8399429Z sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      2020-10-01T14:47:37.8400888Z java.lang.reflect.Method.invoke(Method.java:498)
      2020-10-01T14:47:37.8402012Z org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
      2020-10-01T14:47:37.8404793Z org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
      2020-10-01T14:47:37.8406813Z org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
      2020-10-01T14:47:37.8408844Z org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
      2020-10-01T14:47:37.8412218Z org.springframework.test.context.junit4.statements.RunBeforeTestExecutionCallbacks.evaluate(RunBeforeTestExecutionCallbacks.java:74)
      2020-10-01T14:47:37.8488568Z org.springframework.test.context.junit4.statements.RunAfterTestExecutionCallbacks.evaluate(RunAfterTestExecutionCallbacks.java:84)
      2020-10-01T14:47:37.8491856Z org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
      2020-10-01T14:47:37.8494992Z org.springframework.test.context.junit4.statements.RunBeforeTestMethodCallbacks.evaluate(RunBeforeTestMethodCallbacks.java:75)
      2020-10-01T14:47:37.8500352Z org.springframework.test.context.junit4.statements.RunAfterTestMethodCallbacks.evaluate(RunAfterTestMethodCallbacks.java:86)
      2020-10-01T14:47:37.8503919Z org.springframework.test.context.junit4.statements.SpringRepeat.evaluate(SpringRepeat.java:84)
      2020-10-01T14:47:37.8506789Z org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
      2020-10-01T14:47:37.8509322Z org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:251)
      2020-10-01T14:47:37.8513792Z org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:97)
      2020-10-01T14:47:37.8515844Z org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
      2020-10-01T14:47:37.8516764Z org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
      2020-10-01T14:47:37.8517898Z org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
      2020-10-01T14:47:37.8521080Z org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
      2020-10-01T14:47:37.8522134Z org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
      2020-10-01T14:47:37.8523552Z org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:26)
      2020-10-01T14:47:37.8526635Z org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61)
      2020-10-01T14:47:37.8529694Z org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:27)
      2020-10-01T14:47:37.8532684Z org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:70)
      2020-10-01T14:47:37.8535329Z org.junit.runners.ParentRunner.run(ParentRunner.java:363)
      2020-10-01T14:47:37.8537232Z org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:190)
      2020-10-01T14:47:37.8539070Z org.junit.runner.JUnitCore.run(JUnitCore.java:137)
      2020-10-01T14:47:37.8539873Z org.junit.runner.JUnitCore.run(JUnitCore.java:115)
      2020-10-01T14:47:37.8541267Z org.junit.vintage.engine.execution.RunnerExecutor.execute(RunnerExecutor.java:40)
      2020-10-01T14:47:37.8543054Z org.junit.vintage.engine.VintageTestEngine$$Lambda$224/915231178.accept(Unknown Source)
      2020-10-01T14:47:37.8544171Z java.util.stream.ForEachOps$ForEachOp$OfRef.accept(ForEachOps.java:183)
      2020-10-01T14:47:37.8545230Z java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
      2020-10-01T14:47:37.8546301Z java.util.Iterator.forEachRemaining(Iterator.java:116)
      2020-10-01T14:47:37.8547487Z java.util.Spliterators$IteratorSpliterator.forEachRemaining(Spliterators.java:1801)
      2020-10-01T14:47:37.8548882Z java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:482)
      2020-10-01T14:47:37.8550408Z java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:472)
      2020-10-01T14:47:37.8552072Z java.util.stream.ForEachOps$ForEachOp.evaluateSequential(ForEachOps.java:150)
      2020-10-01T14:47:37.8553324Z java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(ForEachOps.java:173)
      2020-10-01T14:47:37.8554609Z java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
      2020-10-01T14:47:37.8555999Z java.util.stream.ReferencePipeline.forEach(ReferencePipeline.java:485)
      2020-10-01T14:47:37.8557844Z org.junit.vintage.engine.VintageTestEngine.executeAllChildren(VintageTestEngine.java:80)
      2020-10-01T14:47:37.8559865Z org.junit.vintage.engine.VintageTestEngine.execute(VintageTestEngine.java:71)
      2020-10-01T14:47:37.8561700Z org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:220)
      2020-10-01T14:47:37.8563653Z org.junit.platform.launcher.core.DefaultLauncher.lambda$execute$6(DefaultLauncher.java:188)
      2020-10-01T14:47:37.8565381Z org.junit.platform.launcher.core.DefaultLauncher$$Lambda$187/1041536275.accept(Unknown Source)
      2020-10-01T14:47:37.8567537Z org.junit.platform.launcher.core.DefaultLauncher.withInterceptedStreams(DefaultLauncher.java:202)
      2020-10-01T14:47:37.8569918Z org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:181)
      2020-10-01T14:47:37.8571845Z org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:128)
      2020-10-01T14:47:37.8574479Z org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invokeAllTests(JUnitPlatformProvider.java:142)
      2020-10-01T14:47:37.8577586Z org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invoke(JUnitPlatformProvider.java:117)
      2020-10-01T14:47:37.8580571Z org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384)
      2020-10-01T14:47:37.8583325Z org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345)
      2020-10-01T14:47:37.8585361Z org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126)
      2020-10-01T14:47:37.8587033Z org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418)
      2020-10-01T14:47:37.8587886Z 
      2020-10-01T14:47:37.8589517Z 14:47:37.811 [Transaction Reaper Worker 0] WARN  com.arjuna.ats.arjuna - ARJUNA012108: CheckedAction::check - atomic action 0:ffff0a010004:8519:5f75ebc0:47 aborting with 1 threads active!
      2020-10-01T14:47:37.8592442Z 14:47:37.816 [Transaction Reaper Worker 0] WARN  o.h.r.t.b.j.i.s.SynchronizationCallbackCoordinatorTrackingImpl - HHH000451: Transaction afterCompletion called by a background thread; delaying afterCompletion processing until the original thread can handle it. [status=4]
      2020-10-01T14:47:37.8595934Z 14:47:37.816 [Transaction Reaper Worker 0] WARN  o.h.r.t.b.j.i.s.SynchronizationCallbackCoordinatorTrackingImpl - HHH000451: Transaction afterCompletion called by a background thread; delaying afterCompletion processing until the original thread can handle it. [status=4]
      2020-10-01T14:47:37.8599381Z 14:47:37.816 [Transaction Reaper Worker 0] WARN  com.arjuna.ats.arjuna - ARJUNA012121: TransactionReaper::doCancellations worker Thread[Transaction Reaper Worker 0,5,main] successfully canceled TX 0:ffff0a010004:8519:5f75ebc0:47
      2020-10-01T17:46:01.1139025Z ##[error]The operation was canceled.
      2020-10-01T17:46:01.1447873Z Post job cleanup.
      2020-10-01T17:46:01.3542119Z Cleaning up orphan processes
      2020-10-01T17:46:01.4362568Z Terminate orphan process: pid (7838) (java)
      2020-10-01T17:46:01.5012817Z Terminate orphan process: pid (30530) (sh)
      2020-10-01T17:46:01.5607523Z Terminate orphan process: pid (30532) (java) 

      it clearly is stopped for 3 hours and doing nothing after successfully canceled transaction.

      Attachments

        Issue Links

          Activity

            People

              elguardian@gmail.com Enrique González Martínez (Inactive)
              mnovotny@redhat.com Marek Novotny
              Votes:
              0 Vote for this issue
              Watchers:
              1 Start watching this issue

              Dates

                Created:
                Updated:
                Resolved: