Thanks Tauren! I'm working on it now...
On Mon, May 17, 2010 at 8:11 PM, Tauren Mills <[email protected]> wrote: > Les, > > I just created this JIRA: > https://issues.apache.org/jira/browse/SHIRO-164 > Thanks for getting right to it! > Tauren > > On Mon, May 17, 2010 at 6:53 PM, Les Hazlewood <[email protected]> > wrote: >> >> Hi Tauren, >> >> For web-initiated interaction, you should not be seeing these messages: >> >> DEBUG - DefaultWebSessionManager - No request or response bound to >> the thread. Session ID cookie cannot be removed. This could occur in >> a web application that also services non web clients (e.g. RMI >> remoting). >> DEBUG - DefaultWebSessionManager - Request or response object is not >> bound to the thread. Assuming this session start activity is due to a >> non web request (possible in a web application that also services non >> web clients. >> >> The request/response pair should be available at all times to >> web-related components. >> >> Please open a Jira issue and I'll get on it right away. I'm actually >> working on a related bit of code that should clear this up today. >> >> Thanks! >> >> Les >> >> On Mon, May 17, 2010 at 6:40 PM, Tauren Mills <[email protected]> wrote: >> > I am getting an UnknownSessionException, but things still seem to be >> > working. At first I thought it was because I had stopped jetty and then >> > started it back up. But I'm getting these same exceptions every time I >> > click >> > something that causes those same 2 AJAX requests to be made. Is this >> > the >> > expected behavior? Full logs: >> > INFO - log - Started >> > [email protected]:8080 >> > INFO - log - Started >> > [email protected]:8443 >> > TRACE - OncePerRequestFilter - Filter 'null' not yet executed. >> > Executing now. >> > TRACE - OncePerRequestFilter - Filter 'null' not yet executed. >> > Executing now. >> > TRACE - DefaultSecurityManager - Context already contains a >> > SecurityManager instance. Returning. >> > DEBUG - SimpleCookie - Found string value >> > [dc51b21d-5e34-4407-b2e2-74a2bebb7b19] from Cookie [JSESSIONID] >> > DEBUG - ctValidatingSessionManager - No sessionValidationScheduler set. >> > Attempting to create default instance. >> > TRACE - ctValidatingSessionManager - Created default >> > SessionValidationScheduler instance of type >> > >> > [org.apache.shiro.session.mgt.ExecutorServiceSessionValidationScheduler]. >> > INFO - ctValidatingSessionManager - Enabling session validation >> > scheduler... >> > TRACE - DefaultSecurityManager - Context already contains a >> > SecurityManager instance. Returning. >> > DEBUG - SimpleCookie - Found string value >> > [dc51b21d-5e34-4407-b2e2-74a2bebb7b19] from Cookie [JSESSIONID] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [dc51b21d-5e34-4407-b2e2-74a2bebb7b19] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [dc51b21d-5e34-4407-b2e2-74a2bebb7b19] >> > DEBUG - DefaultWebSessionManager - No request or response bound to the >> > thread. Session ID cookie cannot be removed. This could occur in a web >> > application that also services non web clients (e.g. RMI remoting). >> > DEBUG - DefaultSecurityManager - Resolved SubjectContext context >> > session >> > is invalid. Ignoring and creating an anonymous (session-less) Subject >> > instance. >> > org.apache.shiro.session.UnknownSessionException: There is no session >> > with >> > id [dc51b21d-5e34-4407-b2e2-74a2bebb7b19] >> > at >> > >> > org.apache.shiro.session.mgt.eis.AbstractSessionDAO.readSession(AbstractSessionDAO.java:171) >> > at >> > >> > org.apache.shiro.session.mgt.DefaultSessionManager.retrieveSessionFromDataSource(DefaultSessionManager.java:220) >> > at >> > >> > org.apache.shiro.session.mgt.DefaultSessionManager.retrieveSession(DefaultSessionManager.java:216) >> > at >> > >> > org.apache.shiro.session.mgt.AbstractValidatingSessionManager.doGetSession(AbstractValidatingSessionManager.java:122) >> > at >> > >> > org.apache.shiro.session.mgt.AbstractSessionManager.getSession(AbstractSessionManager.java:238) >> > at >> > >> > org.apache.shiro.web.session.DefaultWebSessionManager.getSession(DefaultWebSessionManager.java:178) >> > at >> > >> > org.apache.shiro.web.DefaultWebSecurityManager.resolveContextSession(DefaultWebSecurityManager.java:153) >> > at >> > >> > org.apache.shiro.mgt.DefaultSecurityManager.resolveSession(DefaultSecurityManager.java:385) >> > at >> > >> > org.apache.shiro.mgt.DefaultSecurityManager.createSubject(DefaultSecurityManager.java:336) >> > at >> > org.apache.shiro.subject.Subject$Builder.buildSubject(Subject.java:819) >> > at >> > >> > org.apache.shiro.web.subject.WebSubject$Builder.buildWebSubject(WebSubject.java:90) >> > at >> > >> > org.apache.shiro.web.servlet.AbstractShiroFilter.createSubject(AbstractShiroFilter.java:202) >> > at >> > >> > org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:269) >> > at >> > >> > org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:83) >> > at >> > >> > org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:237) >> > at >> > >> > org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:167) >> > at >> > >> > org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1088) >> > at >> > >> > org.springframework.orm.hibernate3.support.OpenSessionInViewFilter.doFilterInternal(OpenSessionInViewFilter.java:198) >> > at >> > >> > org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76) >> > at >> > >> > org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1088) >> > at >> > org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360) >> > at >> > >> > org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216) >> > at >> > org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181) >> > at >> > org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:729) >> > at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405) >> > at >> > org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) >> > at org.mortbay.jetty.Server.handle(Server.java:324) >> > at >> > org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505) >> > at >> > >> > org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:843) >> > at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:647) >> > at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211) >> > at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380) >> > at >> > >> > org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:228) >> > at >> > >> > org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:488) >> > TRACE - DefaultSecurityManager - No identity (PrincipalCollection) >> > found >> > in the context. Looking for a remembered identity. >> > DEBUG - SimpleCookie - Found string value >> > >> > [p9PNM4iDmrfPhCG7w+2rX1l3HtwrvkzNfF7JBrMfpVwo9VdlAFFu+WDrtvChLPv+PG6lb3NDO6a4WxOcjWG7YaWX2BsDAUXnzgFcXHTvWp9LlNiTdl4pF/EbekI60SYCon77WWNsSqxSO+QwuleKkCIHf7Jm5FpMc+RIb7UGZFN6/LFLtolNIp5jZ17pBDymYyKzDjYP88U7SXU3A59kJLQW6eJ5kAw5b7MyNZqsDblHGQh6/6NJ0nK7KfFS5nTgs2X05C3mbXE2jsuCtJwBb/+8tuFAqAiVVQNlMDV7az7vxR/2Uh230Fw7J9WD9x/HFiBlrttygFiy/F5jwevf8h5bMqPEugpUpOEo8JQ3FhYEZQCloNBBH2bQ0yJCIYaKF5hNgIC2j2fV0sXd5L9iB7zchFbCX07hTBgeC5tXeqrGR5qi2RvBXaWaGzqSml3eL87lFW4urZHC1M9tP3UCpaYQjM/ZQMp1T7Qu9pa/VzmcGQkDAQthNkCUYrgnYRpl4KUMyply/HVlys02x+krAPq7lRDgXZY4sFgGcZ+LL/Tjqmi1sLkvMN1HQ9v3Nl0DsEDBazr7w/Hc5a4D2hi7Uw==] >> > from Cookie [rememberMe] >> > TRACE - CookieRememberMeManager - Acquired Base64 encoded identity >> > >> > [p9PNM4iDmrfPhCG7w+2rX1l3HtwrvkzNfF7JBrMfpVwo9VdlAFFu+WDrtvChLPv+PG6lb3NDO6a4WxOcjWG7YaWX2BsDAUXnzgFcXHTvWp9LlNiTdl4pF/EbekI60SYCon77WWNsSqxSO+QwuleKkCIHf7Jm5FpMc+RIb7UGZFN6/LFLtolNIp5jZ17pBDymYyKzDjYP88U7SXU3A59kJLQW6eJ5kAw5b7MyNZqsDblHGQh6/6NJ0nK7KfFS5nTgs2X05C3mbXE2jsuCtJwBb/+8tuFAqAiVVQNlMDV7az7vxR/2Uh230Fw7J9WD9x/HFiBlrttygFiy/F5jwevf8h5bMqPEugpUpOEo8JQ3FhYEZQCloNBBH2bQ0yJCIYaKF5hNgIC2j2fV0sXd5L9iB7zchFbCX07hTBgeC5tXeqrGR5qi2RvBXaWaGzqSml3eL87lFW4urZHC1M9tP3UCpaYQjM/ZQMp1T7Qu9pa/VzmcGQkDAQthNkCUYrgnYRpl4KUMyply/HVlys02x+krAPq7lRDgXZY4sFgGcZ+LL/Tjqmi1sLkvMN1HQ9v3Nl0DsEDBazr7w/Hc5a4D2hi7Uw==] >> > TRACE - CookieRememberMeManager - Base64 decoded byte array length: >> > 448 >> > bytes. >> > TRACE - JcaCipherService - Attempting to decrypt incoming byte >> > array of length 432 >> > DEBUG - DefaultWebSessionManager - No request or response bound to the >> > thread. Session ID cookie cannot be removed. This could occur in a web >> > application that also services non web clients (e.g. RMI remoting). >> > DEBUG - DefaultSecurityManager - Resolved SubjectContext context >> > session >> > is invalid. Ignoring and creating an anonymous (session-less) Subject >> > instance. >> > org.apache.shiro.session.UnknownSessionException: There is no session >> > with >> > id [dc51b21d-5e34-4407-b2e2-74a2bebb7b19] >> > at >> > >> > org.apache.shiro.session.mgt.eis.AbstractSessionDAO.readSession(AbstractSessionDAO.java:171) >> > at >> > >> > org.apache.shiro.session.mgt.DefaultSessionManager.retrieveSessionFromDataSource(DefaultSessionManager.java:220) >> > at >> > >> > org.apache.shiro.session.mgt.DefaultSessionManager.retrieveSession(DefaultSessionManager.java:216) >> > at >> > >> > org.apache.shiro.session.mgt.AbstractValidatingSessionManager.doGetSession(AbstractValidatingSessionManager.java:122) >> > at >> > >> > org.apache.shiro.session.mgt.AbstractSessionManager.getSession(AbstractSessionManager.java:238) >> > at >> > >> > org.apache.shiro.web.session.DefaultWebSessionManager.getSession(DefaultWebSessionManager.java:178) >> > at >> > >> > org.apache.shiro.web.DefaultWebSecurityManager.resolveContextSession(DefaultWebSecurityManager.java:153) >> > at >> > >> > org.apache.shiro.mgt.DefaultSecurityManager.resolveSession(DefaultSecurityManager.java:385) >> > at >> > >> > org.apache.shiro.mgt.DefaultSecurityManager.createSubject(DefaultSecurityManager.java:336) >> > at >> > org.apache.shiro.subject.Subject$Builder.buildSubject(Subject.java:819) >> > at >> > >> > org.apache.shiro.web.subject.WebSubject$Builder.buildWebSubject(WebSubject.java:90) >> > at >> > >> > org.apache.shiro.web.servlet.AbstractShiroFilter.createSubject(AbstractShiroFilter.java:202) >> > at >> > >> > org.apache.shiro.web.servlet.AbstractShiroFilter.doFilterInternal(AbstractShiroFilter.java:269) >> > at >> > >> > org.apache.shiro.web.servlet.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:83) >> > at >> > >> > org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:237) >> > at >> > >> > org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:167) >> > at >> > >> > org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1088) >> > at >> > >> > org.springframework.orm.hibernate3.support.OpenSessionInViewFilter.doFilterInternal(OpenSessionInViewFilter.java:198) >> > at >> > >> > org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:76) >> > at >> > >> > org.mortbay.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1088) >> > at >> > org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:360) >> > at >> > >> > org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216) >> > at >> > org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181) >> > at >> > org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:729) >> > at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405) >> > at >> > org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) >> > at org.mortbay.jetty.Server.handle(Server.java:324) >> > at >> > org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505) >> > at >> > >> > org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:843) >> > at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:647) >> > at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211) >> > at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380) >> > at >> > >> > org.mortbay.jetty.bio.SocketConnector$Connection.run(SocketConnector.java:228) >> > at >> > >> > org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:488) >> > TRACE - DefaultSecurityManager - No identity (PrincipalCollection) >> > found >> > in the context. Looking for a remembered identity. >> > DEBUG - SimpleCookie - Found string value >> > >> > [p9PNM4iDmrfPhCG7w+2rX1l3HtwrvkzNfF7JBrMfpVwo9VdlAFFu+WDrtvChLPv+PG6lb3NDO6a4WxOcjWG7YaWX2BsDAUXnzgFcXHTvWp9LlNiTdl4pF/EbekI60SYCon77WWNsSqxSO+QwuleKkCIHf7Jm5FpMc+RIb7UGZFN6/LFLtolNIp5jZ17pBDymYyKzDjYP88U7SXU3A59kJLQW6eJ5kAw5b7MyNZqsDblHGQh6/6NJ0nK7KfFS5nTgs2X05C3mbXE2jsuCtJwBb/+8tuFAqAiVVQNlMDV7az7vxR/2Uh230Fw7J9WD9x/HFiBlrttygFiy/F5jwevf8h5bMqPEugpUpOEo8JQ3FhYEZQCloNBBH2bQ0yJCIYaKF5hNgIC2j2fV0sXd5L9iB7zchFbCX07hTBgeC5tXeqrGR5qi2RvBXaWaGzqSml3eL87lFW4urZHC1M9tP3UCpaYQjM/ZQMp1T7Qu9pa/VzmcGQkDAQthNkCUYrgnYRpl4KUMyply/HVlys02x+krAPq7lRDgXZY4sFgGcZ+LL/Tjqmi1sLkvMN1HQ9v3Nl0DsEDBazr7w/Hc5a4D2hi7Uw==] >> > from Cookie [rememberMe] >> > TRACE - CookieRememberMeManager - Acquired Base64 encoded identity >> > >> > [p9PNM4iDmrfPhCG7w+2rX1l3HtwrvkzNfF7JBrMfpVwo9VdlAFFu+WDrtvChLPv+PG6lb3NDO6a4WxOcjWG7YaWX2BsDAUXnzgFcXHTvWp9LlNiTdl4pF/EbekI60SYCon77WWNsSqxSO+QwuleKkCIHf7Jm5FpMc+RIb7UGZFN6/LFLtolNIp5jZ17pBDymYyKzDjYP88U7SXU3A59kJLQW6eJ5kAw5b7MyNZqsDblHGQh6/6NJ0nK7KfFS5nTgs2X05C3mbXE2jsuCtJwBb/+8tuFAqAiVVQNlMDV7az7vxR/2Uh230Fw7J9WD9x/HFiBlrttygFiy/F5jwevf8h5bMqPEugpUpOEo8JQ3FhYEZQCloNBBH2bQ0yJCIYaKF5hNgIC2j2fV0sXd5L9iB7zchFbCX07hTBgeC5tXeqrGR5qi2RvBXaWaGzqSml3eL87lFW4urZHC1M9tP3UCpaYQjM/ZQMp1T7Qu9pa/VzmcGQkDAQthNkCUYrgnYRpl4KUMyply/HVlys02x+krAPq7lRDgXZY4sFgGcZ+LL/Tjqmi1sLkvMN1HQ9v3Nl0DsEDBazr7w/Hc5a4D2hi7Uw==] >> > TRACE - CookieRememberMeManager - Base64 decoded byte array length: >> > 448 >> > bytes. >> > TRACE - JcaCipherService - Attempting to decrypt incoming byte >> > array of length 432 >> > DEBUG - MemberServiceImpl - Finding member by id: 2 >> > DEBUG - MemberServiceImpl - Finding member by id: 2 >> > DEBUG - DefaultSecurityManager - Found remembered >> > PrincipalCollection. >> > Adding to the context to be used for subject construction by the >> > SubjectFactory. >> > DEBUG - DefaultSecurityManager - Found remembered >> > PrincipalCollection. >> > Adding to the context to be used for subject construction by the >> > SubjectFactory. >> > TRACE - DefaultSecurityManager - No session in the current subject >> > context. One will be created to persist principals [2] Doing this >> > prevents >> > unnecessary repeated RememberMe operations since an identity has been >> > discovered. >> > TRACE - DefaultSecurityManager - No session in the current subject >> > context. One will be created to persist principals [2] Doing this >> > prevents >> > unnecessary repeated RememberMe operations since an identity has been >> > discovered. >> > TRACE - DefaultSessionManager - Creating session for host 127.0.0.1 >> > DEBUG - DefaultSessionManager - Creating new EIS record for new >> > session >> > instance [org.apache.shiro.session.mgt.SimpleSession,id=null] >> > TRACE - DefaultSessionManager - Creating session for host 127.0.0.1 >> > DEBUG - DefaultSessionManager - Creating new EIS record for new >> > session >> > instance [org.apache.shiro.session.mgt.SimpleSession,id=null] >> > DEBUG - DefaultWebSessionManager - Request or response object is not >> > bound >> > to the thread. Assuming this session start activity is due to a non web >> > request (possible in a web application that also services non web >> > clients. >> > DEBUG - DefaultWebSessionManager - Request or response object is not >> > bound >> > to the thread. Assuming this session start activity is due to a non web >> > request (possible in a web application that also services non web >> > clients. >> > DEBUG - DefaultSecurityManager - Created session with id >> > f090c5a5-8461-4411-a1b4-468a5637eb55 to retain discovered principals 2 >> > DEBUG - DefaultSecurityManager - Created session with id >> > d406a455-494b-4194-bf57-7f47fc4ccd50 to retain discovered principals 2 >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - DelegatingSubject - attempting to get session; create = >> > false; session is null = false; session has id = true >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - AbstractShiroFilter - No FilterChain configured for the >> > current request. Using the default. >> > TRACE - DelegatingSubject - attempting to get session; create = >> > false; session is null = false; session has id = true >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - AbstractShiroFilter - No FilterChain configured for the >> > current request. Using the default. >> > TRACE - DelegatingSubject - attempting to get session; create = >> > false; session is null = false; session has id = true >> > TRACE - DelegatingSubject - attempting to get session; create = >> > false; session is null = false; session has id = true >> > TRACE - DelegatingSubject - attempting to get session; create = >> > false; session is null = false; session has id = true >> > TRACE - DelegatingSubject - attempting to get session; create = >> > false; session is null = false; session has id = true >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [f090c5a5-8461-4411-a1b4-468a5637eb55] >> > TRACE - ctValidatingSessionManager - Attempting to retrieve session with >> > id >> > [d406a455-494b-4194-bf57-7f47fc4ccd50] >> > TRACE - ShiroFilterFactoryBean - Ignoring non-Filter bean >> > 'assignorsResource' >> > TRACE - ShiroFilterFactoryBean - Ignoring non-Filter bean >> > 'assignorsResource' >> > DEBUG - GameJsonServiceImpl - >> > >> > ----------------------------------------------------------------------------- >> > DEBUG - GameJsonServiceImpl - Getting Recommened Officiators >> > DEBUG - MemberServiceImpl - Getting current member >> > DEBUG - MemberServiceImpl - Get member by id: 2 >> > DEBUG - GameJsonServiceImpl - >> > >> > ----------------------------------------------------------------------------- >> > DEBUG - GameJsonServiceImpl - Getting Games Managed By Member >> > DEBUG - MemberServiceImpl - Getting current member >> > DEBUG - MemberServiceImpl - Get member by id: 2 >> > DEBUG - MemberServiceImpl - Returning member: >> > com.sprtz.model.mem...@3792ca4c[id=2,Name=beck] >> > DEBUG - MemberServiceImpl - Returning member: >> > com.sprtz.model.mem...@1c9ac1e4[id=2,Name=beck] >> > DEBUG - GameJsonServiceImpl - Get Games managed by member: >> > com.sprtz.model.mem...@3792ca4c[id=2,Name=beck] >> > Is there anything in these logs that should concern me? Specifically, >> > the >> > following entries make me wonder if everything is working correctly: >> > DEBUG - DefaultWebSessionManager - No request or response bound to the >> > thread. Session ID cookie cannot be removed. This could occur in a web >> > application that also services non web clients (e.g. RMI remoting). >> > DEBUG - DefaultSecurityManager - Resolved SubjectContext context >> > session >> > is invalid. Ignoring and creating an anonymous (session-less) Subject >> > instance. >> > org.apache.shiro.session.UnknownSessionException: There is no session >> > with >> > id [dc51b21d-5e34-4407-b2e2-74a2bebb7b19] >> > TRACE - DefaultSecurityManager - No session in the current subject >> > context. One will be created to persist principals [2] Doing this >> > prevents >> > unnecessary repeated RememberMe operations since an identity has been >> > discovered. >> > TRACE - DefaultSessionManager - Creating session for host 127.0.0.1 >> > DEBUG - DefaultSessionManager - Creating new EIS record for new >> > session >> > instance [org.apache.shiro.session.mgt.SimpleSession,id=null] >> > DEBUG - DefaultWebSessionManager - Request or response object is not >> > bound >> > to the thread. Assuming this session start activity is due to a non web >> > request (possible in a web application that also services non web >> > clients. >> > Thanks again, >> > Tauren >> > >> > >> > On Mon, May 17, 2010 at 6:18 PM, Tauren Mills <[email protected]> wrote: >> >> >> >> I just updated to the 165 version and it seems to now be working >> >> reliably! >> >> Very nice... Will report back if I have futher problems. What was it >> >> that >> >> Kalle fixed, what issue number so I can take a look? >> >> Thanks, >> >> Tauren >> >> >> >> On Mon, May 17, 2010 at 6:08 PM, Tauren Mills <[email protected]> >> >> wrote: >> >>> >> >>> I'm using this snapshot, which I updated to only about 5 or 6 hours >> >>> ago: >> >>> shiro-core-1.0-incubating-20100515.200134-162.jar dated 5/17/2010 at >> >>> 12:08:40PM PDT >> >>> I'll try updating again and see if it helps. >> >>> Tauren >> >>> >> >>> On Mon, May 17, 2010 at 6:00 PM, Les Hazlewood <[email protected]> >> >>> wrote: >> >>>> >> >>>> Hi Tauren - quick question - are you using the very latest snapshot? >> >>>> Kalle committed a fix to the codebase today that helped fix a bug >> >>>> related to intermittent failures. >> >>>> >> >>>> On Mon, May 17, 2010 at 5:36 PM, Tauren Mills <[email protected]> >> >>>> wrote: >> >>>> > I'm having serious problems with the latest maven snapshot. I've >> >>>> > spent >> >>>> > a >> >>>> > while trying to figure out what could be going on. Any suggestions >> >>>> > on >> >>>> > where >> >>>> > to look would be appreciated. >> >>>> > Once a user is logged In to my application, all additional requests >> >>>> > are AJAX >> >>>> > requests. In many situations, multiple AJAX calls occur at the same >> >>>> > time. >> >>>> > This was working perfectly with a Shiro release from late 2009, >> >>>> > but >> >>>> > ever >> >>>> > since updating to the latest Shiro snapshot, I've been unable to >> >>>> > get >> >>>> > it >> >>>> > working. >> >>>> > The problem is that some requests find the current user and return >> >>>> > valid >> >>>> > data, while other requests cannot locate the current user. It >> >>>> > occurs >> >>>> > randomly with no consistency that I can determine. A request might >> >>>> > not >> >>>> > work >> >>>> > one time, but the next time the same request will work. >> >>>> > The following are the the logs when two concurrent AJAX requests >> >>>> > successfully locate the current user and return valid data. One >> >>>> > request is >> >>>> > "Getting Games Managed By Member", and the other request is >> >>>> > "Getting >> >>>> > Recommended Officiators": >> >>>> > TRACE - OncePerRequestFilter - Filter 'null' not yet >> >>>> > executed. >> >>>> > Executing now. >> >>>> > TRACE - DefaultSecurityManager - Context already contains a >> >>>> > SecurityManager instance. Returning. >> >>>> > TRACE - DefaultSecurityManager - No session found in context. >> >>>> > Looking >> >>>> > for a session id to resolve in to a session. >> >>>> > DEBUG - SimpleCookie - Found string value >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] from Cookie [JSESSIONID] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - AbstractShiroFilter - No FilterChain configured for >> >>>> > the >> >>>> > current request. Using the default. >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ShiroFilterFactoryBean - Ignoring non-Filter bean >> >>>> > 'assignorsResource' >> >>>> > DEBUG - GameJsonServiceImpl - >> >>>> > >> >>>> > >> >>>> > ----------------------------------------------------------------------------- >> >>>> > DEBUG - GameJsonServiceImpl - Getting Games Managed By >> >>>> > Member >> >>>> > DEBUG - MemberServiceImpl - Getting current member >> >>>> > DEBUG - MemberServiceImpl - Get member by id: 2 >> >>>> > DEBUG - MemberServiceImpl - Returning member: >> >>>> > com.sprtz.model.mem...@3796fcd1[id=2,Name=beck] >> >>>> > DEBUG - GameJsonServiceImpl - Get Games managed by member: >> >>>> > com.sprtz.model.mem...@3796fcd1[id=2,Name=beck] >> >>>> > TRACE - OncePerRequestFilter - Filter 'null' not yet >> >>>> > executed. >> >>>> > Executing now. >> >>>> > TRACE - DefaultSecurityManager - Context already contains a >> >>>> > SecurityManager instance. Returning. >> >>>> > TRACE - DefaultSecurityManager - No session found in context. >> >>>> > Looking >> >>>> > for a session id to resolve in to a session. >> >>>> > DEBUG - SimpleCookie - Found string value >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] from Cookie [JSESSIONID] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - AbstractShiroFilter - No FilterChain configured for >> >>>> > the >> >>>> > current request. Using the default. >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ShiroFilterFactoryBean - Ignoring non-Filter bean >> >>>> > 'assignorsResource' >> >>>> > DEBUG - GameJsonServiceImpl - >> >>>> > >> >>>> > >> >>>> > ----------------------------------------------------------------------------- >> >>>> > DEBUG - GameJsonServiceImpl - Getting Recommened Officiators >> >>>> > DEBUG - MemberServiceImpl - Getting current member >> >>>> > DEBUG - MemberServiceImpl - Get member by id: 2 >> >>>> > DEBUG - MemberServiceImpl - Returning member: >> >>>> > com.sprtz.model.mem...@1afb3ad9[id=2,Name=beck] >> >>>> > The following logs show when "Getting Games Managed By Member" has >> >>>> > failed to >> >>>> > find the current user, but "Getting Recommeded Officiators" has >> >>>> > found >> >>>> > the >> >>>> > current user. >> >>>> > TRACE - OncePerRequestFilter - Filter 'null' not yet >> >>>> > executed. >> >>>> > Executing now. >> >>>> > TRACE - DefaultSecurityManager - Context already contains a >> >>>> > SecurityManager instance. Returning. >> >>>> > TRACE - DefaultSecurityManager - No session found in context. >> >>>> > Looking >> >>>> > for a session id to resolve in to a session. >> >>>> > DEBUG - SimpleCookie - Found string value >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] from Cookie [JSESSIONID] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - AbstractShiroFilter - No FilterChain configured for >> >>>> > the >> >>>> > current request. Using the default. >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ShiroFilterFactoryBean - Ignoring non-Filter bean >> >>>> > 'assignorsResource' >> >>>> > TRACE - OncePerRequestFilter - Filter 'null' not yet >> >>>> > executed. >> >>>> > Executing now. >> >>>> > TRACE - DefaultSecurityManager - Context already contains a >> >>>> > SecurityManager instance. Returning. >> >>>> > TRACE - DefaultSecurityManager - No session found in context. >> >>>> > Looking >> >>>> > for a session id to resolve in to a session. >> >>>> > DEBUG - SimpleCookie - Found string value >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] from Cookie [JSESSIONID] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - AbstractShiroFilter - No FilterChain configured for >> >>>> > the >> >>>> > current request. Using the default. >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - DelegatingSubject - attempting to get session; >> >>>> > create >> >>>> > = >> >>>> > false; session is null = false; session has id = true >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ctValidatingSessionManager - Attempting to retrieve session >> >>>> > with id >> >>>> > [f752d00d-7da2-451f-b813-0c36554461a9] >> >>>> > TRACE - ShiroFilterFactoryBean - Ignoring non-Filter bean >> >>>> > 'assignorsResource' >> >>>> > DEBUG - GameJsonServiceImpl - >> >>>> > >> >>>> > >> >>>> > ----------------------------------------------------------------------------- >> >>>> > DEBUG - GameJsonServiceImpl - Getting Recommened Officiators >> >>>> > DEBUG - MemberServiceImpl - Getting current member >> >>>> > DEBUG - MemberServiceImpl - Get member by id: 2 >> >>>> > DEBUG - MemberServiceImpl - Returning member: >> >>>> > com.sprtz.model.mem...@6390403a[id=2,Name=beck] >> >>>> > DEBUG - GameJsonServiceImpl - >> >>>> > >> >>>> > >> >>>> > ----------------------------------------------------------------------------- >> >>>> > DEBUG - GameJsonServiceImpl - Getting Games Managed By >> >>>> > Member >> >>>> > DEBUG - MemberServiceImpl - Getting current member >> >>>> > TRACE - DefaultSecurityManager - Context already contains a >> >>>> > SecurityManager instance. Returning. >> >>>> > TRACE - DefaultSecurityManager - No session found in context. >> >>>> > Looking >> >>>> > for a session id to resolve in to a session. >> >>>> > TRACE - DefaultSecurityManager - No identity >> >>>> > (PrincipalCollection) >> >>>> > found >> >>>> > in the context. Looking for a remembered identity. >> >>>> > DEBUG - CookieRememberMeManager - SubjectContext argument is not >> >>>> > an >> >>>> > HTTP-aware instance. This is required to obtain a servlet request >> >>>> > and >> >>>> > response in order to retrieve the rememberMe cookie. Returning >> >>>> > immediately >> >>>> > and ignoring rememberMe operation. >> >>>> > TRACE - DefaultSecurityManager - No remembered identity found. >> >>>> > Returning original context. >> >>>> > DEBUG - MemberServiceImpl - No current member: >> >>>> > SecurityUtils.getSubject().getPrincipal() returned null >> >>>> > I'd really appreciate any pointers here. I'm stuck until I can get >> >>>> > past >> >>>> > this and may end up reverting back to a much older version of Shiro >> >>>> > and http >> >>>> > sessions. Also, I'm having troubles whether I use http sessions or >> >>>> > native >> >>>> > sessions, but the logs above are using native sessions. >> >>>> > Tauren >> >>>> > >> >>> >> >> >> > >> > > >
