[NEXUS-6244] Regression: Crowd plugin does not support nested group role mapping user privileges - Sonatype JIRA

XML

Word

Printable

Details

Type: Bug
Status: Closed
Priority: Major
Resolution: Fixed
Affects Version/s: 2.7, 2.7.1
Fix Version/s: 2.7.2, 2.8
Component/s: Crowd
Labels:
None

Sprint:
Sprint 11

Description

Crowd Configuration:

Startup Atlassian Crowd 'demo" application.
Enable "Nested Groups" on the demo application Directory inside Crowd.
Create a "parent" group in the default directory.
Create a "child" group in the default directory.
Make the child group a parent group group member.
Add a "child-user" user which is a member of the "child" group only.

Nexus Configuration:

Configure Nexus to connect to the Crowd Server.
Add an external Crowd Role (Group) mapping to the Crowd "parent" group only. Give the parent group Nexus Administrator Role.
Enable Crowd Realm in Nexus Server.
Try to login as 'child-user'. Login fails.

Repeat the above with Nexus 2.6.4-02 and old Crowd plugin. Login succeeds. Old plugin apparently supported nested group role mappings.

Nexus 2.7 uses direct group mappings.

jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user com.atlassian.crowd.integration.rest.service.RestExecutor - Constructed http://localhost:8095/crowd/rest/usermanagement/1/user/group/direct?username=child-user&start-index=0&max-results=2147483647
jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user org.sonatype.nexus.apachehttpclient.Hc4ProviderImpl$1 - Connection request: [route: {}->http://localhost:8095][total kept alive: 1; route allocated: 1 of 20; total allocated: 1 of 200]
jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user org.sonatype.nexus.apachehttpclient.Hc4ProviderImpl$1 - Connection leased: [id: 6][route: {}->http://localhost:8095][total kept alive: 0; route allocated: 1 of 20; total allocated: 1 of 200]
jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user org.sonatype.nexus.apachehttpclient.Hc4ProviderBase$DefaultHttpClientImpl - Attempt 1 to execute request
jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user org.sonatype.nexus.apachehttpclient.Hc4ProviderBase$DefaultHttpClientImpl - Connection can be kept alive for 30000 MILLISECONDS
jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user org.sonatype.nexus.apachehttpclient.Hc4ProviderImpl$1 - Connection [id: 6][route: {}->http://localhost:8095] can be kept alive for 30000 MILLISECONDS
jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user org.sonatype.nexus.apachehttpclient.Hc4ProviderImpl$1 - Connection released: [id: 6][route: {}->http://localhost:8095][total kept alive: 1; route allocated: 1 of 20; total allocated: 1 of 200]
jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user org.sonatype.sisu.goodies.eventbus.internal.DefaultEventBus - Event 'org.sonatype.nexus.auth.NexusAuthorizationEvent@1795f2a7' fired
jvm 1    | 2014-02-03 10:48:49 DEBUG [esh-1-thread-7] child-user org.sonatype.nexus.feeds.record.NexusAuthorizationEventInspector - Unable to authorize user [child-user] for read(HTTP method "GET") to /nexus/service/local/authentication/login from IP Address 127.0.0.1, user agent:"n/a"
jvm 1    | 2014-02-03 10:48:49 DEBUG [qtp1211033288-71] child-user org.sonatype.nexus.security.filter.authc.NexusAuthenticationFilter - Request processing is rejected because user "child-user" lacks permissions.

Hint: Possibly should use group nested resource instead:

> curl -u demo:admin123 "http://localhost:8095/crowd/rest/usermanagement/1/user/group/direct?username=child-user&start-index=0&max-results=2147483647"
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><groups expand="group"><group name="child"><link href="http://localhost:8095/crowd/rest/usermanagement/1/group?groupname=child" rel="self"/></group></groups>

> curl -u demo:admin123 "http://localhost:8095/crowd/rest/usermanagement/1/user/group/nested?username=child-user&start-index=0&max-results=2147483647"
<?xml version="1.0" encoding="UTF-8" standalone="yes"?><groups expand="group"><group name="child"><link href="http://localhost:8095/crowd/rest/usermanagement/1/group?groupname=child" rel="self"/></group><group name="parent"><link href="http://localhost:8095/crowd/rest/usermanagement/1/group?groupname=parent" rel="self"/></group></groups>

Nexus 2.6.4-02 using same crowd setup authenticates and allows the user to login to Nexus using only the parent group mapping:

jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.web.servlet.SimpleCookie - Found 'JSESSIONID' cookie value [f0abb406-7f90-4127-9d81-94eeb7ca06c3]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.mgt.DefaultSecurityManager - Resolved SubjectContext context session is invalid.  Ignoring and creating an anonymous (session-less) Subject instance.
jvm 1    | org.apache.shiro.session.UnknownSessionException: There is no session with id [f0abb406-7f90-4127-9d81-94eeb7ca06c3]
jvm 1    | 	at org.apache.shiro.session.mgt.eis.AbstractSessionDAO.readSession(AbstractSessionDAO.java:170) ~[shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.session.mgt.eis.CachingSessionDAO.readSession(CachingSessionDAO.java:261) ~[shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.session.mgt.DefaultSessionManager.retrieveSessionFromDataSource(DefaultSessionManager.java:236) ~[shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.session.mgt.DefaultSessionManager.retrieveSession(DefaultSessionManager.java:222) ~[shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.session.mgt.AbstractValidatingSessionManager.doGetSession(AbstractValidatingSessionManager.java:118) ~[shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.session.mgt.AbstractNativeSessionManager.lookupSession(AbstractNativeSessionManager.java:108) ~[shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.session.mgt.AbstractNativeSessionManager.getSession(AbstractNativeSessionManager.java:100) ~[shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.mgt.SessionsSecurityManager.getSession(SessionsSecurityManager.java:125) ~[shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.mgt.DefaultSecurityManager.resolveContextSession(DefaultSecurityManager.java:456) [shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.mgt.DefaultSecurityManager.resolveSession(DefaultSecurityManager.java:442) [shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.mgt.DefaultSecurityManager.createSubject(DefaultSecurityManager.java:338) [shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.subject.Subject$Builder.buildSubject(Subject.java:846) [shiro-core-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.web.subject.WebSubject$Builder.buildWebSubject(WebSubject.java:148) [shiro-web-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.web.servlet.AbstractShiroFilter.createSubject(AbstractShiroFilter.java:292) [shiro-web-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:359) [shiro-web-1.2.2.jar:1.2.2]
jvm 1    | 	at org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:125) [shiro-web-1.2.2.jar:1.2.2]
jvm 1    | 	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:120) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at org.sonatype.nexus.web.NexusGuiceFilter$MultiFilterChain.doFilter(NexusGuiceFilter.java:88) [nexus-web-utils-2.6.4-02.jar:2.6.4-02]
jvm 1    | 	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:89) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at com.yammer.metrics.web.WebappMetricsFilter.doFilter(WebappMetricsFilter.java:76) [metrics-web-2.2.0.jar:na]
jvm 1    | 	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:82) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:120) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at org.sonatype.nexus.web.NexusGuiceFilter$MultiFilterChain.doFilter(NexusGuiceFilter.java:88) [nexus-web-utils-2.6.4-02.jar:2.6.4-02]
jvm 1    | 	at com.google.inject.servlet.FilterChainInvocation.doFilter(FilterChainInvocation.java:89) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at com.google.inject.servlet.ManagedFilterPipeline.dispatch(ManagedFilterPipeline.java:120) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at org.sonatype.nexus.web.NexusGuiceFilter$MultiFilterChain.doFilter(NexusGuiceFilter.java:88) [nexus-web-utils-2.6.4-02.jar:2.6.4-02]
jvm 1    | 	at org.sonatype.nexus.web.NexusGuiceFilter$MultiFilterPipeline.dispatch(NexusGuiceFilter.java:58) [nexus-web-utils-2.6.4-02.jar:2.6.4-02]
jvm 1    | 	at com.google.inject.servlet.GuiceFilter$1.call(GuiceFilter.java:132) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at com.google.inject.servlet.GuiceFilter$1.call(GuiceFilter.java:129) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at com.google.inject.servlet.GuiceFilter$Context.call(GuiceFilter.java:206) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at com.google.inject.servlet.GuiceFilter.doFilter(GuiceFilter.java:129) [guice-servlet-3.1.4.jar:3.1.4]
jvm 1    | 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) [jetty-servlet-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) [jetty-servlet-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:557) [jetty-security-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1075) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) [jetty-servlet-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:255) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.Server.handle(Server.java:370) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:949) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1011) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:644) [jetty-http-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235) [jetty-http-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82) [jetty-server-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:668) [jetty-io-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52) [jetty-io-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) [jetty-util-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) [jetty-util-8.1.11.v20130520.jar:8.1.11.v20130520]
jvm 1    | 	at java.lang.Thread.run(Thread.java:744) [na:1.7.0_51]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.sonatype.nexus.security.filter.authc.NexusSecureHttpAuthenticationFilter - Using authorization header from request
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.sonatype.nexus.security.filter.authc.NexusSecureHttpAuthenticationFilter - Using authorization header from request
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.sonatype.nexus.security.filter.authc.NexusSecureHttpAuthenticationFilter - Using authorization header from request
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.sonatype.nexus.security.filter.authc.NexusSecureHttpAuthenticationFilter - Using authorization header from request
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.atlassian.crowd.integration.service.soap.client.SecurityServerClientImpl - Connection URL: http://localhost:8095/crowd/services/SecurityServer
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.atlassian.crowd.integration.service.soap.client.SecurityServerClientImpl - Using existing token: bZxdBqXDFFbLvqVg8h5XdQ00
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.transport.http.HttpTransport - Creating new channel for uri: urn:xfire:transport:http:139143983011310827534615
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.handler.OutMessageSender@6a180a8e to phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapActionOutHandler@4aa5d885 to phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapSerializerHandler@4e2a2a0c to phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapSerializerHandler in phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase policy
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapActionOutHandler in phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.handler.OutMessageSender in phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/authentication/AuthenticatedToken.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /java/lang/String.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/authentication/AuthenticatedToken.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /java/lang/String.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Received message to http://localhost:8095/crowd/services/SecurityServer
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.client.CorrelatorHandler@574d906 to phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.ReadHeadersHandler@78163159 to phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.ValidateHeadersHandler@790207a6 to phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.handler.LocateBindingHandler@7444615a to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapActionInHandler@35539758 to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapBodyHandler@2f52dc2 to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.ReadHeadersHandler in phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.client.CorrelatorHandler in phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Correlating context with ID 139143983011311-754143493
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Found correlated context with ID 139143983011311-754143493
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.client.ClientReceiveHandler@3a541bb6 to phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.handler.LocateBindingHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapBodyHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapActionInHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase policy
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.ValidateHeadersHandler in phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.client.ClientReceiveHandler in phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.XFireProxy - Result [YYXfDbcyJtPH08TdOKz0nQ00]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.atlassian.crowd.integration.service.soap.client.SecurityServerClientImpl - Connection URL: http://localhost:8095/crowd/services/SecurityServer
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.atlassian.crowd.integration.service.soap.client.SecurityServerClientImpl - Using existing token: bZxdBqXDFFbLvqVg8h5XdQ00
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.transport.http.HttpTransport - Creating new channel for uri: urn:xfire:transport:http:1391439830203121860127842
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.handler.OutMessageSender@7187b0e4 to phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapActionOutHandler@4aa5d885 to phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapSerializerHandler@4e2a2a0c to phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapSerializerHandler in phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase policy
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapActionOutHandler in phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.handler.OutMessageSender in phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/authentication/AuthenticatedToken.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /java/lang/String.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/authentication/AuthenticatedToken.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /java/lang/String.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Received message to http://localhost:8095/crowd/services/SecurityServer
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.client.CorrelatorHandler@69d3c639 to phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.ReadHeadersHandler@78163159 to phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.ValidateHeadersHandler@790207a6 to phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.handler.LocateBindingHandler@7444615a to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapActionInHandler@35539758 to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapBodyHandler@2f52dc2 to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.ReadHeadersHandler in phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.client.CorrelatorHandler in phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Correlating context with ID 139143983020313848732040
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Found correlated context with ID 139143983020313848732040
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.client.ClientReceiveHandler@4e7afe76 to phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.handler.LocateBindingHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapBodyHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/soap/SOAPEntity.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /long.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/soap/SOAPEntity.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /boolean.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/soap/SOAPEntity.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/soap/SOAPAttribute.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /java/lang/String.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/soap/SOAPAttribute.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/soap/SOAPEntity.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /java/lang/String.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/soap/SOAPEntity.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /long.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /com/atlassian/crowd/integration/soap/SOAPEntity.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.aegis.XMLClassMetaInfoManager - Mapping file : /java/lang/String.aegis.xml not found.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapActionInHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase policy
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.ValidateHeadersHandler in phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.client.ClientReceiveHandler in phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.XFireProxy - Result [com.atlassian.crowd.integration.soap.SOAPPrincipal@799a061c[ID=-1,name=child-user,directoryID=32769,active=true,conception=<null>,lastModified=<null>,attributes={com.atlassian.crowd.integration.soap.SOAPAttribute@6f0756a2[name=givenName,values={child}],com.atlassian.crowd.integration.soap.SOAPAttribute@4ac8dea0[name=sn,values={user}],com.atlassian.crowd.integration.soap.SOAPAttribute@499000bb[name=mail,values={fake@sonatype.com}],com.atlassian.crowd.integration.soap.SOAPAttribute@d201ab8[name=displayName,values={child user}]}]]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.realm.AuthenticatingRealm - Looked up AuthenticationInfo [child-user] from doGetAuthenticationInfo
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.realm.AuthenticatingRealm - AuthenticationInfo caching is disabled for info [child-user].  Submitted token: [org.apache.shiro.authc.UsernamePasswordToken - child-user, rememberMe=false (127.0.0.1)].
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.authc.credential.SimpleCredentialsMatcher - Performing credentials equality check for tokenCredentials of type [[C and accountCredentials of type [[C]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.authc.credential.SimpleCredentialsMatcher - Both credentials arguments can be easily converted to byte arrays.  Performing array equals comparison
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.authc.AbstractAuthenticator - Authentication successful for token [org.apache.shiro.authc.UsernamePasswordToken - child-user, rememberMe=false (127.0.0.1)].  Returned account [child-user]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.subject.support.DefaultSubjectContext - No SecurityManager available in subject context map.  Falling back to SecurityUtils.getSecurityManager() lookup.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.subject.support.DefaultSubjectContext - No SecurityManager available in subject context map.  Falling back to SecurityUtils.getSecurityManager() lookup.
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.session.mgt.DefaultSessionManager - Creating new EIS record for new session instance [org.apache.shiro.session.mgt.SimpleSession,id=null]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  net.sf.ehcache.store.disk.Segment - put added 0 on heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  net.sf.ehcache.store.disk.Segment - put added 0 on heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  net.sf.ehcache.store.disk.Segment - put updated, deleted 0 on heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault removed 0 from heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.web.servlet.SimpleCookie - Added HttpServletResponse Cookie [JSESSIONID=33bdd299-7d4c-47d5-83af-92693338704b; Path=/nexus; HttpOnly]
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault added 0 on disk
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault installation failed, deleted 0 from heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  net.sf.ehcache.store.disk.Segment - put added 0 on heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault installation failed deleted 0 from disk
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  net.sf.ehcache.store.disk.Segment - put updated, deleted 0 on heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  net.sf.ehcache.store.disk.Segment - put added 0 on heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  net.sf.ehcache.store.disk.Segment - put updated, deleted 0 on heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.web.servlet.SimpleCookie - Added HttpServletResponse Cookie [rememberMe=deleteMe; Path=/nexus; Max-Age=0; Expires=Sun, 02-Feb-2014 15:03:50 GMT]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.apache.shiro.mgt.AbstractRememberMeManager - AuthenticationToken did not indicate RememberMe is requested.  RememberMe functionality will not be executed for corresponding account.
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault removed 0 from heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.sonatype.sisu.goodies.eventbus.internal.DefaultEventBus - Event 'NexusAuthenticationEvent{userId='child-user',remoteIp='127.0.0.1',successful=true}' fired
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault added 0 on disk
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault installation failed, deleted 0 from heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault installation failed deleted 0 from disk
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault removed 0 from heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault added 0 on disk
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault installation failed, deleted 0 from heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [nxevthost-1-thread-5] child-user org.sonatype.nexus.feeds.record.NexusAuthenticationEventInspector - Successfully authenticated user [child-user] from IP address 127.0.0.1
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault installation failed deleted 0 from disk
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.sonatype.security.usermanagement.xml.SecurityXmlUserManager - No user role mapping found for user: child-user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.sonatype.security.crowd.client.DefaultCrowdRoleManager - Looking up role list for username: child-user
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault removed 0 from heap
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.atlassian.crowd.integration.service.soap.client.SecurityServerClientImpl - Connection URL: http://localhost:8095/crowd/services/SecurityServer
jvm 1    | 2014-02-03 11:03:50 DEBUG [shiro-activeSessionCache.data]  net.sf.ehcache.store.disk.Segment - fault added 0 on disk
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.atlassian.crowd.integration.service.soap.client.SecurityServerClientImpl - Using existing token: bZxdBqXDFFbLvqVg8h5XdQ00
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.transport.http.HttpTransport - Creating new channel for uri: urn:xfire:transport:http:1391439830246141534270986
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.handler.OutMessageSender@1df0ca1a to phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapActionOutHandler@4aa5d885 to phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapSerializerHandler@4e2a2a0c to phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapSerializerHandler in phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase policy
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapActionOutHandler in phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.handler.OutMessageSender in phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Received message to http://localhost:8095/crowd/services/SecurityServer
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.client.CorrelatorHandler@917d52e to phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.ReadHeadersHandler@78163159 to phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.ValidateHeadersHandler@790207a6 to phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.handler.LocateBindingHandler@7444615a to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapActionInHandler@35539758 to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapBodyHandler@2f52dc2 to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.ReadHeadersHandler in phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.client.CorrelatorHandler in phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Correlating context with ID 1391439830246151440575786
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Found correlated context with ID 1391439830246151440575786
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.client.ClientReceiveHandler@7bacc3ab to phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.handler.LocateBindingHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapBodyHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapActionInHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase policy
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.ValidateHeadersHandler in phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.client.ClientReceiveHandler in phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.XFireProxy - Result [[Ljava.lang.String;@632f883a]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.atlassian.crowd.integration.service.soap.client.SecurityServerClientImpl - Connection URL: http://localhost:8095/crowd/services/SecurityServer
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.atlassian.crowd.integration.service.soap.client.SecurityServerClientImpl - Using existing token: bZxdBqXDFFbLvqVg8h5XdQ00
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.transport.http.HttpTransport - Creating new channel for uri: urn:xfire:transport:http:139143983027516-140179385
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.handler.OutMessageSender@6f396b3a to phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapActionOutHandler@4aa5d885 to phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapSerializerHandler@4e2a2a0c to phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapSerializerHandler in phase post-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase policy
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapActionOutHandler in phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.handler.OutMessageSender in phase send
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Received message to http://localhost:8095/crowd/services/SecurityServer
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.client.CorrelatorHandler@1777a5de to phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.ReadHeadersHandler@78163159 to phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.ValidateHeadersHandler@790207a6 to phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.handler.LocateBindingHandler@7444615a to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapActionInHandler@35539758 to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.soap.handler.SoapBodyHandler@2f52dc2 to phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase transport
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.ReadHeadersHandler in phase parse
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.client.CorrelatorHandler in phase pre-dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Correlating context with ID 1391439830275171916966652
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.Client - Found correlated context with ID 1391439830275171916966652
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - adding handler org.codehaus.xfire.client.ClientReceiveHandler@302a9ddb to phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.handler.LocateBindingHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapBodyHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.SoapActionInHandler in phase dispatch
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase policy
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.soap.handler.ValidateHeadersHandler in phase pre-invoke
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.handler.HandlerPipeline - Invoking handler org.codehaus.xfire.client.ClientReceiveHandler in phase service
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  org.codehaus.xfire.client.XFireProxy - Result [[Lcom.atlassian.crowd.integration.soap.SOAPNestableGroup;@2b4a2a54]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77]  com.sonatype.security.crowd.client.DefaultCrowdRoleManager - Obtained role list: [child, parent]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77] child-user org.sonatype.nexus.web.NexusRestletServlet - Processing: GET /nexus/service/local/authentication/login?_dc=1391439830107 (http://localhost:8264/nexus/service/local/authentication/login)
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77] child-user org.sonatype.security.DefaultSecuritySystem - User: 'child-user' was not found in: 'default' 
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77] child-user com.sonatype.security.crowd.client.DefaultCrowdRoleManager - Looking up role list for username: child-user
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77] child-user com.sonatype.security.crowd.client.DefaultCrowdRoleManager - Obtained role list: [child, parent]
jvm 1    | 2014-02-03 11:03:50 DEBUG [qtp253747277-77] child-user org.sonatype.security.usermanagement.xml.SecurityXmlUserManager - No user role mapping found for user: child-user

Attachments

Activity

People

Assignee:: Peter Lynch

Reporter:: Peter Lynch

Last Updated By:: Peter Lynch

Votes:: 1 Vote for this issue

Watchers:: 3 Start watching this issue

Dates

Created:: 02/03/14 03:19 PM

Updated:: 02/24/14 07:34 PM

Resolved:: 02/10/14 09:28 PM

Date of First Response:: 06/Feb/14 4:01 PM