@Observes SecurityConfigurationEvent: deployment get stuck at "Bootstrapping PicketLink"
jairosantos Jan 5, 2015 12:07 PMHaving 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