Uploaded image for project: 'Keycloak'
  1. Keycloak
  2. KEYCLOAK-9822

OIDCAdapterClusterTest is failing with legacy adapter

    Details

      Description

      Run with command:
      mvn clean install -Dtest=OIDCAdapterClusterTest -Papp-server-wildfly -Dskip.elytron.adapter.installation=true -Dskip.adapter.offline.installation=false

      Failing test:
      OIDCAdapterClusterTest#testSuccessfulLoginAndProgrammaticLogout:134

      Log of request separately:

      13:44:19,564 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-2) adminRequest http://localhost:8580/session-portal-distributable/logout
      13:44:19,567 DEBUG [org.keycloak.adapters.undertow.KeycloakUndertowAccount] (default task-2) session is active
      13:44:19,567 DEBUG [org.keycloak.adapters.undertow.ServletSessionTokenStore] (default task-2) Cached account found
      13:44:19,567 DEBUG [org.keycloak.adapters.wildfly.WildflyRequestAuthenticator] (default task-2) propagate security context to wildfly
      13:44:19,567 DEBUG [org.keycloak.adapters.RequestAuthenticator] (default task-2) AUTHENTICATED: was cached
      13:44:19,568 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-2) AuthenticatedActionsValve.invoke http://localhost:8580/session-portal-distributable/logout
      13:44:19,568 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-2) Policy enforcement is disabled.
      13:44:19,627 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-1) adminRequest http://localhost:8580/session-portal-distributable/k_logout
      13:44:19,633 DEBUG [org.keycloak.adapters.undertow.UndertowUserSessionManagement] (default task-1) logoutHttpSessions: [0HW6BHGgG5G3PBBq4D450x4fx4iM7Hlv_0kkoxSG]
      13:44:19,633 DEBUG [org.keycloak.adapters.undertow.UndertowUserSessionManagement] (default task-1) logoutHttpSession: 0HW6BHGgG5G3PBBq4D450x4fx4iM7Hlv_0kkoxSG
      13:44:34,636 ERROR [org.infinispan.interceptors.impl.InvocationContextInterceptor] (async-thread--p25-t1) ISPN000136: Error executing command GetKeyValueCommand, writing keys []: org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after 15 seconds for key SessionCreationMetaDataKey(0HW6BHGgG5G3PBBq4D450x4fx4iM7Hlv_0kkoxSG) and requestor GlobalTx:ha-node-2:28. Lock is held by GlobalTx:ha-node-2:27
      	at org.infinispan.util.concurrent.locks.impl.DefaultLockManager$KeyAwareExtendedLockPromise.get(DefaultLockManager.java:288)
      	at org.infinispan.util.concurrent.locks.impl.DefaultLockManager$KeyAwareExtendedLockPromise.get(DefaultLockManager.java:218)
      	at org.infinispan.util.concurrent.locks.impl.InfinispanLock$LockPlaceHolder.checkState(InfinispanLock.java:436)
      	at org.infinispan.util.concurrent.locks.impl.InfinispanLock$LockPlaceHolder.lambda$toInvocationStage$3(InfinispanLock.java:412)
      	at java.util.concurrent.CompletableFuture.uniApply(CompletableFuture.java:602)
      	at java.util.concurrent.CompletableFuture$UniApply.tryFire(CompletableFuture.java:577)
      	at java.util.concurrent.CompletableFuture$Completion.run(CompletableFuture.java:442)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
      	at org.wildfly.clustering.service.concurrent.ClassLoaderThreadFactory.lambda$newThread$0(ClassLoaderThreadFactory.java:47)
      	at java.lang.Thread.run(Thread.java:748)
      
      13:44:34,640 ERROR [io.undertow.request] (default task-1) UT005023: Exception handling request to /session-portal-distributable/k_logout: java.lang.RuntimeException: org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after 15 seconds for key SessionCreationMetaDataKey(0HW6BHGgG5G3PBBq4D450x4fx4iM7Hlv_0kkoxSG) and requestor GlobalTx:ha-node-2:28. Lock is held by GlobalTx:ha-node-2:27
      	at org.keycloak.adapters.PreAuthActionsHandler.handleLogout(PreAuthActionsHandler.java:160)
      	at org.keycloak.adapters.PreAuthActionsHandler.handleRequest(PreAuthActionsHandler.java:81)
      	at org.keycloak.adapters.undertow.ServletPreAuthActionsHandler.handleRequest(ServletPreAuthActionsHandler.java:68)
      	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
      	at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
      	at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
      	at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
      	at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
      	at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
      	at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
      	at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
      	at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
      	at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
      	at io.undertow.server.Connectors.executeRootHandler(Connectors.java:360)
      	at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:830)
      	at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
      	at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1985)
      	at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1487)
      	at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1378)
      	at java.lang.Thread.run(Thread.java:748)
      Caused by: org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after 15 seconds for key SessionCreationMetaDataKey(0HW6BHGgG5G3PBBq4D450x4fx4iM7Hlv_0kkoxSG) and requestor GlobalTx:ha-node-2:28. Lock is held by GlobalTx:ha-node-2:27
      	at org.infinispan.interceptors.impl.AsyncInterceptorChainImpl.invoke(AsyncInterceptorChainImpl.java:259)
      	at org.infinispan.cache.impl.CacheImpl.get(CacheImpl.java:527)
      	at org.infinispan.cache.impl.DecoratedCache.get(DecoratedCache.java:539)
      	at org.infinispan.cache.impl.AbstractDelegatingCache.get(AbstractDelegatingCache.java:438)
      	at org.infinispan.cache.impl.EncoderCache.get(EncoderCache.java:660)
      	at org.infinispan.cache.impl.AbstractDelegatingCache.get(AbstractDelegatingCache.java:438)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.getValue(InfinispanSessionMetaDataFactory.java:81)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.findValue(InfinispanSessionMetaDataFactory.java:71)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.findValue(InfinispanSessionMetaDataFactory.java:42)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionFactory.findValue(InfinispanSessionFactory.java:59)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionFactory.findValue(InfinispanSessionFactory.java:37)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionManager.findSession(InfinispanSessionManager.java:209)
      	at org.wildfly.clustering.web.undertow.session.DistributableSessionManager.getSession(DistributableSessionManager.java:164)
      	at org.keycloak.adapters.undertow.UndertowUserSessionManagement.getSessionById(UndertowUserSessionManagement.java:72)
      	at org.keycloak.adapters.undertow.UndertowUserSessionManagement.logoutSession(UndertowUserSessionManagement.java:61)
      	at org.keycloak.adapters.undertow.UndertowUserSessionManagement.logoutHttpSessions(UndertowUserSessionManagement.java:55)
      	at org.keycloak.adapters.undertow.SessionManagementBridge.logoutHttpSessions(SessionManagementBridge.java:45)
      	at org.keycloak.adapters.PreAuthActionsHandler.handleLogout(PreAuthActionsHandler.java:151)
      	... 24 more
      Caused by: org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after 15 seconds for key SessionCreationMetaDataKey(0HW6BHGgG5G3PBBq4D450x4fx4iM7Hlv_0kkoxSG) and requestor GlobalTx:ha-node-2:28. Lock is held by GlobalTx:ha-node-2:27
      	at org.infinispan.util.concurrent.locks.impl.DefaultLockManager$KeyAwareExtendedLockPromise.get(DefaultLockManager.java:288)
      	at org.infinispan.util.concurrent.locks.impl.DefaultLockManager$KeyAwareExtendedLockPromise.get(DefaultLockManager.java:218)
      	at org.infinispan.util.concurrent.locks.impl.InfinispanLock$LockPlaceHolder.checkState(InfinispanLock.java:436)
      	at org.infinispan.util.concurrent.locks.impl.InfinispanLock$LockPlaceHolder.lambda$toInvocationStage$3(InfinispanLock.java:412)
      	at java.util.concurrent.CompletableFuture.uniApply(CompletableFuture.java:602)
      	at java.util.concurrent.CompletableFuture$UniApply.tryFire(CompletableFuture.java:577)
      	at java.util.concurrent.CompletableFuture$Completion.run(CompletableFuture.java:442)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
      	at org.wildfly.clustering.service.concurrent.ClassLoaderThreadFactory.lambda$newThread$0(ClassLoaderThreadFactory.java:47)
      	... 1 more
      	Suppressed: org.infinispan.util.logging.TraceException
      		at org.infinispan.interceptors.impl.SimpleAsyncInvocationStage.get(SimpleAsyncInvocationStage.java:41)
      		at org.infinispan.interceptors.impl.AsyncInterceptorChainImpl.invoke(AsyncInterceptorChainImpl.java:250)
      		at org.infinispan.cache.impl.CacheImpl.get(CacheImpl.java:527)
      		at org.infinispan.cache.impl.DecoratedCache.get(DecoratedCache.java:539)
      		at org.infinispan.cache.impl.AbstractDelegatingCache.get(AbstractDelegatingCache.java:438)
      		at org.infinispan.cache.impl.EncoderCache.get(EncoderCache.java:660)
      		at org.infinispan.cache.impl.AbstractDelegatingCache.get(AbstractDelegatingCache.java:438)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.getValue(InfinispanSessionMetaDataFactory.java:81)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.findValue(InfinispanSessionMetaDataFactory.java:71)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.findValue(InfinispanSessionMetaDataFactory.java:42)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionFactory.findValue(InfinispanSessionFactory.java:59)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionFactory.findValue(InfinispanSessionFactory.java:37)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionManager.findSession(InfinispanSessionManager.java:209)
      		at org.wildfly.clustering.web.undertow.session.DistributableSessionManager.getSession(DistributableSessionManager.java:164)
      		at org.keycloak.adapters.undertow.UndertowUserSessionManagement.getSessionById(UndertowUserSessionManagement.java:72)
      		at org.keycloak.adapters.undertow.UndertowUserSessionManagement.logoutSession(UndertowUserSessionManagement.java:61)
      		at org.keycloak.adapters.undertow.UndertowUserSessionManagement.logoutHttpSessions(UndertowUserSessionManagement.java:55)
      		at org.keycloak.adapters.undertow.SessionManagementBridge.logoutHttpSessions(SessionManagementBridge.java:45)
      		at org.keycloak.adapters.PreAuthActionsHandler.handleLogout(PreAuthActionsHandler.java:151)
      		at org.keycloak.adapters.PreAuthActionsHandler.handleRequest(PreAuthActionsHandler.java:81)
      		at org.keycloak.adapters.undertow.ServletPreAuthActionsHandler.handleRequest(ServletPreAuthActionsHandler.java:68)
      		at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
      		at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
      		at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
      		at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
      		at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
      		at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
      		at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
      		at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
      		at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      		at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      		at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      		at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      		at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
      		at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
      		at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
      		at io.undertow.server.Connectors.executeRootHandler(Connectors.java:360)
      		at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:830)
      		at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
      		at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1985)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1487)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1378)
      		... 1 more
      
      

      Test log:

      Mar 13, 2019 1:36:20 PM org.openqa.selenium.remote.DesiredCapabilities chrome
      INFO: Using `new ChromeOptions()` is preferred to `DesiredCapabilities.chrome()`
      Mar 13, 2019 1:36:20 PM org.openqa.selenium.remote.DesiredCapabilities chrome
      INFO: Using `new ChromeOptions()` is preferred to `DesiredCapabilities.chrome()`
      Mar 13, 2019 1:36:21 PM org.jboss.arquillian.drone.webdriver.binary.BinaryFilesUtils extract
      INFO: Extracting zip file: /home/mhajas/.arquillian/drone/chrome/2.46/chromedriver_linux64.zip to /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/drone/f63b50301dbce2335cdd442642d7efa0
      Mar 13, 2019 1:36:21 PM org.arquillian.spacelift.Spacelift$SpaceliftInstance <init>
      INFO: Initialized Spacelift from defaults, workspace: /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base, cache: /home/mhajas/.spacelift/cache
      Mar 13, 2019 1:36:21 PM org.jboss.arquillian.drone.webdriver.binary.handler.AbstractBinaryHandler markAsExecutable
      INFO: marking binary file: /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/drone/f63b50301dbce2335cdd442642d7efa0/chromedriver as executable
      Starting ChromeDriver 2.46.628388 (4a34a70827ac54148e092aafb70504c4ea7ae926) on port 2264
      Only local connections are allowed.
      Please protect ports used by ChromeDriver and related test frameworks to prevent access by malicious code.
      Mar 13, 2019 1:36:22 PM org.openqa.selenium.remote.ProtocolHandshake createSession
      INFO: Detected dialect: OSS
      13:36:22,265 INFO  [org.keycloak.testsuite.drone.KeycloakDronePostSetup] Detected browser: chrome 72.0.3626.96
      13:36:22,277 INFO  [org.keycloak.testsuite.drone.KeycloakDronePostSetup] Configuring driver settings. implicitWait=750, pageLoadTimeout=10000
      13:36:23,231 DEBUG [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] updating admin password
      13:36:23,233 INFO  [org.keycloak.testsuite.util.URLUtils] starting navigation
      13:36:23,239 INFO  [org.keycloak.testsuite.util.URLUtils] current URL:  data:,
      13:36:23,239 INFO  [org.keycloak.testsuite.util.URLUtils] navigating to https://localhost:8543/auth
      13:36:24,600 INFO  [org.keycloak.testsuite.util.URLUtils] new current URL:  https://localhost:8543/auth/
      13:36:24,600 INFO  [org.keycloak.testsuite.util.URLUtils] navigation complete
      13:36:24,612 DEBUG [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] loading test realms
      Loaded realm demo
      13:36:24,687 INFO  [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] importing test realms
      13:36:24,687 DEBUG [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] --importing realm: demo
      13:36:26,930 INFO  [org.keycloak.testsuite.util.URLUtils] starting navigation
      13:36:26,934 INFO  [org.keycloak.testsuite.util.URLUtils] current URL:  https://localhost:8543/auth/
      13:36:26,934 INFO  [org.keycloak.testsuite.util.URLUtils] navigating to https://localhost:8543/auth/realms/demo/account
      13:36:27,804 INFO  [org.keycloak.testsuite.util.URLUtils] new current URL:  https://localhost:8543/auth/realms/demo/protocol/openid-connect/auth?client_id=account&redirect_uri=https%3A%2F%2Flocalhost%3A8543%2Fauth%2Frealms%2Fdemo%2Faccount%2Flogin-redirect&state=0%2Fe2dcc66b-cfa1-466f-81d2-c71becd52a3c&response_type=code&scope=openid
      13:36:27,805 INFO  [org.keycloak.testsuite.util.URLUtils] navigation complete
      13:36:27,805 INFO  [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] deleting cookies in 'demo' realm
      Mar 13, 2019 1:36:27 PM org.jboss.arquillian.container.test.impl.client.container.ClientContainerController start
      INFO: Manual starting of a server instance
      13:36:27,849 INFO  [org.keycloak.testsuite.arquillian.AuthServerTestEnricher] ************************app-server-wildfly-ha-node-1*****************************************************************************
      13:36:27,866 INFO  [org.jboss.threads] JBoss Threads version 2.3.2.Final
      Mar 13, 2019 1:36:27 PM org.jboss.as.arquillian.container.managed.ManagedDeployableContainer startInternal
      INFO: Starting container with: [/usr/java/jdk1.8.0_161/jre/bin/java, -D[Standalone], -agentlib:jdwp=transport=dt_socket,server=y,suspend=n,address=7901, -Xms64m, -Xmx384m, -XX:MetaspaceSize=96M, -XX:MaxMetaspaceSize=256m, -Djava.net.preferIPv4Stack=true, -ea, -Djboss.home.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly, -Dorg.jboss.boot.log.file=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1/log/server.log, -Dlogging.configuration=file:/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1/configuration/logging.properties, -jar, /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/jboss-modules.jar, -mp, /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/modules, org.jboss.as.standalone, -Djboss.home.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly, -Djboss.server.base.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1, -Djboss.server.log.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1/log, -Djboss.server.config.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1/configuration, -Djboss.socket.binding.port-offset=300, -Djboss.node.name=ha-node-1, -Dkeycloak.x509cert.lookup.provider=default, -Dapp.server.base.url=http://localhost:8280, -Dauth.server.base.url=http://localhost:8180, -Dapp.server.ssl.base.url=https://localhost:8643, -Dapp.server.ssl.required=false, -Dauth.server.ssl.base.url=https://localhost:8543, -Dauth.server.ssl.required=true, -Dmy.host.name=localhost, -Djava.security.krb5.conf=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/dependency/kerberos/test-krb5.conf-Dkie.maven.settings.custom=/home/mhajas/.m2/settings.xml, -Dkie.maven.repo.local=/home/mhajas/.m2/repository, -Drepo.url=, -c=standalone-ha.xml]
      13:36:27,935 INFO  [org.jboss.remoting] JBoss Remoting version 5.0.0.Final
      Listening for transport dt_socket at address: 7901
      13:36:28,038 INFO  [org.wildfly.security] ELY00001: WildFly Elytron version 1.7.0.Final
      13:36:28,321 INFO  [org.jboss.modules] (main) JBoss Modules version 1.8.7.Final
      13:36:28,629 INFO  [org.jboss.msc] (main) JBoss MSC version 1.4.5.Final
      13:36:28,637 INFO  [org.jboss.threads] (main) JBoss Threads version 2.3.2.Final
      13:36:28,750 INFO  [org.jboss.as] (MSC service thread 1-2) WFLYSRV0049: WildFly Full 15.0.1.Final (WildFly Core 7.0.0.Final) starting
      13:36:29,425 INFO  [org.wildfly.security] (ServerService Thread Pool -- 26) ELY00001: WildFly Elytron version 1.7.0.Final
      13:36:30,046 INFO  [org.jboss.as.controller.management-deprecated] (Controller Boot Thread) WFLYCTL0028: Attribute 'security-realm' in the resource at address '/core-service=management/management-interface=http-interface' is deprecated, and may be removed in a future version. See the attribute description in the output of the read-resource-description operation to learn more about the deprecation.
      13:36:30,086 INFO  [org.jboss.as.controller.management-deprecated] (ServerService Thread Pool -- 40) WFLYCTL0028: Attribute 'security-realm' in the resource at address '/subsystem=undertow/server=default-server/https-listener=https' is deprecated, and may be removed in a future version. See the attribute description in the output of the read-resource-description operation to learn more about the deprecation.
      13:36:30,119 INFO  [org.jboss.as.controller.management-deprecated] (ServerService Thread Pool -- 30) WFLYCTL0028: Attribute 'socket-binding' in the resource at address '/subsystem=jgroups/stack=tcpping/protocol=FD_SOCK' is deprecated, and may be removed in a future version. See the attribute description in the output of the read-resource-description operation to learn more about the deprecation.
      13:36:30,261 INFO  [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating http management service using socket-binding (management-http)
      13:36:30,279 INFO  [org.xnio] (MSC service thread 1-6) XNIO version 3.6.5.Final
      13:36:30,288 INFO  [org.xnio.nio] (MSC service thread 1-6) XNIO NIO Implementation Version 3.6.5.Final
      13:36:30,316 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 54) WFLYCLINF0001: Activating Infinispan subsystem.
      13:36:30,335 INFO  [org.jboss.as.jaxrs] (ServerService Thread Pool -- 56) WFLYRS0016: RESTEasy version 3.6.2.Final
      13:36:30,343 INFO  [org.wildfly.extension.microprofile.opentracing] (ServerService Thread Pool -- 69) WFLYTRACEXT0001: Activating MicroProfile OpenTracing Subsystem
      13:36:30,352 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 71) WFLYNAM0001: Activating Naming Subsystem
      13:36:30,352 INFO  [org.wildfly.extension.microprofile.health.smallrye] (ServerService Thread Pool -- 67) WFLYHEALTH0001: Activating Eclipse MicroProfile Health Subsystem
      13:36:30,355 INFO  [org.jboss.as.jsf] (ServerService Thread Pool -- 62) WFLYJSF0007: Activated the following JSF Implementations: [main]
      13:36:30,355 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 47) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version 1.4)
      13:36:30,356 INFO  [org.jboss.as.connector] (MSC service thread 1-7) WFLYJCA0009: Starting JCA Subsystem (WildFly/IronJacamar 1.4.11.Final)
      13:36:30,360 INFO  [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 59) WFLYCLJG0001: Activating JGroups subsystem. JGroups version 4.0.15
      13:36:30,368 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-1) WFLYMAIL0002: Unbound mail session [java:jboss/mail/Default]
      13:36:30,371 INFO  [org.wildfly.extension.microprofile.metrics.smallrye] (ServerService Thread Pool -- 68) WFLYMETRICS0001: Activating Eclipse MicroProfile Metrics Subsystem
      13:36:30,379 INFO  [org.wildfly.extension.microprofile.config.smallrye._private] (ServerService Thread Pool -- 66) WFLYCONF0001: Activating WildFly MicroProfile Config Subsystem
      13:36:30,382 WARN  [org.jboss.as.txn] (ServerService Thread Pool -- 80) WFLYTX0013: The node-identifier attribute on the /subsystem=transactions is set to the default value. This is a danger for environments running multiple servers. Please make sure the attribute value is unique.
      13:36:30,389 INFO  [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-5) WFLYJCA0018: Started Driver service with driver-name = h2
      13:36:30,391 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 77) WFLYSEC0002: Activating Security Subsystem
      13:36:30,401 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 82) WFLYWS0002: Activating WebServices Extension
      13:36:30,423 INFO  [org.jboss.as.security] (MSC service thread 1-4) WFLYSEC0001: Current PicketBox version=5.0.3.Final
      13:36:30,447 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-3) WFLYUT0003: Undertow 2.0.15.Final starting
      13:36:30,494 INFO  [org.jboss.remoting] (MSC service thread 1-6) JBoss Remoting version 5.0.8.Final
      13:36:30,531 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-7) WFLYMAIL0001: Bound mail session [java:jboss/mail/Default]
      13:36:30,536 INFO  [org.jboss.as.naming] (MSC service thread 1-2) WFLYNAM0003: Starting Naming Service
      13:36:30,544 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) WFLYJCA0010: Unbound data source [java:jboss/datasources/ExampleDS]
      13:36:30,562 INFO  [io.smallrye.metrics] (MSC service thread 1-8) Converted [2] config entries and added [4] replacements
      13:36:30,574 INFO  [io.smallrye.metrics] (MSC service thread 1-8) Converted [3] config entries and added [14] replacements
      13:36:30,657 INFO  [org.jboss.as.ejb3] (MSC service thread 1-2) WFLYEJB0482: Strict pool mdb-strict-max-pool is using a max instance size of 32 (per class), which is derived from the number of CPUs on this host.
      13:36:30,661 INFO  [org.jboss.as.ejb3] (MSC service thread 1-5) WFLYEJB0481: Strict pool slsb-strict-max-pool is using a max instance size of 128 (per class), which is derived from thread worker pool sizing.
      13:36:30,688 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 81) WFLYUT0014: Creating file handler for path '/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/welcome-content' with options [directory-listing: 'false', follow-symlink: 'false', case-sensitive: 'true', safe-symlink-paths: '[]']
      13:36:30,699 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0012: Started server default-server.
      13:36:30,701 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0018: Host default-host starting
      13:36:30,780 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0006: Undertow AJP listener ajp listening on 127.0.0.1:8309
      13:36:30,785 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0006: Undertow HTTP listener default listening on 127.0.0.1:8380
      13:36:31,009 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 84) MODCLUSTER000001: Initializing mod_cluster version 1.4.0.Final
      13:36:31,019 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 84) MODCLUSTER000032: Listening to proxy advertisements on /224.0.1.105:23364
      13:36:31,044 INFO  [org.jboss.as.ejb3] (MSC service thread 1-4) WFLYEJB0493: EJB subsystem suspension complete
      13:36:31,132 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-5) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS]
      13:36:31,134 INFO  [org.jboss.as.patching] (MSC service thread 1-1) WFLYPAT0050: WildFly Full cumulative patch ID is: base, one-off patches include: none
      13:36:31,147 WARN  [org.jboss.as.domain.management.security] (MSC service thread 1-5) WFLYDM0111: Keystore /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1/configuration/application.keystore not found, it will be auto generated on first use with a self signed certificate for host localhost
      13:36:31,163 INFO  [org.jboss.as.server.deployment.scanner] (MSC service thread 1-2) WFLYDS0013: Started FileSystemDeploymentService for directory /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1/deployments
      13:36:31,244 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-8) WFLYUT0006: Undertow HTTPS listener https listening on 127.0.0.1:8743
      13:36:31,315 INFO  [org.jboss.ws.common.management] (MSC service thread 1-2) JBWS022052: Starting JBossWS 5.2.4.Final (Apache CXF 3.2.5.jbossorg-1) 
      13:36:31,371 INFO  [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0212: Resuming server
      13:36:31,373 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management interface listening on http://127.0.0.1:10290/management
      13:36:31,374 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console listening on http://127.0.0.1:10290
      13:36:31,374 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Full 15.0.1.Final (WildFly Core 7.0.0.Final) started in 3392ms - Started 321 of 623 services (409 services are lazy, passive or on-demand)
      Mar 13, 2019 1:36:31 PM org.jboss.arquillian.container.test.impl.client.container.ClientContainerController start
      INFO: Manual starting of a server instance
      13:36:31,630 INFO  [org.keycloak.testsuite.arquillian.AuthServerTestEnricher] ************************app-server-wildfly-ha-node-2*****************************************************************************
      Mar 13, 2019 1:36:31 PM org.jboss.as.arquillian.container.managed.ManagedDeployableContainer startInternal
      INFO: Starting container with: [/usr/java/jdk1.8.0_161/jre/bin/java, -D[Standalone], -agentlib:jdwp=transport=dt_socket,server=y,suspend=n,address=7902, -Xms64m, -Xmx384m, -XX:MetaspaceSize=96M, -XX:MaxMetaspaceSize=256m, -Djava.net.preferIPv4Stack=true, -ea, -Djboss.home.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly, -Dorg.jboss.boot.log.file=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2/log/server.log, -Dlogging.configuration=file:/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2/configuration/logging.properties, -jar, /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/jboss-modules.jar, -mp, /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/modules, org.jboss.as.standalone, -Djboss.home.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly, -Djboss.server.base.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2, -Djboss.server.log.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2/log, -Djboss.server.config.dir=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2/configuration, -Djboss.socket.binding.port-offset=400, -Djboss.node.name=ha-node-2, -Dkeycloak.x509cert.lookup.provider=default, -Dapp.server.base.url=http://localhost:8280, -Dauth.server.base.url=http://localhost:8180, -Dapp.server.ssl.base.url=https://localhost:8643, -Dapp.server.ssl.required=false, -Dauth.server.ssl.base.url=https://localhost:8543, -Dauth.server.ssl.required=true, -Dmy.host.name=localhost, -Djava.security.krb5.conf=/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/dependency/kerberos/test-krb5.conf-Dkie.maven.settings.custom=/home/mhajas/.m2/settings.xml, -Dkie.maven.repo.local=/home/mhajas/.m2/repository, -Drepo.url=, -c=standalone-ha.xml]
      Listening for transport dt_socket at address: 7902
      13:36:32,038 INFO  [org.jboss.modules] (main) JBoss Modules version 1.8.7.Final
      13:36:32,395 INFO  [org.jboss.msc] (main) JBoss MSC version 1.4.5.Final
      13:36:32,404 INFO  [org.jboss.threads] (main) JBoss Threads version 2.3.2.Final
      13:36:32,526 INFO  [org.jboss.as] (MSC service thread 1-2) WFLYSRV0049: WildFly Full 15.0.1.Final (WildFly Core 7.0.0.Final) starting
      13:36:33,200 INFO  [org.wildfly.security] (ServerService Thread Pool -- 30) ELY00001: WildFly Elytron version 1.7.0.Final
      13:36:33,830 INFO  [org.jboss.as.controller.management-deprecated] (Controller Boot Thread) WFLYCTL0028: Attribute 'security-realm' in the resource at address '/core-service=management/management-interface=http-interface' is deprecated, and may be removed in a future version. See the attribute description in the output of the read-resource-description operation to learn more about the deprecation.
      13:36:33,864 INFO  [org.jboss.as.controller.management-deprecated] (ServerService Thread Pool -- 27) WFLYCTL0028: Attribute 'security-realm' in the resource at address '/subsystem=undertow/server=default-server/https-listener=https' is deprecated, and may be removed in a future version. See the attribute description in the output of the read-resource-description operation to learn more about the deprecation.
      13:36:33,874 INFO  [org.jboss.as.controller.management-deprecated] (ServerService Thread Pool -- 2) WFLYCTL0028: Attribute 'socket-binding' in the resource at address '/subsystem=jgroups/stack=tcpping/protocol=FD_SOCK' is deprecated, and may be removed in a future version. See the attribute description in the output of the read-resource-description operation to learn more about the deprecation.
      13:36:33,992 INFO  [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating http management service using socket-binding (management-http)
      13:36:34,131 INFO  [org.xnio] (MSC service thread 1-2) XNIO version 3.6.5.Final
      13:36:34,138 INFO  [org.xnio.nio] (MSC service thread 1-2) XNIO NIO Implementation Version 3.6.5.Final
      13:36:34,186 INFO  [org.jboss.remoting] (MSC service thread 1-6) JBoss Remoting version 5.0.8.Final
      13:36:34,194 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 54) WFLYCLINF0001: Activating Infinispan subsystem.
      13:36:34,201 INFO  [org.jboss.as.jaxrs] (ServerService Thread Pool -- 56) WFLYRS0016: RESTEasy version 3.6.2.Final
      13:36:34,203 INFO  [org.wildfly.extension.microprofile.config.smallrye._private] (ServerService Thread Pool -- 66) WFLYCONF0001: Activating WildFly MicroProfile Config Subsystem
      13:36:34,210 INFO  [org.wildfly.extension.microprofile.opentracing] (ServerService Thread Pool -- 69) WFLYTRACEXT0001: Activating MicroProfile OpenTracing Subsystem
      13:36:34,217 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 71) WFLYNAM0001: Activating Naming Subsystem
      13:36:34,219 INFO  [org.jboss.as.clustering.jgroups] (ServerService Thread Pool -- 59) WFLYCLJG0001: Activating JGroups subsystem. JGroups version 4.0.15
      13:36:34,220 WARN  [org.jboss.as.txn] (ServerService Thread Pool -- 80) WFLYTX0013: The node-identifier attribute on the /subsystem=transactions is set to the default value. This is a danger for environments running multiple servers. Please make sure the attribute value is unique.
      13:36:34,221 INFO  [org.wildfly.extension.microprofile.health.smallrye] (ServerService Thread Pool -- 67) WFLYHEALTH0001: Activating Eclipse MicroProfile Health Subsystem
      13:36:34,227 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-7) WFLYMAIL0002: Unbound mail session [java:jboss/mail/Default]
      13:36:34,227 INFO  [org.jboss.as.jsf] (ServerService Thread Pool -- 62) WFLYJSF0007: Activated the following JSF Implementations: [main]
      13:36:34,244 INFO  [org.wildfly.extension.microprofile.metrics.smallrye] (ServerService Thread Pool -- 68) WFLYMETRICS0001: Activating Eclipse MicroProfile Metrics Subsystem
      13:36:34,247 INFO  [org.jboss.as.connector] (MSC service thread 1-2) WFLYJCA0009: Starting JCA Subsystem (WildFly/IronJacamar 1.4.11.Final)
      13:36:34,254 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 82) WFLYWS0002: Activating WebServices Extension
      13:36:34,255 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 77) WFLYSEC0002: Activating Security Subsystem
      13:36:34,261 INFO  [org.jboss.as.security] (MSC service thread 1-1) WFLYSEC0001: Current PicketBox version=5.0.3.Final
      13:36:34,262 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 47) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version 1.4)
      13:36:34,265 INFO  [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-7) WFLYJCA0018: Started Driver service with driver-name = h2
      13:36:34,275 INFO  [org.jboss.as.naming] (MSC service thread 1-7) WFLYNAM0003: Starting Naming Service
      13:36:34,281 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-7) WFLYMAIL0001: Bound mail session [java:jboss/mail/Default]
      13:36:34,303 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0003: Undertow 2.0.15.Final starting
      13:36:34,315 INFO  [io.smallrye.metrics] (MSC service thread 1-4) Converted [2] config entries and added [4] replacements
      13:36:34,331 INFO  [io.smallrye.metrics] (MSC service thread 1-4) Converted [3] config entries and added [14] replacements
      13:36:34,401 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) WFLYJCA0010: Unbound data source [java:jboss/datasources/ExampleDS]
      13:36:34,474 INFO  [org.jboss.as.ejb3] (MSC service thread 1-6) WFLYEJB0482: Strict pool mdb-strict-max-pool is using a max instance size of 32 (per class), which is derived from the number of CPUs on this host.
      13:36:34,474 INFO  [org.jboss.as.ejb3] (MSC service thread 1-3) WFLYEJB0481: Strict pool slsb-strict-max-pool is using a max instance size of 128 (per class), which is derived from thread worker pool sizing.
      13:36:34,488 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 81) WFLYUT0014: Creating file handler for path '/home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/welcome-content' with options [directory-listing: 'false', follow-symlink: 'false', case-sensitive: 'true', safe-symlink-paths: '[]']
      13:36:34,510 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-6) WFLYUT0012: Started server default-server.
      13:36:34,513 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0018: Host default-host starting
      13:36:34,575 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0006: Undertow AJP listener ajp listening on 127.0.0.1:8409
      13:36:34,577 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-3) WFLYUT0006: Undertow HTTP listener default listening on 127.0.0.1:8480
      13:36:34,582 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 84) MODCLUSTER000001: Initializing mod_cluster version 1.4.0.Final
      13:36:34,592 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 84) MODCLUSTER000032: Listening to proxy advertisements on /224.0.1.105:23364
      13:36:34,669 INFO  [org.jboss.as.ejb3] (MSC service thread 1-4) WFLYEJB0493: EJB subsystem suspension complete
      13:36:34,737 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-6) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS]
      13:36:34,775 INFO  [org.jboss.as.patching] (MSC service thread 1-4) WFLYPAT0050: WildFly Full cumulative patch ID is: base, one-off patches include: none
      13:36:34,783 WARN  [org.jboss.as.domain.management.security] (MSC service thread 1-3) WFLYDM0111: Keystore /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2/configuration/application.keystore not found, it will be auto generated on first use with a self signed certificate for host localhost
      13:36:34,791 INFO  [org.jboss.as.server.deployment.scanner] (MSC service thread 1-3) WFLYDS0013: Started FileSystemDeploymentService for directory /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2/deployments
      13:36:34,884 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0006: Undertow HTTPS listener https listening on 127.0.0.1:8843
      13:36:34,946 INFO  [org.jboss.ws.common.management] (MSC service thread 1-1) JBWS022052: Starting JBossWS 5.2.4.Final (Apache CXF 3.2.5.jbossorg-1) 
      13:36:35,011 INFO  [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0212: Resuming server
      13:36:35,015 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management interface listening on http://127.0.0.1:10390/management
      13:36:35,015 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console listening on http://127.0.0.1:10390
      13:36:35,015 INFO  [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Full 15.0.1.Final (WildFly Core 7.0.0.Final) started in 3302ms - Started 321 of 623 services (409 services are lazy, passive or on-demand)
      13:36:35,793 INFO  [org.jboss.as.repository] (management-handler-thread - 1) WFLYDR0001: Content added at location /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1/data/content/5e/ef955d308795c52be7ef4bd95a2a336bf0632d/content
      13:36:35,809 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) WFLYSRV0027: Starting deployment of "session-portal-distributable.war" (runtime-name: "session-portal-distributable.war")
      13:36:36,079 WARN  [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0081: The deployment session-portal-distributable.war will not be distributable because this feature is disabled in web-fragment.xml of the module arquillian-protocol.jar.
      13:36:36,410 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-5) WFLYSRV0018: Deployment "deployment.session-portal-distributable.war" is using a private module ("org.apache.httpcomponents") which may be changed or removed in future versions without notice.
      13:36:36,411 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-5) WFLYSRV0018: Deployment "deployment.session-portal-distributable.war" is using a private module ("org.keycloak.keycloak-saml-core") which may be changed or removed in future versions without notice.
      13:36:36,412 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-5) WFLYSRV0018: Deployment "deployment.session-portal-distributable.war" is using a private module ("org.keycloak.keycloak-saml-core-public") which may be changed or removed in future versions without notice.
      13:36:36,605 WARN  [org.jboss.weld.deployer] (MSC service thread 1-8) WFLYWELD0013: Deployment session-portal-distributable.war contains CDI annotations but no bean archive was found (no beans.xml or class with bean defining annotations was present).
      13:36:39,909 INFO  [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-3) ISPN000128: Infinispan version: Infinispan 'Infinity Minus ONE +2' 9.4.3.Final
      13:36:40,075 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-2) ISPN000078: Starting JGroups channel ejb
      13:36:40,075 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-3) ISPN000078: Starting JGroups channel ejb
      13:36:40,075 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-1) ISPN000078: Starting JGroups channel ejb
      13:36:40,075 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-5) ISPN000078: Starting JGroups channel ejb
      13:36:40,082 INFO  [org.infinispan.CLUSTER] (MSC service thread 1-1) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|0] (1) [ha-node-1]
      13:36:40,083 INFO  [org.infinispan.CLUSTER] (MSC service thread 1-2) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|0] (1) [ha-node-1]
      13:36:40,080 INFO  [org.infinispan.CLUSTER] (MSC service thread 1-5) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|0] (1) [ha-node-1]
      13:36:40,083 INFO  [org.infinispan.CLUSTER] (MSC service thread 1-3) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|0] (1) [ha-node-1]
      13:36:40,090 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-1) ISPN000079: Channel ejb local address is ha-node-1, physical addresses are [127.0.0.1:7900]
      13:36:40,095 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-3) ISPN000079: Channel ejb local address is ha-node-1, physical addresses are [127.0.0.1:7900]
      13:36:40,095 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-2) ISPN000079: Channel ejb local address is ha-node-1, physical addresses are [127.0.0.1:7900]
      13:36:40,096 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-5) ISPN000079: Channel ejb local address is ha-node-1, physical addresses are [127.0.0.1:7900]
      13:36:40,379 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 30) WFLYCLINF0002: Started session-portal-distributable.war cache from web container
      13:36:40,379 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 84) WFLYCLINF0002: Started client-mappings cache from ejb container
      13:36:40,379 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 28) WFLYCLINF0002: Started default-server cache from web container
      13:36:40,624 DEBUG [org.keycloak.adapters.undertow.KeycloakServletExtension] (ServerService Thread Pool -- 30) KeycloakServletException initialization
      13:36:40,625 DEBUG [org.keycloak.adapters.undertow.KeycloakServletExtension] (ServerService Thread Pool -- 30) using /WEB-INF/keycloak.json
      13:36:40,924 DEBUG [org.keycloak.adapters.authentication.ClientCredentialsProviderUtils] (ServerService Thread Pool -- 30) Using provider 'secret' for authentication of client 'session-portal-distributable'
      13:36:40,925 DEBUG [org.keycloak.adapters.authentication.ClientCredentialsProviderUtils] (ServerService Thread Pool -- 30) Loaded clientCredentialsProvider secret
      13:36:40,926 DEBUG [org.keycloak.adapters.authentication.ClientCredentialsProviderUtils] (ServerService Thread Pool -- 30) Loaded clientCredentialsProvider jwt
      13:36:40,926 DEBUG [org.keycloak.adapters.authentication.ClientCredentialsProviderUtils] (ServerService Thread Pool -- 30) Loaded clientCredentialsProvider secret-jwt
      13:36:40,983 DEBUG [org.keycloak.adapters.KeycloakDeployment] (ServerService Thread Pool -- 30) resolveUrls
      13:36:40,984 DEBUG [org.keycloak.adapters.KeycloakDeploymentBuilder] (ServerService Thread Pool -- 30) Use authServerUrl: https://localhost:8543/auth, tokenUrl: https://localhost:8543/auth/realms/demo/protocol/openid-connect/token, relativeUrls: NEVER
      13:36:40,985 DEBUG [org.keycloak.adapters.undertow.KeycloakServletExtension] (ServerService Thread Pool -- 30) Keycloak is using a per-deployment configuration.
      13:36:40,988 DEBUG [org.keycloak.adapters.wildfly.WildflyKeycloakServletExtension] (ServerService Thread Pool -- 30) creating WildflyAuthenticationMechanism
      13:36:40,993 DEBUG [org.keycloak.adapters.undertow.KeycloakServletExtension] (ServerService Thread Pool -- 30) Setting jsession cookie path to: /session-portal-distributable
      13:36:41,064 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 30) WFLYUT0021: Registered web context: '/session-portal-distributable' for server 'default-server'
      13:36:41,131 INFO  [org.jboss.as.server] (management-handler-thread - 1) WFLYSRV0010: Deployed "session-portal-distributable.war" (runtime-name : "session-portal-distributable.war")
      13:36:41,993 INFO  [org.jboss.as.repository] (management-handler-thread - 1) WFLYDR0001: Content added at location /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2/data/content/b9/b496785c1aabe81e413027ed93d8fb72066da7/content
      13:36:42,009 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) WFLYSRV0027: Starting deployment of "session-portal-distributable.war" (runtime-name: "session-portal-distributable.war")
      13:36:42,257 WARN  [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0081: The deployment session-portal-distributable.war will not be distributable because this feature is disabled in web-fragment.xml of the module arquillian-protocol.jar.
      13:36:42,550 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-6) WFLYSRV0018: Deployment "deployment.session-portal-distributable.war" is using a private module ("org.apache.httpcomponents") which may be changed or removed in future versions without notice.
      13:36:42,551 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-6) WFLYSRV0018: Deployment "deployment.session-portal-distributable.war" is using a private module ("org.keycloak.keycloak-saml-core") which may be changed or removed in future versions without notice.
      13:36:42,552 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-6) WFLYSRV0018: Deployment "deployment.session-portal-distributable.war" is using a private module ("org.keycloak.keycloak-saml-core-public") which may be changed or removed in future versions without notice.
      13:36:42,738 WARN  [org.jboss.weld.deployer] (MSC service thread 1-7) WFLYWELD0013: Deployment session-portal-distributable.war contains CDI annotations but no bean archive was found (no beans.xml or class with bean defining annotations was present).
      13:36:42,866 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|1] (2) [ha-node-1, ha-node-2]
      13:36:42,870 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN100000: Node ha-node-2 joined the cluster
      13:36:42,872 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|1] (2) [ha-node-1, ha-node-2]
      13:36:42,873 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN100000: Node ha-node-2 joined the cluster
      13:36:42,874 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|1] (2) [ha-node-1, ha-node-2]
      13:36:42,875 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN100000: Node ha-node-2 joined the cluster
      13:36:42,876 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|1] (2) [ha-node-1, ha-node-2]
      13:36:42,876 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN100000: Node ha-node-2 joined the cluster
      13:36:43,103 INFO  [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-3) ISPN000128: Infinispan version: Infinispan 'Infinity Minus ONE +2' 9.4.3.Final
      13:36:43,232 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-1) ISPN000078: Starting JGroups channel ejb
      13:36:43,232 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-3) ISPN000078: Starting JGroups channel ejb
      13:36:43,232 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-6) ISPN000078: Starting JGroups channel ejb
      13:36:43,232 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-5) ISPN000078: Starting JGroups channel ejb
      13:36:43,239 INFO  [org.infinispan.CLUSTER] (MSC service thread 1-1) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|1] (2) [ha-node-1, ha-node-2]
      13:36:43,239 INFO  [org.infinispan.CLUSTER] (MSC service thread 1-3) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|1] (2) [ha-node-1, ha-node-2]
      13:36:43,239 INFO  [org.infinispan.CLUSTER] (MSC service thread 1-6) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|1] (2) [ha-node-1, ha-node-2]
      13:36:43,239 INFO  [org.infinispan.CLUSTER] (MSC service thread 1-5) ISPN000094: Received new cluster view for channel ejb: [ha-node-1|1] (2) [ha-node-1, ha-node-2]
      13:36:43,246 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-3) ISPN000079: Channel ejb local address is ha-node-2, physical addresses are [127.0.0.1:8000]
      13:36:43,248 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-1) ISPN000079: Channel ejb local address is ha-node-2, physical addresses are [127.0.0.1:8000]
      13:36:43,249 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-6) ISPN000079: Channel ejb local address is ha-node-2, physical addresses are [127.0.0.1:8000]
      13:36:43,249 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-5) ISPN000079: Channel ejb local address is ha-node-2, physical addresses are [127.0.0.1:8000]
      13:36:43,670 INFO  [org.infinispan.CLUSTER] (remote-thread--p5-t2) [Context=client-mappings] ISPN100002: Starting rebalance with members [ha-node-1, ha-node-2], phase READ_OLD_WRITE_ALL, topology id 2
      13:36:43,670 INFO  [org.infinispan.CLUSTER] (remote-thread--p7-t1) [Context=default-server] ISPN100002: Starting rebalance with members [ha-node-1, ha-node-2], phase READ_OLD_WRITE_ALL, topology id 2
      13:36:43,670 INFO  [org.infinispan.CLUSTER] (remote-thread--p7-t2) [Context=session-portal-distributable.war] ISPN100002: Starting rebalance with members [ha-node-1, ha-node-2], phase READ_OLD_WRITE_ALL, topology id 2
      13:36:43,711 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 84) WFLYCLINF0002: Started session-portal-distributable.war cache from web container
      13:36:43,712 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 2) WFLYCLINF0002: Started default-server cache from web container
      13:36:43,714 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 20) WFLYCLINF0002: Started client-mappings cache from ejb container
      13:36:43,810 INFO  [org.infinispan.CLUSTER] (remote-thread--p5-t1) [Context=client-mappings] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 3
      13:36:43,820 INFO  [org.infinispan.CLUSTER] (remote-thread--p7-t3) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 3
      13:36:43,831 INFO  [org.infinispan.CLUSTER] (remote-thread--p7-t3) [Context=session-portal-distributable.war] ISPN100009: Advancing to rebalance phase READ_ALL_WRITE_ALL, topology id 3
      13:36:43,831 INFO  [org.infinispan.CLUSTER] (transport-thread--p14-t12) [Context=client-mappings] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 4
      13:36:43,838 INFO  [org.infinispan.CLUSTER] (transport-thread--p15-t20) [Context=default-server] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 4
      13:36:43,847 INFO  [org.infinispan.CLUSTER] (transport-thread--p14-t15) [Context=client-mappings] ISPN100010: Finished rebalance with members [ha-node-1, ha-node-2], topology id 5
      13:36:43,855 INFO  [org.infinispan.CLUSTER] (transport-thread--p15-t24) [Context=default-server] ISPN100010: Finished rebalance with members [ha-node-1, ha-node-2], topology id 5
      13:36:43,857 INFO  [org.infinispan.CLUSTER] (transport-thread--p15-t25) [Context=session-portal-distributable.war] ISPN100009: Advancing to rebalance phase READ_NEW_WRITE_ALL, topology id 4
      13:36:43,874 INFO  [org.infinispan.CLUSTER] (remote-thread--p7-t3) [Context=session-portal-distributable.war] ISPN100010: Finished rebalance with members [ha-node-1, ha-node-2], topology id 5
      13:36:43,963 DEBUG [org.keycloak.adapters.undertow.KeycloakServletExtension] (ServerService Thread Pool -- 2) KeycloakServletException initialization
      13:36:43,963 DEBUG [org.keycloak.adapters.undertow.KeycloakServletExtension] (ServerService Thread Pool -- 2) using /WEB-INF/keycloak.json
      13:36:44,266 DEBUG [org.keycloak.adapters.authentication.ClientCredentialsProviderUtils] (ServerService Thread Pool -- 2) Using provider 'secret' for authentication of client 'session-portal-distributable'
      13:36:44,267 DEBUG [org.keycloak.adapters.authentication.ClientCredentialsProviderUtils] (ServerService Thread Pool -- 2) Loaded clientCredentialsProvider secret
      13:36:44,268 DEBUG [org.keycloak.adapters.authentication.ClientCredentialsProviderUtils] (ServerService Thread Pool -- 2) Loaded clientCredentialsProvider jwt
      13:36:44,269 DEBUG [org.keycloak.adapters.authentication.ClientCredentialsProviderUtils] (ServerService Thread Pool -- 2) Loaded clientCredentialsProvider secret-jwt
      13:36:44,338 DEBUG [org.keycloak.adapters.KeycloakDeployment] (ServerService Thread Pool -- 2) resolveUrls
      13:36:44,340 DEBUG [org.keycloak.adapters.KeycloakDeploymentBuilder] (ServerService Thread Pool -- 2) Use authServerUrl: https://localhost:8543/auth, tokenUrl: https://localhost:8543/auth/realms/demo/protocol/openid-connect/token, relativeUrls: NEVER
      13:36:44,341 DEBUG [org.keycloak.adapters.undertow.KeycloakServletExtension] (ServerService Thread Pool -- 2) Keycloak is using a per-deployment configuration.
      13:36:44,344 DEBUG [org.keycloak.adapters.wildfly.WildflyKeycloakServletExtension] (ServerService Thread Pool -- 2) creating WildflyAuthenticationMechanism
      13:36:44,350 DEBUG [org.keycloak.adapters.undertow.KeycloakServletExtension] (ServerService Thread Pool -- 2) Setting jsession cookie path to: /session-portal-distributable
      13:36:44,422 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 2) WFLYUT0021: Registered web context: '/session-portal-distributable' for server 'default-server'
      13:36:44,503 INFO  [org.jboss.as.server] (management-handler-thread - 1) WFLYSRV0010: Deployed "session-portal-distributable.war" (runtime-name : "session-portal-distributable.war")
      13:36:44,770 DEBUG [org.keycloak.testsuite.arquillian.provider.URLProvider] Fixed injected @ArquillianResource URL to: http://localhost:8380/session-portal-distributable/
      13:36:44,857 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-1) adminRequest http://localhost:8580/session-portal-distributable/
      13:36:44,873 DEBUG [org.keycloak.adapters.undertow.ServletSessionTokenStore] (default task-1) session was null, returning null
      13:36:44,874 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) there was no code
      13:36:44,874 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) redirecting to auth server
      13:36:44,875 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) callback uri: http://localhost:8580/session-portal-distributable/
      13:36:44,894 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) Sending redirect to login page: https://localhost:8543/auth/realms/demo/protocol/openid-connect/auth?response_type=code&client_id=session-portal-distributable&redirect_uri=http%3A%2F%2Flocalhost%3A8580%2Fsession-portal-distributable%2F&state=d5fab340-56e1-4d56-98a0-5523e6c6c08b&login=true&scope=openid
      13:36:45,597 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-1) adminRequest http://localhost:8580/session-portal-distributable/?state=d5fab340-56e1-4d56-98a0-5523e6c6c08b&session_state=63ad3bad-b05d-478b-af4b-ea135922f739&code=a2f71a60-5ae5-4828-8920-ae7061b2a181.63ad3bad-b05d-478b-af4b-ea135922f739.c045373c-2fb6-4fbc-9749-6b4b3fc87083
      13:36:45,607 DEBUG [org.keycloak.adapters.undertow.ServletSessionTokenStore] (default task-1) Account was not in session, returning null
      13:36:45,608 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) there was a code, resolving
      13:36:45,608 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) checking state cookie for after code
      13:36:45,608 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) ** reseting application state cookie
      13:36:45,879 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) Verifying tokens
      13:36:45,916 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) Token Verification succeeded!
      13:36:45,916 DEBUG [org.keycloak.adapters.OAuthRequestAuthenticator] (default task-1) successful authenticated
      13:36:45,925 DEBUG [org.keycloak.adapters.wildfly.WildflyRequestAuthenticator] (default task-1) propagate security context to wildfly
      13:36:45,927 DEBUG [org.keycloak.adapters.RequestAuthenticator] (default task-1) User '0917c4ea-94c0-4105-8e36-fa3995a6db42' invoking 'http://localhost:8580/session-portal-distributable/?state=d5fab340-56e1-4d56-98a0-5523e6c6c08b&session_state=63ad3bad-b05d-478b-af4b-ea135922f739&code=a2f71a60-5ae5-4828-8920-ae7061b2a181.63ad3bad-b05d-478b-af4b-ea135922f739.c045373c-2fb6-4fbc-9749-6b4b3fc87083' on client 'session-portal-distributable'
      13:36:45,927 DEBUG [org.keycloak.adapters.RequestAuthenticator] (default task-1) AUTHENTICATED
      13:36:45,932 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-2) adminRequest http://localhost:8580/session-portal-distributable/
      13:36:45,956 DEBUG [org.keycloak.adapters.undertow.KeycloakUndertowAccount] (default task-2) session is active
      13:36:45,956 DEBUG [org.keycloak.adapters.undertow.ServletSessionTokenStore] (default task-2) Cached account found
      13:36:45,957 DEBUG [org.keycloak.adapters.wildfly.WildflyRequestAuthenticator] (default task-2) propagate security context to wildfly
      13:36:45,958 DEBUG [org.keycloak.adapters.RequestAuthenticator] (default task-2) AUTHENTICATED: was cached
      13:36:45,960 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-2) AuthenticatedActionsValve.invoke http://localhost:8580/session-portal-distributable/
      13:36:45,960 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-2) Policy enforcement is disabled.
      13:36:46,528 INFO  [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] Reverse proxy will direct requests to http://localhost:8480
      13:36:46,557 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-1) adminRequest http://localhost:8580/session-portal-distributable/
      13:36:46,758 DEBUG [org.keycloak.adapters.undertow.KeycloakUndertowAccount] (default task-1) session is active
      13:36:46,758 DEBUG [org.keycloak.adapters.undertow.ServletSessionTokenStore] (default task-1) Cached account found
      13:36:46,769 DEBUG [org.keycloak.adapters.wildfly.WildflyRequestAuthenticator] (default task-1) propagate security context to wildfly
      13:36:46,772 DEBUG [org.keycloak.adapters.RequestAuthenticator] (default task-1) AUTHENTICATED: was cached
      13:36:46,773 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-1) AuthenticatedActionsValve.invoke http://localhost:8580/session-portal-distributable/
      13:36:46,774 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-1) Policy enforcement is disabled.
      13:36:46,808 INFO  [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] Reverse proxy will direct requests to http://localhost:8380
      13:36:46,816 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-2) adminRequest http://localhost:8580/session-portal-distributable/
      13:36:46,820 DEBUG [org.keycloak.adapters.undertow.KeycloakUndertowAccount] (default task-2) session is active
      13:36:46,820 DEBUG [org.keycloak.adapters.undertow.ServletSessionTokenStore] (default task-2) Cached account found
      13:36:46,820 DEBUG [org.keycloak.adapters.wildfly.WildflyRequestAuthenticator] (default task-2) propagate security context to wildfly
      13:36:46,821 DEBUG [org.keycloak.adapters.RequestAuthenticator] (default task-2) AUTHENTICATED: was cached
      13:36:46,821 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-2) AuthenticatedActionsValve.invoke http://localhost:8580/session-portal-distributable/
      13:36:46,821 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-2) Policy enforcement is disabled.
      13:36:46,841 INFO  [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] Reverse proxy will direct requests to http://localhost:8480
      13:36:46,847 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-1) adminRequest http://localhost:8580/session-portal-distributable/
      13:36:46,861 DEBUG [org.keycloak.adapters.undertow.KeycloakUndertowAccount] (default task-1) session is active
      13:36:46,861 DEBUG [org.keycloak.adapters.undertow.ServletSessionTokenStore] (default task-1) Cached account found
      13:36:46,861 DEBUG [org.keycloak.adapters.wildfly.WildflyRequestAuthenticator] (default task-1) propagate security context to wildfly
      13:36:46,861 DEBUG [org.keycloak.adapters.RequestAuthenticator] (default task-1) AUTHENTICATED: was cached
      13:36:46,861 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-1) AuthenticatedActionsValve.invoke http://localhost:8580/session-portal-distributable/
      13:36:46,862 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-1) Policy enforcement is disabled.
      13:36:46,884 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-1) adminRequest http://localhost:8580/session-portal-distributable/logout
      13:36:46,894 DEBUG [org.keycloak.adapters.undertow.KeycloakUndertowAccount] (default task-1) session is active
      13:36:46,894 DEBUG [org.keycloak.adapters.undertow.ServletSessionTokenStore] (default task-1) Cached account found
      13:36:46,894 DEBUG [org.keycloak.adapters.wildfly.WildflyRequestAuthenticator] (default task-1) propagate security context to wildfly
      13:36:46,894 DEBUG [org.keycloak.adapters.RequestAuthenticator] (default task-1) AUTHENTICATED: was cached
      13:36:46,895 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-1) AuthenticatedActionsValve.invoke http://localhost:8580/session-portal-distributable/logout
      13:36:46,895 DEBUG [org.keycloak.adapters.AuthenticatedActionsHandler] (default task-1) Policy enforcement is disabled.
      13:36:47,112 DEBUG [org.keycloak.adapters.PreAuthActionsHandler] (default task-2) adminRequest http://localhost:8580/session-portal-distributable/k_logout
      13:36:47,133 DEBUG [org.keycloak.adapters.undertow.UndertowUserSessionManagement] (default task-2) logoutHttpSessions: [Ond_jfHwUv6lfyNcZwkf1X9yUb-pDjt--8fiJCQ8]
      13:36:47,134 DEBUG [org.keycloak.adapters.undertow.UndertowUserSessionManagement] (default task-2) logoutHttpSession: Ond_jfHwUv6lfyNcZwkf1X9yUb-pDjt--8fiJCQ8
      [1552480616.880][SEVERE]: Timed out receiving message from renderer: 10.000
      [1552480616.882][SEVERE]: Timed out receiving message from renderer: -0.002
      [1552480616.885][SEVERE]: Timed out receiving message from renderer: -0.005
      13:36:56,924 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 30) WFLYUT0022: Unregistered web context: '/session-portal-distributable' from server 'default-server'
      13:36:56,941 INFO  [org.infinispan.eviction.impl.PassivationManagerImpl] (ServerService Thread Pool -- 30) ISPN000029: Passivating all entries to disk
      13:36:56,948 INFO  [org.infinispan.eviction.impl.PassivationManagerImpl] (ServerService Thread Pool -- 30) ISPN000030: Passivated 3 entries in 6 milliseconds
      13:36:56,950 INFO  [org.infinispan.CLUSTER] (ServerService Thread Pool -- 30) [Context=session-portal-distributable.war] ISPN100008: Updating cache members list [ha-node-2], topology id 6
      13:36:56,960 WARN  [org.infinispan.transaction.impl.TransactionTable] (ServerService Thread Pool -- 30) ISPN000100: Stopping, but there are 0 local transactions and 2 remote transactions that did not finish in time.
      13:36:56,965 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 30) WFLYCLINF0003: Stopped session-portal-distributable.war cache from web container
      13:36:57,004 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0028: Stopped deployment session-portal-distributable.war (runtime-name: session-portal-distributable.war) in 86ms
      13:36:57,006 INFO  [org.infinispan.CLUSTER] (ServerService Thread Pool -- 84) [Context=default-server] ISPN100008: Updating cache members list [ha-node-2], topology id 6
      13:36:57,025 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 84) WFLYCLINF0003: Stopped default-server cache from web container
      13:36:57,078 INFO  [org.jboss.as.repository] (management-handler-thread - 1) WFLYDR0002: Content removed from location /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-1/data/content/5e/ef955d308795c52be7ef4bd95a2a336bf0632d/content
      13:36:57,079 INFO  [org.jboss.as.server] (management-handler-thread - 1) WFLYSRV0009: Undeployed "session-portal-distributable.war" (runtime-name: "session-portal-distributable.war")
      13:36:57,104 INFO  [org.wildfly.extension.undertow] (ServerService Thread Pool -- 2) WFLYUT0022: Unregistered web context: '/session-portal-distributable' from server 'default-server'
      13:36:57,131 INFO  [org.infinispan.eviction.impl.PassivationManagerImpl] (ServerService Thread Pool -- 84) ISPN000029: Passivating all entries to disk
      13:36:57,144 ERROR [org.infinispan.interceptors.impl.InvocationContextInterceptor] (timeout-thread--p10-t1) ISPN000136: Error executing command GetKeyValueCommand, writing keys []: org.infinispan.util.concurrent.TimeoutException: ISPN000476: Timed out waiting for responses for request 24 from ha-node-1
      	at org.infinispan.remoting.transport.impl.MultiTargetRequest.onTimeout(MultiTargetRequest.java:167)
      	at org.infinispan.remoting.transport.AbstractRequest.call(AbstractRequest.java:87)
      	at org.infinispan.remoting.transport.AbstractRequest.call(AbstractRequest.java:22)
      	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
      	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
      	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
      	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)
      
      13:36:57,150 INFO  [org.infinispan.eviction.impl.PassivationManagerImpl] (ServerService Thread Pool -- 84) ISPN000030: Passivated 3 entries in 19 milliseconds
      13:36:57,158 ERROR [io.undertow.request] (default task-2) UT005023: Exception handling request to /session-portal-distributable/k_logout: java.lang.RuntimeException: org.infinispan.util.concurrent.TimeoutException: ISPN000476: Timed out waiting for responses for request 24 from ha-node-1
      	at org.keycloak.adapters.PreAuthActionsHandler.handleLogout(PreAuthActionsHandler.java:160)
      	at org.keycloak.adapters.PreAuthActionsHandler.handleRequest(PreAuthActionsHandler.java:81)
      	at org.keycloak.adapters.undertow.ServletPreAuthActionsHandler.handleRequest(ServletPreAuthActionsHandler.java:68)
      	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
      	at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
      	at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
      	at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
      	at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
      	at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
      	at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
      	at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
      	at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
      	at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
      	at io.undertow.server.Connectors.executeRootHandler(Connectors.java:360)
      	at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:830)
      	at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
      	at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1985)
      	at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1487)
      	at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1349)
      	at java.lang.Thread.run(Thread.java:748)
      Caused by: org.infinispan.util.concurrent.TimeoutException: ISPN000476: Timed out waiting for responses for request 24 from ha-node-1
      	at org.infinispan.interceptors.impl.AsyncInterceptorChainImpl.invoke(AsyncInterceptorChainImpl.java:259)
      	at org.infinispan.cache.impl.CacheImpl.get(CacheImpl.java:527)
      	at org.infinispan.cache.impl.DecoratedCache.get(DecoratedCache.java:539)
      	at org.infinispan.cache.impl.AbstractDelegatingCache.get(AbstractDelegatingCache.java:438)
      	at org.infinispan.cache.impl.EncoderCache.get(EncoderCache.java:660)
      	at org.infinispan.cache.impl.AbstractDelegatingCache.get(AbstractDelegatingCache.java:438)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.getValue(InfinispanSessionMetaDataFactory.java:81)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.findValue(InfinispanSessionMetaDataFactory.java:71)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.findValue(InfinispanSessionMetaDataFactory.java:42)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionFactory.findValue(InfinispanSessionFactory.java:59)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionFactory.findValue(InfinispanSessionFactory.java:37)
      	at org.wildfly.clustering.web.infinispan.session.InfinispanSessionManager.findSession(InfinispanSessionManager.java:209)
      	at org.wildfly.clustering.web.undertow.session.DistributableSessionManager.getSession(DistributableSessionManager.java:164)
      	at org.keycloak.adapters.undertow.UndertowUserSessionManagement.getSessionById(UndertowUserSessionManagement.java:72)
      	at org.keycloak.adapters.undertow.UndertowUserSessionManagement.logoutSession(UndertowUserSessionManagement.java:61)
      	at org.keycloak.adapters.undertow.UndertowUserSessionManagement.logoutHttpSessions(UndertowUserSessionManagement.java:55)
      	at org.keycloak.adapters.undertow.SessionManagementBridge.logoutHttpSessions(SessionManagementBridge.java:45)
      	at org.keycloak.adapters.PreAuthActionsHandler.handleLogout(PreAuthActionsHandler.java:151)
      	... 24 more
      Caused by: org.infinispan.util.concurrent.TimeoutException: ISPN000476: Timed out waiting for responses for request 24 from ha-node-1
      	at org.infinispan.remoting.transport.impl.MultiTargetRequest.onTimeout(MultiTargetRequest.java:167)
      	at org.infinispan.remoting.transport.AbstractRequest.call(AbstractRequest.java:87)
      	at org.infinispan.remoting.transport.AbstractRequest.call(AbstractRequest.java:22)
      	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
      	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
      	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
      	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
      	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
      	... 1 more
      	Suppressed: org.infinispan.util.logging.TraceException
      		at org.infinispan.interceptors.impl.SimpleAsyncInvocationStage.get(SimpleAsyncInvocationStage.java:41)
      		at org.infinispan.interceptors.impl.AsyncInterceptorChainImpl.invoke(AsyncInterceptorChainImpl.java:250)
      		at org.infinispan.cache.impl.CacheImpl.get(CacheImpl.java:527)
      		at org.infinispan.cache.impl.DecoratedCache.get(DecoratedCache.java:539)
      		at org.infinispan.cache.impl.AbstractDelegatingCache.get(AbstractDelegatingCache.java:438)
      		at org.infinispan.cache.impl.EncoderCache.get(EncoderCache.java:660)
      		at org.infinispan.cache.impl.AbstractDelegatingCache.get(AbstractDelegatingCache.java:438)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.getValue(InfinispanSessionMetaDataFactory.java:81)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.findValue(InfinispanSessionMetaDataFactory.java:71)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionMetaDataFactory.findValue(InfinispanSessionMetaDataFactory.java:42)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionFactory.findValue(InfinispanSessionFactory.java:59)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionFactory.findValue(InfinispanSessionFactory.java:37)
      		at org.wildfly.clustering.web.infinispan.session.InfinispanSessionManager.findSession(InfinispanSessionManager.java:209)
      		at org.wildfly.clustering.web.undertow.session.DistributableSessionManager.getSession(DistributableSessionManager.java:164)
      		at org.keycloak.adapters.undertow.UndertowUserSessionManagement.getSessionById(UndertowUserSessionManagement.java:72)
      		at org.keycloak.adapters.undertow.UndertowUserSessionManagement.logoutSession(UndertowUserSessionManagement.java:61)
      		at org.keycloak.adapters.undertow.UndertowUserSessionManagement.logoutHttpSessions(UndertowUserSessionManagement.java:55)
      		at org.keycloak.adapters.undertow.SessionManagementBridge.logoutHttpSessions(SessionManagementBridge.java:45)
      		at org.keycloak.adapters.PreAuthActionsHandler.handleLogout(PreAuthActionsHandler.java:151)
      		at org.keycloak.adapters.PreAuthActionsHandler.handleRequest(PreAuthActionsHandler.java:81)
      		at org.keycloak.adapters.undertow.ServletPreAuthActionsHandler.handleRequest(ServletPreAuthActionsHandler.java:68)
      		at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
      		at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
      		at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
      		at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
      		at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
      		at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
      		at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
      		at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
      		at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      		at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      		at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      		at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      		at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
      		at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
      		at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
      		at io.undertow.server.Connectors.executeRootHandler(Connectors.java:360)
      		at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:830)
      		at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
      		at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1985)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1487)
      		at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1349)
      		... 1 more
      
      13:36:57,200 ERROR [org.infinispan.commons.tx.TransactionImpl] (default task-1) ISPN000926: afterCompletion() failed for SynchronizationAdapter{localTransaction=LocalTransaction{remoteLockedNodes=[ha-node-1, ha-node-2], isMarkedForRollback=false, lockedKeys=[SessionAccessMetaDataKey(Ond_jfHwUv6lfyNcZwkf1X9yUb-pDjt--8fiJCQ8)], backupKeyLocks=[SessionCreationMetaDataKey(Ond_jfHwUv6lfyNcZwkf1X9yUb-pDjt--8fiJCQ8), SessionAttributesKey(Ond_jfHwUv6lfyNcZwkf1X9yUb-pDjt--8fiJCQ8)], topologyId=5, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@a} org.infinispan.transaction.synchronization.SynchronizationAdapter@29: org.infinispan.IllegalLifecycleStateException: Cannot wire or start components while the registry is not running
      	at org.infinispan.factories.impl.BasicComponentRegistryImpl.prepareWrapperChange(BasicComponentRegistryImpl.java:610)
      	at org.infinispan.factories.impl.BasicComponentRegistryImpl.wireWrapper(BasicComponentRegistryImpl.java:158)
      	at org.infinispan.factories.impl.BasicComponentRegistryImpl$ComponentWrapper.wire(BasicComponentRegistryImpl.java:736)
      	at org.infinispan.factories.impl.BasicComponentRegistryImpl$ComponentWrapper.running(BasicComponentRegistryImpl.java:712)
      	at org.infinispan.transaction.impl.TransactionCoordinator.commit(TransactionCoordinator.java:148)
      	at org.infinispan.transaction.impl.TransactionTable.afterCompletion(TransactionTable.java:861)
      	at org.infinispan.transaction.synchronization.SynchronizationAdapter.afterCompletion(SynchronizationAdapter.java:33)
      	at org.infinispan.commons.tx.TransactionImpl.notifyAfterCompletion(TransactionImpl.java:506)
      	at org.infinispan.commons.tx.TransactionImpl.runCommit(TransactionImpl.java:338)
      	at org.infinispan.commons.tx.TransactionImpl.commit(TransactionImpl.java:110)
      	at org.wildfly.clustering.ee.infinispan.InfinispanBatch.close(InfinispanBatch.java:97)
      	at org.wildfly.clustering.web.undertow.session.DistributableSession.requestDone(DistributableSession.java:87)
      	at io.undertow.servlet.spec.ServletContextImpl.updateSessionAccessTime(ServletContextImpl.java:945)
      	at io.undertow.servlet.spec.HttpServletResponseImpl.responseDone(HttpServletResponseImpl.java:579)
      	at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:346)
      	at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
      	at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
      	at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
      	at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
      	at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
      	at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1502)
      	at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
      	at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
      	at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
      	at io.undertow.server.Connectors.executeRootHandler(Connectors.java:360)
      	at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:830)
      	at org.jboss.threads.ContextClassLoaderSavingRunnable.run(ContextClassLoaderSavingRunnable.java:35)
      	at org.jboss.threads.EnhancedQueueExecutor.safeRun(EnhancedQueueExecutor.java:1985)
      	at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.doRunTask(EnhancedQueueExecutor.java:1487)
      	at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1378)
      	at java.lang.Thread.run(Thread.java:748)
      
      13:37:07,172 WARN  [org.infinispan.transaction.impl.TransactionTable] (ServerService Thread Pool -- 84) ISPN000100: Stopping, but there are 1 local transactions and 0 remote transactions that did not finish in time.
      13:37:07,176 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 84) WFLYCLINF0003: Stopped session-portal-distributable.war cache from web container
      13:37:07,190 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 2) WFLYCLINF0003: Stopped default-server cache from web container
      13:37:07,202 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) WFLYSRV0028: Stopped deployment session-portal-distributable.war (runtime-name: session-portal-distributable.war) in 10101ms
      13:37:07,226 INFO  [org.jboss.as.repository] (management-handler-thread - 1) WFLYDR0002: Content removed from location /home/mhajas/work/keycloak/testsuite/integration-arquillian/tests/base/target/containers/app-server-wildfly/standalone-ha-node-2/data/content/b9/b496785c1aabe81e413027ed93d8fb72066da7/content
      13:37:07,227 INFO  [org.jboss.as.server] (management-handler-thread - 1) WFLYSRV0009: Undeployed "session-portal-distributable.war" (runtime-name: "session-portal-distributable.war")
      Mar 13, 2019 1:37:07 PM org.jboss.arquillian.container.test.impl.client.container.ClientContainerController stop
      INFO: Manual stopping of a server instance
      13:37:07,235 INFO  [org.jboss.as.server] (management-handler-thread - 1) WFLYSRV0236: Suspending server with no timeout.
      13:37:07,236 INFO  [org.jboss.as.ejb3] (management-handler-thread - 1) WFLYEJB0493: EJB subsystem suspension complete
      13:37:07,237 INFO  [org.jboss.as.server] (Management Triggered Shutdown) WFLYSRV0241: Shutting down in response to management operation 'shutdown'
      13:37:07,246 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-3) ISPN000080: Disconnecting JGroups channel ejb
      13:37:07,247 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 30) MODCLUSTER000002: Initiating mod_cluster shutdown
      13:37:07,248 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-8) ISPN000080: Disconnecting JGroups channel ejb
      13:37:07,252 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-6) WFLYUT0008: Undertow AJP listener ajp suspending
      13:37:07,252 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0019: Host default-host stopping
      13:37:07,253 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0008: Undertow HTTPS listener https suspending
      13:37:07,253 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-1) ISPN000080: Disconnecting JGroups channel ejb
      13:37:07,253 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0007: Undertow HTTPS listener https stopped, was bound to 127.0.0.1:8743
      13:37:07,254 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) WFLYJCA0010: Unbound data source [java:jboss/datasources/ExampleDS]
      13:37:07,253 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-4) WFLYMAIL0002: Unbound mail session [java:jboss/mail/Default]
      13:37:07,256 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-6) WFLYUT0007: Undertow AJP listener ajp stopped, was bound to 127.0.0.1:8309
      13:37:07,262 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-3) WFLYUT0008: Undertow HTTP listener default suspending
      13:37:07,264 INFO  [org.infinispan.CLUSTER] (ServerService Thread Pool -- 84) [Context=client-mappings] ISPN100008: Updating cache members list [ha-node-2], topology id 6
      13:37:07,264 INFO  [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-2) WFLYJCA0019: Stopped Driver service with driver-name = h2
      13:37:07,266 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-3) WFLYUT0007: Undertow HTTP listener default stopped, was bound to 127.0.0.1:8380
      13:37:07,270 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0004: Undertow 2.0.15.Final stopping
      13:37:07,287 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 84) WFLYCLINF0003: Stopped client-mappings cache from ejb container
      13:37:07,290 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-2) ISPN000080: Disconnecting JGroups channel ejb
      13:37:07,312 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN000094: Received new cluster view for channel ejb: [ha-node-2|2] (1) [ha-node-2]
      13:37:07,318 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN100001: Node ha-node-1 left the cluster
      13:37:07,321 INFO  [org.jboss.as] (MSC service thread 1-1) WFLYSRV0050: WildFly Full 15.0.1.Final (WildFly Core 7.0.0.Final) stopped in 80ms
      13:37:07,322 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN000094: Received new cluster view for channel ejb: [ha-node-2|2] (1) [ha-node-2]
      13:37:07,323 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN100001: Node ha-node-1 left the cluster
      13:37:07,325 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN000094: Received new cluster view for channel ejb: [ha-node-2|2] (1) [ha-node-2]
      13:37:07,326 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN100001: Node ha-node-1 left the cluster
      13:37:07,328 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN000094: Received new cluster view for channel ejb: [ha-node-2|2] (1) [ha-node-2]
      13:37:07,329 INFO  [org.infinispan.CLUSTER] (thread-4,null,null) ISPN100001: Node ha-node-1 left the cluster
      13:37:07,341 INFO  [org.infinispan.CLUSTER] (stateTransferExecutor-thread--p18-t12) [Context=client-mappings] ISPN100007: After merge (or coordinator change), recovered members [ha-node-2] with topology id 7
      Mar 13, 2019 1:37:07 PM org.jboss.arquillian.container.test.impl.client.container.ClientContainerController stop
      INFO: Manual stopping of a server instance
      13:37:07,673 INFO  [org.jboss.as.server] (management-handler-thread - 1) WFLYSRV0236: Suspending server with no timeout.
      13:37:07,675 INFO  [org.jboss.as.ejb3] (management-handler-thread - 1) WFLYEJB0493: EJB subsystem suspension complete
      13:37:07,676 INFO  [org.jboss.as.server] (Management Triggered Shutdown) WFLYSRV0241: Shutting down in response to management operation 'shutdown'
      13:37:07,684 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-4) ISPN000080: Disconnecting JGroups channel ejb
      13:37:07,688 INFO  [org.jboss.modcluster] (ServerService Thread Pool -- 84) MODCLUSTER000002: Initiating mod_cluster shutdown
      13:37:07,688 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-8) ISPN000080: Disconnecting JGroups channel ejb
      13:37:07,694 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0008: Undertow AJP listener ajp suspending
      13:37:07,695 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-8) WFLYUT0008: Undertow HTTPS listener https suspending
      13:37:07,696 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-6) WFLYUT0019: Host default-host stopping
      13:37:07,697 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-8) WFLYUT0007: Undertow HTTPS listener https stopped, was bound to 127.0.0.1:8843
      13:37:07,697 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0007: Undertow AJP listener ajp stopped, was bound to 127.0.0.1:8409
      13:37:07,697 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-3) ISPN000080: Disconnecting JGroups channel ejb
      13:37:07,700 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-5) WFLYMAIL0002: Unbound mail session [java:jboss/mail/Default]
      13:37:07,701 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-8) WFLYJCA0010: Unbound data source [java:jboss/datasources/ExampleDS]
      13:37:07,705 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0008: Undertow HTTP listener default suspending
      13:37:07,705 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0007: Undertow HTTP listener default stopped, was bound to 127.0.0.1:8480
      13:37:07,706 INFO  [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0004: Undertow 2.0.15.Final stopping
      13:37:07,712 INFO  [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-8) WFLYJCA0019: Stopped Driver service with driver-name = h2
      13:37:07,716 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 87) WFLYCLINF0003: Stopped client-mappings cache from ejb container
      13:37:07,718 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (MSC service thread 1-7) ISPN000080: Disconnecting JGroups channel ejb
      13:37:07,735 INFO  [org.jboss.as] (MSC service thread 1-3) WFLYSRV0050: WildFly Full 15.0.1.Final (WildFly Core 7.0.0.Final) stopped in 55ms
      13:37:08,087 INFO  [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] removing test realms after test method
      13:37:08,088 INFO  [org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest] removing realm: demo
      
      org.openqa.selenium.TimeoutException: timeout
        (Session info: chrome=72.0.3626.96)
        (Driver info: chromedriver=2.46.628388 (4a34a70827ac54148e092aafb70504c4ea7ae926),platform=Linux 4.18.19-100.fc27.x86_64 x86_64) (WARNING: The server did not provide any stacktrace information)
      Command duration or timeout: 0 milliseconds
      Build info: version: '3.14.0', revision: 'aacccce0', time: '2018-08-02T20:19:58.91Z'
      System info: host: 'localhost.localdomain', ip: '127.0.0.1', os.name: 'Linux', os.arch: 'amd64', os.version: '4.18.19-100.fc27.x86_64', java.version: '1.8.0_161'
      Driver info: org.openqa.selenium.chrome.ChromeDriver
      Capabilities {acceptInsecureCerts: true, acceptSslCerts: true, applicationCacheEnabled: false, browserConnectionEnabled: false, browserName: chrome, chrome: {chromedriverVersion: 2.46.628388 (4a34a70827ac54..., userDataDir: /tmp/.org.chromium.Chromium...}, cssSelectorsEnabled: true, databaseEnabled: false, goog:chromeOptions: {debuggerAddress: localhost:39049}, handlesAlerts: true, hasTouchScreen: false, javascriptEnabled: true, locationContextEnabled: true, mobileEmulationEnabled: false, nativeEvents: true, networkConnectionEnabled: false, pageLoadStrategy: normal, platform: LINUX, platformName: LINUX, proxy: Proxy(), rotatable: false, setWindowRect: true, strictFileInteractability: false, takesHeapSnapshot: true, takesScreenshot: true, timeouts: {implicit: 0, pageLoad: 300000, script: 30000}, unexpectedAlertBehaviour: ignore, unhandledPromptBehavior: ignore, version: 72.0.3626.96, webStorageEnabled: true}
      Session ID: 8952e6a38726a9235f140dfc7fdc9519
      
      	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
      	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
      	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
      	at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
      	at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:214)
      	at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:166)
      	at org.openqa.selenium.remote.http.JsonHttpResponseCodec.reconstructValue(JsonHttpResponseCodec.java:40)
      	at org.openqa.selenium.remote.http.AbstractHttpResponseCodec.decode(AbstractHttpResponseCodec.java:80)
      	at org.openqa.selenium.remote.http.AbstractHttpResponseCodec.decode(AbstractHttpResponseCodec.java:44)
      	at org.openqa.selenium.remote.HttpCommandExecutor.execute(HttpCommandExecutor.java:158)
      	at org.openqa.selenium.remote.service.DriverCommandExecutor.execute(DriverCommandExecutor.java:83)
      	at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:548)
      	at org.openqa.selenium.remote.RemoteWebDriver.get(RemoteWebDriver.java:276)
      	at org.openqa.selenium.remote.RemoteWebDriver$RemoteNavigation.to(RemoteWebDriver.java:853)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.graphene.proxy.GrapheneProxyHandler.invokeReal(GrapheneProxyHandler.java:129)
      	at org.jboss.arquillian.graphene.proxy.GrapheneContextualHandler$1.invoke(GrapheneContextualHandler.java:169)
      	at org.jboss.arquillian.graphene.proxy.GrapheneContextualHandler$2.call(GrapheneContextualHandler.java:241)
      	at org.jboss.arquillian.graphene.context.BrowserActions.performAction(BrowserActions.java:62)
      	at org.jboss.arquillian.graphene.proxy.GrapheneContextualHandler.invoke(GrapheneContextualHandler.java:237)
      	at com.sun.proxy.$Proxy130.to(Unknown Source)
      	at org.keycloak.testsuite.adapter.servlet.cluster.OIDCAdapterClusterTest.testSuccessfulLoginAndProgrammaticLogout(OIDCAdapterClusterTest.java:134)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
      	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
      	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
      	at org.jboss.arquillian.junit.Arquillian$8$1.invokeMethod(Arquillian.java:325)
      	at org.jboss.arquillian.junit.MethodInvoker$1.invoke(MethodInvoker.java:18)
      	at org.jboss.arquillian.container.test.impl.execution.LocalTestExecuter.execute(LocalTestExecuter.java:57)
      	at org.keycloak.testsuite.arquillian.ModelTestExecutor.execute(ModelTestExecutor.java:49)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:103)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:90)
      	at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:133)
      	at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:105)
      	at org.jboss.arquillian.core.impl.EventImpl.fire(EventImpl.java:62)
      	at org.jboss.arquillian.container.test.impl.execution.ClientTestExecuter.execute(ClientTestExecuter.java:50)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:103)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:90)
      	at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createContext(ContainerEventController.java:128)
      	at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createTestContext(ContainerEventController.java:118)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:95)
      	at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:116)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:95)
      	at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:83)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:95)
      	at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:69)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:95)
      	at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:133)
      	at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.test(EventTestRunnerAdaptor.java:122)
      	at org.jboss.arquillian.junit.MethodInvoker.invoke(MethodInvoker.java:15)
      	at org.jboss.arquillian.junit.Arquillian$8.evaluate(Arquillian.java:332)
      	at org.jboss.arquillian.junit.Arquillian$4.evaluate(Arquillian.java:204)
      	at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:350)
      	at org.jboss.arquillian.junit.Arquillian.access$200(Arquillian.java:54)
      	at org.jboss.arquillian.junit.Arquillian$5.evaluate(Arquillian.java:215)
      	at org.jboss.arquillian.junit.Arquillian$7$1.invoke(Arquillian.java:279)
      	at org.jboss.arquillian.container.test.impl.execution.ClientBeforeAfterLifecycleEventExecuter.execute(ClientBeforeAfterLifecycleEventExecuter.java:88)
      	at org.jboss.arquillian.container.test.impl.execution.ClientBeforeAfterLifecycleEventExecuter.on(ClientBeforeAfterLifecycleEventExecuter.java:66)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.invokeObservers(EventContextImpl.java:103)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:90)
      	at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createContext(ContainerEventController.java:128)
      	at org.jboss.arquillian.container.test.impl.client.ContainerEventController.createBeforeContext(ContainerEventController.java:114)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:95)
      	at org.jboss.arquillian.test.impl.TestContextHandler.createTestContext(TestContextHandler.java:116)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:95)
      	at org.jboss.arquillian.test.impl.TestContextHandler.createClassContext(TestContextHandler.java:83)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:95)
      	at org.jboss.arquillian.test.impl.TestContextHandler.createSuiteContext(TestContextHandler.java:69)
      	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      	at java.lang.reflect.Method.invoke(Method.java:498)
      	at org.jboss.arquillian.core.impl.ObserverImpl.invoke(ObserverImpl.java:86)
      	at org.jboss.arquillian.core.impl.EventContextImpl.proceed(EventContextImpl.java:95)
      	at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:133)
      	at org.jboss.arquillian.core.impl.ManagerImpl.fire(ManagerImpl.java:105)
      	at org.jboss.arquillian.test.impl.EventTestRunnerAdaptor.fireCustomLifecycle(EventTestRunnerAdaptor.java:142)
      	at org.jboss.arquillian.junit.Arquillian$7.evaluate(Arquillian.java:273)
      	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325)
      	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
      	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
      	at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
      	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
      	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
      	at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
      	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
      	at org.jboss.arquillian.junit.Arquillian$2.evaluate(Arquillian.java:166)
      	at org.jboss.arquillian.junit.Arquillian.multiExecute(Arquillian.java:350)
      	at org.jboss.arquillian.junit.Arquillian.access$200(Arquillian.java:54)
      	at org.jboss.arquillian.junit.Arquillian$3.evaluate(Arquillian.java:177)
      	at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
      	at org.jboss.arquillian.junit.Arquillian.run(Arquillian.java:115)
      	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
      	at com.intellij.junit4.JUnit4IdeaTestRunner.startRunnerWithArgs(JUnit4IdeaTestRunner.java:68)
      	at com.intellij.rt.execution.junit.IdeaTestRunner$Repeater.startRunnerWithArgs(IdeaTestRunner.java:47)
      	at com.intellij.rt.execution.junit.JUnitStarter.prepareStreamsAndStart(JUnitStarter.java:242)
      	at com.intellij.rt.execution.junit.JUnitStarter.main(JUnitStarter.java:70)
      
      
      

        Gliffy Diagrams

          Attachments

            Issue Links

              Activity

                People

                • Assignee:
                  hmlnarik Hynek Mlnařík
                  Reporter:
                  mitko Michal Hajas
                • Votes:
                  0 Vote for this issue
                  Watchers:
                  3 Start watching this issue

                  Dates

                  • Created:
                    Updated:
                    Resolved: