7 Replies Latest reply on Jul 6, 2015 11:12 AM by F. Sola

    @Observes SecurityConfigurationEvent: deployment get stuck at "Bootstrapping PicketLink"

    Jairo S. Newbie

      Having some problems with application deployment after I added PicketLink to my project. When I add an observer to SecurityConfigurationEvent, the deployment simply stops at "PLINK002000: Bootstrapping PicketLink" phase.

       

      Steps to reproduce:

      • create an empty Maven Java EE 7 Web Application project;
      • configure CDI (add beans.xml);
      • add PicketLink 2.7.0.CR3 uber dependency;
      • add an observer to receive SecurityConfigurationEvent events;
      • run;

       

      Environment:

      • Win 7 x86;
      • WildFly 8.2.0.Final;
      • Maven Java EE 7 Web application created in NetBeans;

       

      Relevant POM part:

          <dependencyManagement>
              <dependencies>
                  <dependency>
                      <groupId>org.picketlink</groupId>
                      <artifactId>picketlink-javaee-7.0</artifactId>
                      <version>2.7.0.CR3</version>
                      <scope>import</scope>
                      <type>pom</type>
                  </dependency>
              </dependencies>
          </dependencyManagement>
      
          <dependencies>
              <dependency>
                  <groupId>javax</groupId>
                  <artifactId>javaee-web-api</artifactId>
                  <version>7.0</version>
                  <scope>provided</scope>
              </dependency>
              
              <dependency>
                  <groupId>org.picketlink</groupId>
                  <artifactId>picketlink</artifactId>
                  <scope>compile</scope>
              </dependency>
          </dependencies>
      

       

      Simple security configuration class:

      package com.mycompany.mavenproject1;
      import javax.ejb.Stateless;
      import javax.enterprise.event.Observes;
      import org.picketlink.event.SecurityConfigurationEvent;
      
      @Stateless
      public class NewClass {
          public void configure(@Observes SecurityConfigurationEvent event) {
              //even with no configuration at all, PicketLink get stuck at "Bootstrapping Picketlink" phase.
          }
      }
      

       

       

      Wildfly deployment log:

      2015-01-05 14:42:54,889 INFO  [org.jboss.as.server.deployment.scanner] (DeploymentScanner-threads - 1) JBAS015003: Found mavenproject1-1.0-SNAPSHOT.war in deployment directory. To trigger deployment create a file called mavenproject1-1.0-SNAPSHOT.war.dodeploy
      2015-01-05 14:42:54,967 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "mavenproject1-1.0-SNAPSHOT.war" (runtime-name: "mavenproject1-1.0-SNAPSHOT.war")
      2015-01-05 14:42:56,184 INFO  [org.jboss.weld.deployer] (MSC service thread 1-1) JBAS016002: Processing weld deployment mavenproject1-1.0-SNAPSHOT.war
      2015-01-05 14:42:56,715 INFO  [org.hibernate.validator.internal.util.Version] (MSC service thread 1-1) HV000001: Hibernate Validator 5.1.3.Final
      2015-01-05 14:42:56,917 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-1) JNDI bindings for session bean named NewClass in deployment unit deployment "mavenproject1-1.0-SNAPSHOT.war" are as follows:
       java:global/mavenproject1-1.0-SNAPSHOT/NewClass!com.mycompany.mavenproject1.NewClass
       java:app/mavenproject1-1.0-SNAPSHOT/NewClass!com.mycompany.mavenproject1.NewClass
       java:module/NewClass!com.mycompany.mavenproject1.NewClass
       java:global/mavenproject1-1.0-SNAPSHOT/NewClass
       java:app/mavenproject1-1.0-SNAPSHOT/NewClass
       java:module/NewClass
      2015-01-05 14:42:56,917 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-1) JNDI bindings for session bean named SecurityConfigurer in deployment unit deployment "mavenproject1-1.0-SNAPSHOT.war" are as follows:
       java:global/mavenproject1-1.0-SNAPSHOT/SecurityConfigurer!com.mycompany.mavenproject1.SecurityConfigurer
       java:app/mavenproject1-1.0-SNAPSHOT/SecurityConfigurer!com.mycompany.mavenproject1.SecurityConfigurer
       java:module/SecurityConfigurer!com.mycompany.mavenproject1.SecurityConfigurer
       java:global/mavenproject1-1.0-SNAPSHOT/SecurityConfigurer
       java:app/mavenproject1-1.0-SNAPSHOT/SecurityConfigurer
       java:module/SecurityConfigurer
      2015-01-05 14:42:57,417 INFO  [org.jboss.weld.deployer] (MSC service thread 1-4) JBAS016005: Starting Services for CDI deployment: mavenproject1-1.0-SNAPSHOT.war
      2015-01-05 14:42:57,541 INFO  [org.jboss.weld.Version] (MSC service thread 1-4) WELD-000900: 2.2.6 (Final)
      2015-01-05 14:42:57,697 INFO  [org.jboss.weld.deployer] (MSC service thread 1-4) JBAS016008: Starting weld service for deployment mavenproject1-1.0-SNAPSHOT.war
      2015-01-05 14:42:59,211 INFO  [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (ServerService Thread Pool -- 56) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
      2015-01-05 14:42:59,226 INFO  [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (ServerService Thread Pool -- 56) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
      2015-01-05 14:42:59,601 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 55) ISPN000078: Starting JGroups Channel
      2015-01-05 14:42:59,679 INFO  [stdout] (ServerService Thread Pool -- 55) 
      2015-01-05 14:42:59,679 INFO  [stdout] (ServerService Thread Pool -- 55) -------------------------------------------------------------------
      2015-01-05 14:42:59,679 INFO  [stdout] (ServerService Thread Pool -- 55) GMS: address=jairo-pc/ejb, cluster=ejb, physical address=127.0.0.1:55200
      2015-01-05 14:42:59,679 INFO  [stdout] (ServerService Thread Pool -- 55) -------------------------------------------------------------------
      2015-01-05 14:43:01,738 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 55) ISPN000094: Received new cluster view: [jairo-pc/ejb|0] (1) [jairo-pc/ejb]
      2015-01-05 14:43:01,738 INFO  [org.infinispan.remoting.transport.jgroups.JGroupsTransport] (ServerService Thread Pool -- 55) ISPN000079: Cache local address is jairo-pc/ejb, physical addresses are [127.0.0.1:55200]
      2015-01-05 14:43:01,753 INFO  [org.infinispan.factories.GlobalComponentRegistry] (ServerService Thread Pool -- 55) ISPN000128: Infinispan version: Infinispan 'Infinium' 6.0.2.Final
      2015-01-05 14:43:02,097 INFO  [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool -- 55) ISPN000031: MBeans were successfully registered to the platform MBean server.
      2015-01-05 14:43:02,128 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 55) JBAS010281: Started dist cache from ejb container
      2015-01-05 14:43:02,253 INFO  [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
      2015-01-05 14:43:02,253 INFO  [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated.
      2015-01-05 14:43:02,284 INFO  [org.infinispan.jmx.CacheJmxRegistration] (ServerService Thread Pool -- 55) ISPN000031: MBeans were successfully registered to the platform MBean server.
      2015-01-05 14:43:02,284 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 55) JBAS010281: Started mavenproject1-1.0-SNAPSHOT.war cache from ejb container
      2015-01-05 14:43:02,377 WARN  [org.jboss.weld.Event] (MSC service thread 1-1) WELD-000411: Observer method [BackedAnnotatedMethod] protected org.apache.deltaspike.core.impl.exclude.extension.ExcludeExtension.vetoBeans(@Observes ProcessAnnotatedType<Object>, BeanManager) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.
      2015-01-05 14:43:02,424 WARN  [org.jboss.weld.Event] (MSC service thread 1-1) WELD-000411: Observer method [BackedAnnotatedMethod] protected org.apache.deltaspike.core.impl.message.MessageBundleExtension.detectInterfaces(@Observes ProcessAnnotatedType<Object>) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.
      2015-01-05 14:43:02,487 WARN  [org.jboss.weld.Event] (MSC service thread 1-1) WELD-000411: Observer method [BackedAnnotatedMethod] public org.apache.deltaspike.security.impl.extension.SecurityExtension.processAnnotatedType(@Observes ProcessAnnotatedType<Object>) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.
      2015-01-05 14:43:02,502 WARN  [org.jboss.weld.Event] (MSC service thread 1-1) WELD-000411: Observer method [BackedAnnotatedMethod] org.picketlink.extension.PicketLinkExtension.vetoIdentityImplementations(@Observes ProcessAnnotatedType<Object>) receives events for all annotated types. Consider restricting events using @WithAnnotations or a generic type with bounds.
      2015-01-05 14:43:02,627 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.scope.DeltaSpikeContextExtension activated=true
      2015-01-05 14:43:02,721 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.exception.control.extension.ExceptionControlExtension activated=true
      2015-01-05 14:43:02,783 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.config.ConfigurationExtension activated=true
      2015-01-05 14:43:02,783 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.security.impl.extension.SecurityExtension activated=true
      2015-01-05 14:43:02,783 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.message.MessageBundleExtension activated=true
      2015-01-05 14:43:02,783 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.message.NamedMessageBundleInvocationHandler activated=true
      2015-01-05 14:43:02,783 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.exclude.extension.ExcludeExtension activated=true
      2015-01-05 14:43:02,783 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.exclude.CustomProjectStageBeanFilter activated=true
      2015-01-05 14:43:02,799 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.exclude.GlobalAlternative activated=true
      2015-01-05 14:43:02,799 INFO  [org.apache.deltaspike.core.util.ClassDeactivationUtils] (MSC service thread 1-1) class: org.apache.deltaspike.core.impl.jmx.MBeanExtension activated=true
      2015-01-05 14:43:04,483 INFO  [org.apache.deltaspike.core.util.ProjectStageProducer] (MSC service thread 1-1) Computed the following DeltaSpike ProjectStage: Production
      2015-01-05 14:43:07,385 INFO  [org.picketlink] (MSC service thread 1-1) PLINK002000: Bootstrapping PicketLink
      2015-01-05 14:48:07,452 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper) ARJUNA012117: TransactionReaper::check timeout for TX 0:ffffc0a80169:-583b52e0:54aabea8:9 in state  RUN
      2015-01-05 14:48:07,452 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012095: Abort of action id 0:ffffc0a80169:-583b52e0:54aabea8:9 invoked while multiple threads active within it.
      2015-01-05 14:48:07,452 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012108: CheckedAction::check - atomic action 0:ffffc0a80169:-583b52e0:54aabea8:9 aborting with 1 threads active!
      2015-01-05 14:48:07,452 WARN  [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ARJUNA012121: TransactionReaper::doCancellations worker Thread[Transaction Reaper Worker 0,5,main] successfully canceled TX 0:ffffc0a80169:-583b52e0:54aabea8:9
      2015-01-05 14:52:54,914 ERROR [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS015870: Deploy of deployment "mavenproject1-1.0-SNAPSHOT.war" was rolled back with the following failure message: "JBAS014862: Operation cancelled"
      2015-01-05 14:52:54,934 WARN  [org.jboss.as.controller.management-operation] (DeploymentScanner-threads - 2) JBAS014626: Operation was interrupted before stability could be reached
      2015-01-05 14:52:54,934 INFO  [org.infinispan.eviction.PassivationManagerImpl] (ServerService Thread Pool -- 57) ISPN000029: Passivating all entries to disk
      2015-01-05 14:52:54,934 INFO  [org.infinispan.eviction.PassivationManagerImpl] (ServerService Thread Pool -- 57) ISPN000030: Passivated 0 entries in 2 milliseconds
      2015-01-05 14:52:54,954 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 57) JBAS010282: Stopped mavenproject1-1.0-SNAPSHOT.war cache from ejb container