Uploaded image for project: 'WildFly'
  1. WildFly
  2. WFLY-9957

ISPN000481: Cannot create remote transaction GlobalTx:X, the originator is not in the cluster view

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Unresolved
    • Icon: Major Major
    • None
    • 12.0.0.Beta1
    • Clustering

      Seen in scenario: stress-granular-dist-sync

      In the beggining of the test, all 4 servers form a cluster:

      [JBossINF] [0m[0m09:38:07,301 INFO  [org.infinispan.CLUSTER] (thread-10,ejb,dev212) ISPN000094: Received new cluster view for channel ejb: [dev212|3] (4) [dev212, dev213, dev214, dev215]
      [JBossINF] [0m[0m09:38:07,301 INFO  [org.infinispan.CLUSTER] (thread-10,ejb,dev212) ISPN100000: Node dev215 joined the cluster
      [JBossINF] [0m[0m09:38:07,301 INFO  [org.infinispan.CLUSTER] (thread-10,ejb,dev212) ISPN000094: Received new cluster view for channel ejb: [dev212|3] (4) [dev212, dev213, dev214, dev215]
      [JBossINF] [0m[0m09:38:07,302 INFO  [org.infinispan.CLUSTER] (thread-10,ejb,dev212) ISPN100000: Node dev215 joined the cluster
      [JBossINF] [0m[0m09:38:07,303 INFO  [org.infinispan.CLUSTER] (thread-10,ejb,dev212) ISPN000094: Received new cluster view for channel ejb: [dev212|3] (4) [dev212, dev213, dev214, dev215]
      [JBossINF] [0m[0m09:38:07,303 INFO  [org.infinispan.CLUSTER] (thread-10,ejb,dev212) ISPN100000: Node dev215 joined the cluster
      [JBossINF] [0m[0m09:38:07,303 INFO  [org.infinispan.CLUSTER] (thread-10,ejb,dev212) ISPN000094: Received new cluster view for channel ejb: [dev212|3] (4) [dev212, dev213, dev214, dev215]
      [JBossINF] [0m[0m09:38:07,303 INFO  [org.infinispan.CLUSTER] (thread-10,ejb,dev212) ISPN100000: Node dev215 joined the cluster
      

      After less than 1 minute of running test, servers start logging:

      [JBossINF] [0m[31m10:36:11,844 ERROR [org.infinispan.interceptors.impl.InvocationContextInterceptor] (thread-259,ejb,dev212) ISPN000136: Error executing command PrepareCommand, writing keys [SessionAccessMetaDataKey(gqatwr7cNgtvGW9Z60E3ug2v9sSoZZbGl40r1smV), SessionCreationMetaDataKey(gqatwr7cNgtvGW9Z60E3ug2v9sSoZZbGl40r1smV), SessionAttributeKey(gqatwr7cNgtvGW9Z60E3ug2v9sSoZZbGl40r1smV[1])]: org.infinispan.remoting.RemoteException: ISPN000217: Received exception from dev215, see cause for remote stack trace
      [JBossINF] 	at org.infinispan.remoting.transport.ResponseCollectors.wrapRemoteException(ResponseCollectors.java:27)
      [JBossINF] 	at org.infinispan.remoting.transport.jgroups.SyncMapResponseCollector.addException(SyncMapResponseCollector.java:48)
      [JBossINF] 	at org.infinispan.remoting.transport.jgroups.SyncMapResponseCollector.addException(SyncMapResponseCollector.java:26)
      [JBossINF] 	at org.infinispan.remoting.transport.ValidResponseCollector.addResponse(ValidResponseCollector.java:29)
      [JBossINF] 	at org.infinispan.remoting.transport.impl.MultiTargetRequest.onResponse(MultiTargetRequest.java:91)
      [JBossINF] 	at org.infinispan.remoting.transport.impl.RequestRepository.addResponse(RequestRepository.java:53)
      [JBossINF] 	at org.infinispan.remoting.transport.jgroups.JGroupsTransport.processResponse(JGroupsTransport.java:1328)
      [JBossINF] 	at org.infinispan.remoting.transport.jgroups.JGroupsTransport.processMessage(JGroupsTransport.java:1238)
      [JBossINF] 	at org.infinispan.remoting.transport.jgroups.JGroupsTransport.access$200(JGroupsTransport.java:121)
      [JBossINF] 	at org.infinispan.remoting.transport.jgroups.JGroupsTransport$ChannelCallbacks.receive(JGroupsTransport.java:1366)
      [JBossINF] 	at org.jgroups.JChannel.up(JChannel.java:819)
      [JBossINF] 	at org.jgroups.fork.ForkProtocolStack.up(ForkProtocolStack.java:134)
      [JBossINF] 	at org.jgroups.stack.Protocol.up(Protocol.java:340)
      [JBossINF] 	at org.jgroups.protocols.FORK.up(FORK.java:134)
      [JBossINF] 	at org.jgroups.protocols.FRAG3.up(FRAG3.java:171)
      [JBossINF] 	at org.jgroups.protocols.FlowControl.up(FlowControl.java:343)
      [JBossINF] 	at org.jgroups.protocols.FlowControl.up(FlowControl.java:343)
      [JBossINF] 	at org.jgroups.protocols.pbcast.GMS.up(GMS.java:864)
      [JBossINF] 	at org.jgroups.protocols.pbcast.STABLE.up(STABLE.java:240)
      [JBossINF] 	at org.jgroups.protocols.UNICAST3.deliverMessage(UNICAST3.java:1002)
      [JBossINF] 	at org.jgroups.protocols.UNICAST3.handleDataReceived(UNICAST3.java:728)
      [JBossINF] 	at org.jgroups.protocols.UNICAST3.up(UNICAST3.java:383)
      [JBossINF] 	at org.jgroups.protocols.pbcast.NAKACK2.up(NAKACK2.java:600)
      [JBossINF] 	at org.jgroups.protocols.VERIFY_SUSPECT.up(VERIFY_SUSPECT.java:119)
      [JBossINF] 	at org.jgroups.protocols.FD_ALL.up(FD_ALL.java:199)
      [JBossINF] 	at org.jgroups.protocols.FD_SOCK.up(FD_SOCK.java:252)
      [JBossINF] 	at org.jgroups.protocols.MERGE3.up(MERGE3.java:276)
      [JBossINF] 	at org.jgroups.protocols.Discovery.up(Discovery.java:267)
      [JBossINF] 	at org.jgroups.protocols.TP.passMessageUp(TP.java:1248)
      [JBossINF] 	at org.jgroups.util.SubmitToThreadPool$SingleMessageHandler.run(SubmitToThreadPool.java:87)
      [JBossINF] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
      [JBossINF] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
      [JBossINF] 	at org.jboss.as.clustering.jgroups.ClassLoaderThreadFactory.lambda$newThread$0(ClassLoaderThreadFactory.java:52)
      [JBossINF] 	at java.lang.Thread.run(Thread.java:748)
      [JBossINF] Caused by: org.infinispan.commons.CacheException: ISPN000481: Cannot create remote transaction GlobalTx:dev212:63375657, the originator is not in the cluster view
      [JBossINF] 	at org.infinispan.transaction.impl.TransactionTable.getOrCreateRemoteTransaction(TransactionTable.java:375)
      [JBossINF] 	at org.infinispan.transaction.impl.TransactionTable.getOrCreateRemoteTransaction(TransactionTable.java:360)
      [JBossINF] 	at org.infinispan.commands.tx.PrepareCommand.createContext(PrepareCommand.java:120)
      [JBossINF] 	at org.infinispan.commands.tx.PrepareCommand.invokeAsync(PrepareCommand.java:101)
      [JBossINF] 	at org.infinispan.remoting.inboundhandler.BasePerCacheInboundInvocationHandler.invokeCommand(BasePerCacheInboundInvocationHandler.java:102)
      [JBossINF] 	at org.infinispan.remoting.inboundhandler.BaseBlockingRunnable.invoke(BaseBlockingRunnable.java:99)
      [JBossINF] 	at org.infinispan.remoting.inboundhandler.BaseBlockingRunnable.runAsync(BaseBlockingRunnable.java:71)
      [JBossINF] 	at org.infinispan.remoting.inboundhandler.BaseBlockingRunnable.run(BaseBlockingRunnable.java:40)
      [JBossINF] 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
      [JBossINF] 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
      [JBossINF] 	at org.wildfly.clustering.service.concurrent.ClassLoaderThreadFactory.lambda$newThread$0(ClassLoaderThreadFactory.java:47)
      [JBossINF] 	... 1 more
      

      Also it is interesting, that clients in this test log either stale data:

      2018/02/16 10:36:16:596 EST [WARN ][Runner - 19] SFCORE_LOG - Error sampling data:  <org.jboss.smartfrog.loaddriver.RequestProcessingException: Stale session data received. Expected 31196, received 31195, Runner: 19>
              org.jboss.smartfrog.loaddriver.RequestProcessingException: Stale session data received. Expected 31196, received 31195, Runner: 19
      	at org.jboss.smartfrog.loaddriver.http.AbstractSerialNumberValidatorFactoryImpl$SerialNumberValidator.processRequest(AbstractSerialNumberValidatorFactoryImpl.java:133)
      	at org.jboss.smartfrog.loaddriver.CompoundRequestProcessorFactoryImpl$CompoundRequestProcessor.processRequest(CompoundRequestProcessorFactoryImpl.java:52)
      	at org.jboss.smartfrog.loaddriver.Runner.run(Runner.java:103)
      	at java.lang.Thread.run(Thread.java:748)
      

      or 503 Service Temporarily Unavailable

      2018/02/16 10:37:24:837 EST [WARN ][Runner - 822] HOST dev220.mw.lab.eng.bos.redhat.com:rootProcess:c - Error sampling data:  <org.jboss.smartfrog.loaddriver.RequestProcessingException: Invalid response code: 503 Content: <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
      <html><head>
      <title>503 Service Temporarily Unavailable</title>
      </head><body>
      <h1>Service Temporarily Unavailable</h1>
      <p>The server is temporarily unable to service your
      request due to maintenance downtime or capacity
      problems. Please try again later.</p>
      <hr>
      <address>Apache/2.2.26 (@VENDOR@) Server at dev221 Port 8080</address>
      </body></html>
      >
      

      Logs:
      client http://jenkins.hosts.mwqe.eng.bos.redhat.com/hudson/job/eap-7x-stress-granular-dist-sync/41/console-dev221/
      server http://jenkins.hosts.mwqe.eng.bos.redhat.com/hudson/job/eap-7x-stress-granular-dist-sync/41/console-dev212/

              pferraro@redhat.com Paul Ferraro
              mvinkler1@redhat.com Michal Vinkler
              Votes:
              0 Vote for this issue
              Watchers:
              3 Start watching this issue

                Created:
                Updated: