Adding a second directory to crowd causes it to hang

Hi We recently got crowd setup to handle SSO for a number of Atlassian apps. The directory we used was a basic crowd directory with basic authentication. Now we are trying to setup a second directory for active directory authentication but when we click on add directory the crowd app hangs.

I cant even stop the service, I have to kill the process after finding it in process explorer.

I dont see any errors in the atlassian-crowd.log file or the event viewer.

We eventually time out and get the following.

Proxy Error

The proxy server received an invalid response from an upstream server.
The proxy server could not handle the request GET /crowd/console/secure/application/browse.action.

Reason: Error reading from remote server


with logging set to all and profiling enabled we got a some information from the log file but we cant see any problems.

2011-09-12 13:12:43,376 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [3ms] - AOP: SecurityServer.isValidPrincipalToken()
[1ms] - AOP: SOAPService.validateSOAPService()
[0ms] - AOP: CrowdLicenseManager.isLicenseValid()
[1ms] - AOP: TokenAuthenticationManager.validateApplicationToken()
[0ms] - AOP: TokenManager.findByRandomHash()
[0ms] - AOP: PropertyManager.getSessionTime()
[0ms] - AOP: TokenManager.update()
[0ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: PropertyManager.isCacheEnabled()
[2ms] - AOP: TokenAuthenticationManager.validateUserToken()
[1ms] - AOP: TokenManager.findByRandomHash()
[0ms] - AOP: PropertyManager.getSessionTime()
[1ms] - AOP: TokenManager.update()
[0ms] - AOP: PropertyManager.isCacheEnabled()

2011-09-12 13:12:43,386 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [6ms] - AOP: SecurityServer.findPrincipalByToken()
[1ms] - AOP: SOAPService.validateSOAPService()
[0ms] - AOP: CrowdLicenseManager.isLicenseValid()
[1ms] - AOP: TokenAuthenticationManager.validateApplicationToken()
[0ms] - AOP: TokenManager.findByRandomHash()
[0ms] - AOP: PropertyManager.getSessionTime()
[1ms] - AOP: TokenManager.update()
[0ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: PropertyManager.isCacheEnabled()
[5ms] - AOP: TokenAuthenticationManager.findUserByToken()
[1ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: TokenManager.findByRandomHash()
[4ms] - AOP: DirectoryManager.findUserByName()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:43,394 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [3ms] - AOP: SecurityServer.getGrantedAuthorities()
[2ms] - AOP: SOAPService.validateSOAPService()
[0ms] - AOP: CrowdLicenseManager.isLicenseValid()
[1ms] - AOP: TokenAuthenticationManager.validateApplicationToken()
[1ms] - AOP: TokenManager.findByRandomHash()
[0ms] - AOP: PropertyManager.getSessionTime()
[0ms] - AOP: TokenManager.update()
[0ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: PropertyManager.isCacheEnabled()

2011-09-12 13:12:43,399 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [2ms] - AOP: SecurityServer.getCookieInfo()
[1ms] - AOP: SOAPService.validateSOAPService()
[0ms] - AOP: CrowdLicenseManager.isLicenseValid()
[1ms] - AOP: TokenAuthenticationManager.validateApplicationToken()
[0ms] - AOP: TokenManager.findByRandomHash()
[1ms] - AOP: PropertyManager.getSessionTime()
[0ms] - AOP: TokenManager.update()
[0ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: PropertyManager.isCacheEnabled()
[0ms] - AOP: PropertyManager.getDomain()
[1ms] - AOP: PropertyManager.isSecureCookie()

2011-09-12 13:12:46,464 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationManager.findByName()

2011-09-12 13:12:46,539 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [68ms] - AOP: ApplicationManager.authenticate()

2011-09-12 13:12:47,296 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationManager.findByName()

2011-09-12 13:12:47,306 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [9ms] - AOP: ApplicationService.searchGroups()
[9ms] - AOP: ApplicationService.searchGroups()
[9ms] - AOP: ApplicationService.searchGroups()
[7ms] - AOP: DirectoryManager.searchGroups()

2011-09-12 13:12:47,353 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationManager.findByName()

2011-09-12 13:12:47,357 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [2ms] - AOP: ApplicationService.searchGroups()
[2ms] - AOP: ApplicationService.searchGroups()
[2ms] - AOP: ApplicationService.searchGroups()
[1ms] - AOP: DirectoryManager.searchGroups()

2011-09-12 13:12:47,367 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [9ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[9ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[9ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[9ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,370 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,372 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,374 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,377 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,379 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: DirectoryManager.searchDirectGroupRelationships()

2011-09-12 13:12:47,380 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,381 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: DirectoryManager.searchDirectGroupRelationships()

2011-09-12 13:12:47,382 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,385 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,387 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,388 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[0ms] - AOP: DirectoryManager.searchDirectGroupRelationships()

2011-09-12 13:12:47,389 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,392 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [2ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: ApplicationService.searchDirectGroupRelationships()
[1ms] - AOP: DirectoryManager.searchDirectGroupRelationships()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:47,428 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [11ms] - AOP: ApplicationService.searchUsers()
[10ms] - AOP: ApplicationService.searchUsers()
[10ms] - AOP: ApplicationService.searchUsers()
[9ms] - AOP: DirectoryManager.searchUsers()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[0ms] - AOP: AliasManager.findAliasByUsername()
[1ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:59,236 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: PropertyManager.isGzipEnabled()

2011-09-12 13:12:59,241 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [4ms] - AOP: SecurityServer.isValidPrincipalToken()
[2ms] - AOP: SOAPService.validateSOAPService()
[0ms] - AOP: CrowdLicenseManager.isLicenseValid()
[1ms] - AOP: TokenAuthenticationManager.validateApplicationToken()
[1ms] - AOP: TokenManager.findByRandomHash()
[0ms] - AOP: PropertyManager.getSessionTime()
[0ms] - AOP: TokenManager.update()
[1ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: PropertyManager.isCacheEnabled()
[2ms] - AOP: TokenAuthenticationManager.validateUserToken()
[1ms] - AOP: TokenManager.findByRandomHash()
[0ms] - AOP: PropertyManager.getSessionTime()
[0ms] - AOP: TokenManager.update()
[0ms] - AOP: PropertyManager.isCacheEnabled()

2011-09-12 13:12:59,251 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [5ms] - AOP: SecurityServer.findPrincipalByToken()
[1ms] - AOP: SOAPService.validateSOAPService()
[0ms] - AOP: CrowdLicenseManager.isLicenseValid()
[1ms] - AOP: TokenAuthenticationManager.validateApplicationToken()
[0ms] - AOP: TokenManager.findByRandomHash()
[1ms] - AOP: PropertyManager.getSessionTime()
[0ms] - AOP: TokenManager.update()
[0ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: PropertyManager.isCacheEnabled()
[4ms] - AOP: TokenAuthenticationManager.findUserByToken()
[1ms] - AOP: ApplicationManager.findByName()
[1ms] - AOP: TokenManager.findByRandomHash()
[2ms] - AOP: DirectoryManager.findUserByName()
[0ms] - AOP: AliasManager.findAliasByUsername()

2011-09-12 13:12:59,258 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [2ms] - AOP: SecurityServer.getGrantedAuthorities()
[1ms] - AOP: SOAPService.validateSOAPService()
[0ms] - AOP: CrowdLicenseManager.isLicenseValid()
[1ms] - AOP: TokenAuthenticationManager.validateApplicationToken()
[0ms] - AOP: TokenManager.findByRandomHash()
[0ms] - AOP: PropertyManager.getSessionTime()
[1ms] - AOP: TokenManager.update()
[0ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: PropertyManager.isCacheEnabled()

2011-09-12 13:12:59,264 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [2ms] - AOP: SecurityServer.getCookieInfo()
[2ms] - AOP: SOAPService.validateSOAPService()
[0ms] - AOP: CrowdLicenseManager.isLicenseValid()
[1ms] - AOP: TokenAuthenticationManager.validateApplicationToken()
[1ms] - AOP: TokenManager.findByRandomHash()
[0ms] - AOP: PropertyManager.getSessionTime()
[0ms] - AOP: TokenManager.update()
[1ms] - AOP: ApplicationManager.findByName()
[0ms] - AOP: PropertyManager.isCacheEnabled()
[0ms] - AOP: PropertyManager.getDomain()
[0ms] - AOP: PropertyManager.isSecureCookie()

2011-09-12 13:12:59,267 http-8095-4 DEBUG [console.action.options.UpdateLoggingProfiling] New log configuration saved
2011-09-12 13:12:59,268 http-8095-4 DEBUG [atlassian.xwork.interceptors.TransactionalInvocation] Committing transaction for action /console/secure/admin/loggingProfiling.action (UpdateLoggingProfiling.default())
2011-09-12 13:12:59,268 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,269 http-8095-4 DEBUG [atlassian.xwork.interceptors.TransactionalInvocation] Creating transaction for action result: /console/secure/admin/loggingProfiling.action (UpdateLoggingProfiling.default())
2011-09-12 13:12:59,269 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,269 http-8095-4 DEBUG [apache.catalina.core.StandardWrapper] Returning non-STM instance
2011-09-12 13:12:59,270 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] JspEngine --> /console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,270 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] ServletPath: /console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,270 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] PathInfo: null
2011-09-12 13:12:59,270 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RealPath: E:\JiraStuff\crowd-2.3.2\atlassian-crowd-2.3.2\apache-tomcat\webapps\..\..\crowd-webapp\console\secure\admin\logging_profiling.jsp
2011-09-12 13:12:59,271 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RequestURI: /crowd/console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,271 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] QueryString: null
2011-09-12 13:12:59,273 http-8095-4 DEBUG [apache.catalina.core.StandardWrapper] Returning non-STM instance
2011-09-12 13:12:59,274 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] JspEngine --> /template/xhtml/hidden.jsp
2011-09-12 13:12:59,274 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] ServletPath: /console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,274 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] PathInfo: null
2011-09-12 13:12:59,275 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RealPath: E:\JiraStuff\crowd-2.3.2\atlassian-crowd-2.3.2\apache-tomcat\webapps\..\..\crowd-webapp\template\xhtml\hidden.jsp
2011-09-12 13:12:59,275 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RequestURI: /crowd/console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,276 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] QueryString: null
2011-09-12 13:12:59,278 http-8095-4 DEBUG [apache.catalina.core.StandardWrapper] Returning non-STM instance
2011-09-12 13:12:59,278 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] JspEngine --> /template/xhtml/hidden.jsp
2011-09-12 13:12:59,279 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] ServletPath: /console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,279 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] PathInfo: null
2011-09-12 13:12:59,280 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RealPath: E:\JiraStuff\crowd-2.3.2\atlassian-crowd-2.3.2\apache-tomcat\webapps\..\..\crowd-webapp\template\xhtml\hidden.jsp
2011-09-12 13:12:59,280 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RequestURI: /crowd/console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,280 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] QueryString: null
2011-09-12 13:12:59,281 http-8095-4 DEBUG [apache.catalina.core.StandardWrapper] Returning non-STM instance
2011-09-12 13:12:59,282 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] JspEngine --> /template/xhtml/form_messages.jsp
2011-09-12 13:12:59,282 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] ServletPath: /console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,283 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] PathInfo: null
2011-09-12 13:12:59,283 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RealPath: E:\JiraStuff\crowd-2.3.2\atlassian-crowd-2.3.2\apache-tomcat\webapps\..\..\crowd-webapp\template\xhtml\form_messages.jsp
2011-09-12 13:12:59,284 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RequestURI: /crowd/console/secure/admin/logging_profiling.jsp
2011-09-12 13:12:59,284 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] QueryString: null
2011-09-12 13:12:59,290 http-8095-4 DEBUG [[Catalina].[localhost].[/crowd].[jsp]] Disabling the response for futher output
2011-09-12 13:12:59,290 http-8095-4 DEBUG [[Catalina].[localhost].[/crowd].[jsp]] The Response is vehiculed using a wrapper: com.opensymphony.module.sitemesh.filter.PageResponseWrapper
2011-09-12 13:12:59,290 http-8095-4 DEBUG [atlassian.xwork.interceptors.TransactionalInvocation] Committing transaction for action /console/secure/admin/loggingProfiling.action (UpdateLoggingProfiling.default())
2011-09-12 13:12:59,290 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,291 http-8095-4 DEBUG [apache.catalina.core.StandardWrapper] Returning non-STM instance
2011-09-12 13:12:59,292 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] JspEngine --> /console/decorator/default.jsp
2011-09-12 13:12:59,292 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] ServletPath: /console/secure/admin/loggingProfiling!updateLogging.action
2011-09-12 13:12:59,292 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] PathInfo: null
2011-09-12 13:12:59,293 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RealPath: E:\JiraStuff\crowd-2.3.2\atlassian-crowd-2.3.2\apache-tomcat\webapps\..\..\crowd-webapp\console\decorator\default.jsp
2011-09-12 13:12:59,293 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] RequestURI: /crowd/console/secure/admin/loggingProfiling!updateLogging.action
2011-09-12 13:12:59,293 http-8095-4 DEBUG [apache.jasper.servlet.JspServlet] QueryString: null
2011-09-12 13:12:59,295 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,295 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,295 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,295 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,305 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,305 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,306 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,306 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,306 http-8095-4 DEBUG [crowd.console.filter.CrowdOpenSessionInViewFilter] Closing single Hibernate Session in OpenSessionInViewFilter
2011-09-12 13:12:59,328 http-8095-4 DEBUG [apache.catalina.core.StandardWrapper] Returning non-STM instance
2011-09-12 13:12:59,328 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,328 http-8095-4 DEBUG [sf.ehcache.store.MemoryStore] com.atlassian.crowd.model.property.PropertyCache: com.atlassian.crowd.model.property.PropertyMemoryStore hit for com.atlassian.crowd.model.property.Property#com.atlassian.crowd.model.property.PropertyId@1776c7a0
2011-09-12 13:12:59,329 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,329 http-8095-4 DEBUG [crowd.console.filter.CrowdOpenSessionInViewFilter] Using SessionFactory 'sessionFactory' for OpenSessionInViewFilter
2011-09-12 13:12:59,329 http-8095-4 DEBUG [crowd.console.filter.CrowdOpenSessionInViewFilter] Opening single Hibernate Session in OpenSessionInViewFilter
2011-09-12 13:12:59,329 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,329 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,329 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,330 http-8095-4 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: CrowdLicenseManager.isLicenseValid()

