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 >>>> > >>> >> > >
