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 >