2011-09-12 13:12:59,330 http-8095-4 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,330 http-8095-4 DEBUG [integration.http.util.CrowdHttpTokenHelperImpl] Checking for a SSO token that will need to be verified by Crowd.
2011-09-12 13:12:59,330 http-8095-4 DEBUG [integration.http.util.CrowdHttpTokenHelperImpl] No request attribute token could be found, now checking the browser submitted cookies.
2011-09-12 13:12:59,330 http-8095-3 DEBUG [apache.catalina.core.StandardWrapper] Returning non-STM instance
2011-09-12 13:12:59,331 http-8095-4 DEBUG [integration.http.util.CrowdHttpTokenHelperImpl] Cookie name/value: JSESSIONID / DBB2AF925F0128088465E09F04E73B02
2011-09-12 13:12:59,331 http-8095-4 DEBUG [integration.http.util.CrowdHttpTokenHelperImpl] Cookie name/value: crowd.token_key / xJ8rA0Rbm33aqigs7wrf2g00
2011-09-12 13:12:59,331 http-8095-4 DEBUG [integration.http.util.CrowdHttpTokenHelperImpl] Accepting the SSO cookie value: xJ8rA0Rbm33aqigs7wrf2g00
2011-09-12 13:12:59,331 http-8095-3 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused: 1, excluded: 0] (e.g. com.mchange.v2.c3p0.impl.NewPooledConnection@b1fea4)
2011-09-12 13:12:59,331 http-8095-4 DEBUG [integration.http.util.CrowdHttpTokenHelperImpl] Existing token value yet to be verified by Crowd: xJ8rA0Rbm33aqigs7wrf2g00
2011-09-12 13:12:59,331 http-8095-4 DEBUG [crowd.integration.springsecurity.CrowdAuthenticationProvider] Processing a CrowdSSOAuthenticationToken
2011-09-12 13:12:59,331 http-8095-3 DEBUG [sf.ehcache.store.MemoryStore] com.atlassian.crowd.model.property.PropertyCache: com.atlassian.crowd.model.property.PropertyMemoryStore hit for com.atlassian.crowd.model.property.Property#com.atlassian.crowd.model.property.PropertyId@1776c7a0
2011-09-12 13:12:59,332 http-8095-4 DEBUG [service.soap.client.SecurityServerClientImpl] Connection URL: http://crowd.onlifehealth.com/crowd/services/SecurityServer<br< a=""> />2011-09-12 13:12:59,332 http-8095-3 DEBUG [atlassian.util.profiling.UtilTimerStack] [1ms] - AOP: PropertyManager.isGzipEnabled()

2011-09-12 13:12:59,332 http-8095-4 DEBUG [service.soap.client.SecurityServerClientImpl] Using existing token: PpjcHnqzbz178O5RLwNN0A00
2011-09-12 13:12:59,332 http-8095-3 DEBUG [mchange.v2.resourcepool.BasicResourcePool] trace com.mchange.v2.resourcepool.BasicResourcePool@1edf84f [managed: 2, unused...





























































































Any ideas what could be causing this?

2 answers

1 accepted

2 votes
Colin Goudie Community Champion Sep 13, 2011

Did you modify the environment variables for max heap size etc..? It's possible that with Crowd running as a service it has just the default memory settings. These are set usually in the registry or via the tomcat.exe //ES/ServiceName procedure

I did change the heap size and perm size and it improved the performance considerably.

THanks

Also just to mention this, when i run the crowd app (not as a windows service) with start_crowd.bat then i dont get this hang.

Suggest an answer

Log in or Join to answer
Community showcase
Emilee Spencer
Published Friday in Marketplace Apps

Marketplace Spotlight: DeepAffects

Hello Atlassian Community! My name is Emilee, and I’m a Product Marketing Manager for the Marketplace team. Starting with this post, I'm kicking off a monthly series of Spotlights to highlight Ma...

63 views 0 3
Read article

Atlassian User Groups

Connect with like-minded Atlassian users at free events near you!

Find a group

Connect with like-minded Atlassian users at free events near you!

Find my local user group

Unfortunately there are no AUG chapters near you at the moment.

Start an AUG

You're one step closer to meeting fellow Atlassian users at your local meet up. Learn more about AUGs

Groups near you
Atlassian Team Tour

Join us on the Team Tour

We're bringing product updates and pro tips on teamwork to ten cities around the world.

Save your spot