[Sakai Jira] Created: (KERN-1140) Searching all groups causes Exception with strong hints of a synchronization issue.
Alan Berg (JIRA)
-----------------------------------------------------------------------------------
Key: KERN-1140
URL: http://jira.sakaiproject.org/browse/KERN-1140
Project: Nakamura
Issue Type: Bug
Components: System - other
Affects Versions: 0.8
Environment: DEBUG: Nakamura Version: hudson-Nakamura-431 | UX Code Timestamp: Fri Sep 24 2010 12:49:52 GMT+0200
DOC mod date: 09/24/2010 12:49:09 | PLACE: search_groups.html#q=*&facet=all
Their are 2300 users created through curl and 1 by the create_account.html
Reporter: Alan Berg
Priority: Blocker
Fix For: 0.9
Attachments: Screenshot.png
On Searching All groups through a GUI the Searching ... text remains and No results returned. There is a 500 status in the log and Exception thrown.
What is strange and gives rise to a Blocker until explained is that the Exception is discussing the user who before has done the test. For example:
Login in as A do the search, log out Login as B in a second browser type to do the search. The Exception mentions A (key=A)
24.09.2010 13:37:45.870 *ERROR* [77.248.94.54 [1285328265714] GET /var/search/groups.json HTTP/1.1] org.apache.sling.engine.impl.SlingMainServlet service: Uncaught Throwable java.lang.IllegalStateException: Entry.next=null, data[removeIndex]=anonymous=true previous=anonymous=true key=amb value=true size=1000 maxSize=1000 Please check that your keys are immutable, and that you have used synchronization properly. If so, then please report this to commo...@jakarta.apache.org as a bug.
at org.apache.commons.collections.map.LRUMap.reuseMapping(LRUMap.java:301)
at org.apache.commons.collections.map.LRUMap.addMapping(LRUMap.java:267)
at org.apache.commons.collections.map.AbstractHashedMap.put(AbstractHashedMap.java:284)
at org.apache.jackrabbit.core.security.authorization.acl.DynamicACLProvider.hasPrincipal(DynamicACLProvider.java:412)
at org.apache.jackrabbit.core.security.authorization.acl.DynamicACLProvider$Entries.collectEntriesFromAcl(DynamicACLProvider.java:203)
at org.apache.jackrabbit.core.security.authorization.acl.DynamicACLProvider$Entries.collectEntries(DynamicACLProvider.java:156)
at org.apache.jackrabbit.core.security.authorization.acl.DynamicACLProvider$Entries.collectEntries(DynamicACLProvider.java:161)
at org.apache.jackrabbit.core.security.authorization.acl.DynamicACLProvider$Entries.<init>(DynamicACLProvider.java:146)
at org.apache.jackrabbit.core.security.authorization.acl.DynamicACLProvider$Entries.<init>(DynamicACLProvider.java:131)
at org.apache.jackrabbit.core.security.authorization.acl.DynamicACLProvider.retrieveResultEntries(DynamicACLProvider.java:124)
at org.apache.jackrabbit.core.security.authorization.acl.ACLProvider$AclPermissions.buildResult(ACLProvider.java:400)
at org.apache.jackrabbit.core.security.authorization.AbstractCompiledPermissions.getResult(AbstractCompiledPermissions.java:50)
at org.apache.jackrabbit.core.security.authorization.AbstractCompiledPermissions.grants(AbstractCompiledPermissions.java:86)
at org.apache.jackrabbit.core.security.DefaultAccessManager.isGranted(DefaultAccessManager.java:246)
at org.apache.sling.jcr.jackrabbit.server.impl.security.PluggableDefaultAccessManager.isGranted(PluggableDefaultAccessManager.java:124)
at org.apache.jackrabbit.core.security.DefaultAccessManager.isGranted(DefaultAccessManager.java:234)
at org.apache.sling.jcr.jackrabbit.server.impl.security.PluggableDefaultAccessManager.isGranted(PluggableDefaultAccessManager.java:117)
at org.apache.jackrabbit.core.query.lucene.QueryResultImpl.isAccessGranted(QueryResultImpl.java:375)
at org.apache.jackrabbit.core.query.lucene.QueryResultImpl.collectScoreNodes(QueryResultImpl.java:353)
at org.apache.jackrabbit.core.query.lucene.QueryResultImpl.getResults(QueryResultImpl.java:310)
at org.apache.jackrabbit.core.query.lucene.SingleColumnQueryResult.<init>(SingleColumnQueryResult.java:70)
at org.apache.jackrabbit.core.query.lucene.QueryImpl.execute(QueryImpl.java:133)
at org.apache.jackrabbit.core.query.QueryImpl.execute(QueryImpl.java:127)
at org.sakaiproject.nakamura.presence.search.UniqueAuthorizableProfileSearchResultProcessor.getSearchResultSet(UniqueAuthorizableProfileSearchResultProcessor.java:87)
at org.sakaiproject.nakamura.search.SearchServlet.doGet(SearchServlet.java:344)
at org.apache.sling.api.servlets.SlingSafeMethodsServlet.mayService(SlingSafeMethodsServlet.java:268)
at org.apache.sling.api.servlets.SlingSafeMethodsServlet.service(SlingSafeMethodsServlet.java:344)
at org.apache.sling.api.servlets.SlingSafeMethodsServlet.service(SlingSafeMethodsServlet.java:375)
at org.apache.sling.engine.impl.request.RequestData.service(RequestData.java:523)
at org.apache.sling.engine.impl.SlingMainServlet.processRequest(SlingMainServlet.java:427)
at org.apache.sling.engine.impl.filter.RequestSlingFilterChain.render(RequestSlingFilterChain.java:48)
at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:64)
at org.apache.sling.engine.impl.debug.RequestProgressTrackerLogFilter.doFilter(RequestProgressTrackerLogFilter.java:59)
at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60)
at org.sakaiproject.nakamura.batch.RequestEventsFilter.doFilter(RequestEventsFilter.java:96)
at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60)
at org.sakaiproject.nakamura.privacy.RestPrivacyFilter.doFilter(RestPrivacyFilter.java:81)
at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60)
at org.sakaiproject.nakamura.files.pool.ContentPoolFilter.doFilter(ContentPoolFilter.java:77)
at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60)
at org.sakaiproject.nakamura.cluster.ClusterTrackingFilter.doFilter(ClusterTrackingFilter.java:87)
at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60)
at org.sakaiproject.nakamura.persistence.TransactionManagerFilter.doFilter(TransactionManagerFilter.java:95)
at org.apache.sling.engine.impl.filter.AbstractSlingFilterChain.doFilter(AbstractSlingFilterChain.java:60)
at org.apache.sling.engine.impl.SlingMainServlet.service(SlingMainServlet.java:313)
at org.apache.sling.engine.impl.SlingMainServlet.service(SlingMainServlet.java:207)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:502)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:389)
at org.ops4j.pax.web.service.internal.HttpServiceServletHandler.handle(HttpServiceServletHandler.java:64)
at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181)
at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:765)
at org.ops4j.pax.web.service.internal.HttpServiceContext.handle(HttpServiceContext.java:111)
at org.ops4j.pax.web.service.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:64)
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:535)
at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:865)
at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:539)
at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:212)
at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:404)
at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:409)
at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:520)
24.09.2010 13:37:45.877 *INFO* [77.248.94.54 [1285328265714] GET /var/search/groups.json HTTP/1.1] logs/request.log 24/Sep/2010:13:37:45 +0200 [6024] <- 500 text/html; charset=UTF-8 163ms
--
This message is automatically generated by JIRA.
-
If you think it was sent incorrectly contact one of the administrators: http://jira.sakaiproject.org/secure/Administrators.jspa
-
For more information on JIRA, see: http://www.atlassian.com/software/jira
Ian Boston (JIRA)
[ http://jira.sakaiproject.org/browse/KERN-1140?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=108688#action_108688 ]
Ian Boston commented on KERN-1140:
----------------------------------
Looks like LRUMap needs to be synchronised.
Ian Boston (JIRA)
[ http://jira.sakaiproject.org/browse/KERN-1140?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Ian Boston reassigned KERN-1140:
--------------------------------
Assignee: Ian Boston
> Searching all groups causes Exception with strong hints of a synchronization issue.
> -----------------------------------------------------------------------------------
>
> Key: KERN-1140
> URL: http://jira.sakaiproject.org/browse/KERN-1140
> Project: Nakamura
> Issue Type: Bug
> Components: System - other
> Affects Versions: 0.8
> Environment: DEBUG: Nakamura Version: hudson-Nakamura-431 | UX Code Timestamp: Fri Sep 24 2010 12:49:52 GMT+0200
> DOC mod date: 09/24/2010 12:49:09 | PLACE: search_groups.html#q=*&facet=all
> Their are 2300 users created through curl and 1 by the create_account.html
> Reporter: Alan Berg
> Assignee: Ian Boston
Ian Boston (JIRA)
[ http://jira.sakaiproject.org/browse/KERN-1140?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Ian Boston resolved KERN-1140.
------------------------------
Resolution: Fixed
Fixed
> Searching all groups causes Exception with strong hints of a synchronization issue.
> -----------------------------------------------------------------------------------
>
> Key: KERN-1140
> URL: http://jira.sakaiproject.org/browse/KERN-1140
> Project: Nakamura
> Issue Type: Bug
> Components: System - other
> Affects Versions: 0.8
> Environment: DEBUG: Nakamura Version: hudson-Nakamura-431 | UX Code Timestamp: Fri Sep 24 2010 12:49:52 GMT+0200
> DOC mod date: 09/24/2010 12:49:09 | PLACE: search_groups.html#q=*&facet=all
> Their are 2300 users created through curl and 1 by the create_account.html
> Reporter: Alan Berg
> Assignee: Ian Boston