Installing Confluence with MSSQL not successful

Question

Hi i'm trying to install Confluence 6.11.0 with MS SQL Server. The connection Test to the database was successful. When Tomcat starts, i get an ERROR. In the CMD Log i see: 01-Sep-2018 15:43:27.387 SCHWERWIEGEND [http-nio-8090-exec-6] org.apache.catalina.core.StandardHostValve.custom Exception Processing ErrorPage[errorCode=500, location=/500page.jsp]
org.apache.jasper.JasperException: An exception occurred processing [/500page.jsp] at line [120]

Please Help

Roland Frigo · Answer

Hi Ahnad,

It is a little bit frustrating. but i like to bring it up and running.

When i start http://localhost:8090/setup/setupdbchoice-start.action

Enter the data to connet database, test connetion successful, Continue…. after a while…

i get :

HTTP Status 500 – Internal Server Error

Type Exception Report

Message Cannot call sendError() after the response has been committed

Description The server encountered an unexpected condition that prevented it from fulfilling the request.

The atlassian-confluence.log says 6 ERRORS

2018-10-03 21:47:33,529 ERROR [http-nio-8090-exec-6] [atlassian.confluence.setup.DefaultHibernateConfigurator] createConfluenceSchema Error while creating database schema

2018-10-03 21:47:33,556 ERROR [http-nio-8090-exec-6] [confluence.setup.actions.SetupDBTypeAction] setupDatabase Unable to bootstrap standard database

2018-10-03 21:47:33,643 ERROR [http-nio-8090-exec-6] [engine.jdbc.spi.SqlExceptionHelper] logExceptions The SELECT permission was denied on the object 'BANDANA', database 'confluence', schema 'dbo'.

2018-10-03 21:47:33,707 ERROR [http-nio-8090-exec-6] [confluence.util.profiling.ProfilingSiteMeshFilter] doFilter Application context has not been initialized:

2018-10-03 21:47:33,727 ERROR [http-nio-8090-exec-6] [[Standalone].[localhost].[/].[action]] log Servlet.service() for servlet [action] in context with path [] threw exception
java.lang.IllegalStateException: Cannot call sendError() after the response has been committed

2018-10-03 21:47:33,926 ERROR [http-nio-8090-exec-1] [atlassian.confluence.util.AbstractBootstrapHotSwappingFilter] doFilter Could not get swap target filter

Thank you for your Support

Roland

AhmadDanial · Answer

Hi, Roland. No worries. I just got back from a week off as well. Thanks for attaching the log snippet here for review. What we are seeing here is a WARNING rather than an ERROR actually. Looking at the specific lines that you shared: 11-Sep-2018 20:11:37.996 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.074 WARNUNG [main] org.apache.catalina.startup.SetAllPropertiesRule.begin [SetAllPropertiesRule]{Server/Service/Connector} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.183 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server/Service/Engine} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.183 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server/Service/Engine/Host} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.230 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.246 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting property 'debug' to '0' did not find a matching property. This seems to be related to the CONFSERVER-35172: WARNING: [SetPropertiesRule] {Server} Setting property 'debug' to '0' did not find a matching property. which should not affect the installation of Confluence Server. May I know if you have gone through the steps listed in the following KB to ensure that Confluence is running and accessible? * How to check the Confluence application is running Moving forward with the next WARNING you are seeing: 11-Sep-2018 20:14:53.257 WARNUNG [ContainerBackgroundProcessor[StandardEngine[Standalone]]] org.apache.catalina.valves.StuckThreadDetectionValve.notifyStuckThreadDetected Thread [http-nio-8090-exec-2] (id=[35]) has been active for [62,574] milliseconds (since [9/11/18 8:13 PM]) to serve the same request for [http://localhost:8090/setup/setupdbtype.action] and may be stuck (configured threshold for this StuckThreadDetectionValve is [60] seconds). There is/are [1] thread(s) in total that are monitored by this Valve and may be stuck. java.lang.Throwable at java.io.WinNTFileSystem.getLastModifiedTime(Native Method) at java.io.File.lastModified(File.java:943) at java.util.zip.ZipFile.(ZipFile.java:225) at java.util.zip.ZipFile.(ZipFile.java:155) at java.util.jar.JarFile.(JarFile.java:166) at java.util.jar.JarFile.(JarFile.java:130) at org.apache.tomcat.util.compat.JreCompat.jarFileNewInstance This warning indicates that there is a StuckThreadDetectionValve thread with the id-35 has been running for more than the default threshold (62.574 vs 60 seconds). While this does not indicate any specific issues with Confluence, can you please explain in detail on the issue that you observe in Confluence? Is the any error messages when you try to set it up from the web browser? Any other concerning ERRORs that you observe from the atlassian-confluence.log that you noticed that might be of help to determine the actual problem that you are facing here?

Roland Frigo · Answer

Hi Ahnad, No Problem, i was also on vacation. I tried the KB Tip. Host Name was not avaiable with ping. The i changed the host file (diffrent Location then Linux in Windows). The check with ping was ok. Then i retryed the Installation, but again an ERROR. I think now a diffrent. See log below. Can you help on this too? Thank you! 11-Sep-2018 20:11:37.996 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.074 WARNUNG [main] org.apache.catalina.startup.SetAllPropertiesRule.begin [SetAllPropertiesRule]{Server/Service/Connector} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.183 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server/Service/Engine} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.183 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server/Service/Engine/Host} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.230 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.246 WARNUNG [main] org.apache.tomcat.util.digester.SetPropertiesRule.begin [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting property 'debug' to '0' did not find a matching property. 11-Sep-2018 20:11:38.496 INFORMATION [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8090"] 11-Sep-2018 20:11:38.621 INFORMATION [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read 11-Sep-2018 20:11:38.637 INFORMATION [main] org.apache.catalina.startup.Catalina.load Initialization processed in 769 ms 11-Sep-2018 20:11:38.637 INFORMATION [main] org.apache.catalina.core.StandardService.startInternal Starting service [Tomcat-Standalone] 11-Sep-2018 20:11:38.637 INFORMATION [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet Engine: Apache Tomcat/9.0.10 11-Sep-2018 20:11:40.527 INFORMATION [localhost-startStop-2] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time. 11-Sep-2018 20:11:40.886 INFORMATION [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log Spring WebApplicationInitializers detected on classpath: [com.atlassian.synchrony.proxy.SynchronyDispatcherServletInitializer@6a9957b1] 11-Sep-2018 20:11:41.199 INFORMATION [localhost-startStop-2] org.apache.catalina.core.ApplicationContext.log Initializing Spring FrameworkServlet 'dispatcher' 11-Sep-2018 20:11:41.214 INFORMATION [localhost-startStop-2] org.springframework.web.servlet.DispatcherServlet.initServletBean FrameworkServlet 'dispatcher': initialization started 11-Sep-2018 20:11:41.214 INFORMATION [localhost-startStop-2] org.springframework.web.context.support.AnnotationConfigWebApplicationContext.prepareRefresh Refreshing WebApplicationContext for namespace 'dispatcher-servlet': startup date [Tue Sep 11 20:11:41 CEST 2018]; root of context hierarchy 11-Sep-2018 20:11:41.308 INFORMATION [localhost-startStop-2] org.springframework.web.context.support.AnnotationConfigWebApplicationContext.loadBeanDefinitions Registering annotated classes: [class com.atlassian.synchrony.proxy.websocket.WebSocketConfig,class com.atlassian.synchrony.proxy.web.SynchronyWebMvcConfig] 11-Sep-2018 20:11:42.030 INFORMATION [localhost-startStop-2] org.springframework.scheduling.concurrent.ThreadPoolTaskScheduler.initialize Initializing ExecutorService 'defaultSockJsTaskScheduler' 11-Sep-2018 20:11:42.092 INFORMATION [localhost-startStop-2] org.springframework.web.socket.server.support.WebSocketHandlerMapping.registerHandler Mapped URL path [/v1/bayeux-sync1] onto handler of type [class org.springframework.web.socket.server.support.WebSocketHttpRequestHandler] 2018-09-11 20:11:42,561 INFO [localhost-startStop-1] [com.atlassian.confluence.lifecycle] contextInitialized Starting Confluence 6.11.0 [build 7801 based on commit hash 0ac8ad8167063a2d16fb426a10b9044cf4bf1b17] - synchrony version 2.1.0-master-cdbe2074 11-Sep-2018 20:11:42.702 INFORMATION [localhost-startStop-2] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.initControllerAdviceCache Looking for @ControllerAdvice: WebApplicationContext for namespace 'dispatcher-servlet': startup date [Tue Sep 11 20:11:41 CEST 2018]; root of context hierarchy 11-Sep-2018 20:11:42.827 INFORMATION [localhost-startStop-2] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping.register Mapped "{[/healthcheck]}" onto public com.atlassian.synchrony.proxy.web.HealthCheckResult com.atlassian.synchrony.proxy.web.SynchronyProxyRestController.getSynchronyProxyInfo() 11-Sep-2018 20:11:42.842 INFORMATION [localhost-startStop-2] org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerMapping.register Mapped "{[/reload],methods=[PUT]}" onto public org.springframework.http.ResponseEntity com.atlassian.synchrony.proxy.web.SynchronyProxyRestController.reloadConfiguration(com.atlassian.synchrony.proxy.web.SynchronyProxyConfigPayload) 11-Sep-2018 20:11:42.858 INFORMATION [localhost-startStop-2] org.springframework.web.servlet.handler.SimpleUrlHandlerMapping.registerHandler Mapped URL path [/**] onto handler of type [class org.springframework.web.servlet.resource.DefaultServletHttpRequestHandler] 11-Sep-2018 20:11:42.967 INFORMATION [localhost-startStop-2] org.springframework.context.support.DefaultLifecycleProcessor.start Starting beans in phase 2147483647 11-Sep-2018 20:11:43.014 INFORMATION [localhost-startStop-2] org.springframework.web.servlet.DispatcherServlet.initServletBean FrameworkServlet 'dispatcher': initialization completed in 1800 ms 11-Sep-2018 20:11:52.859 INFORMATION [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8090"] 11-Sep-2018 20:11:52.874 INFORMATION [main] org.apache.catalina.startup.Catalina.start Server startup in 14239 ms 11-Sep-2018 20:14:53.257 WARNUNG [ContainerBackgroundProcessor[StandardEngine[Standalone]]] org.apache.catalina.valves.StuckThreadDetectionValve.notifyStuckThreadDetected Thread [http-nio-8090-exec-2] (id=[35]) has been active for [62,574] milliseconds (since [9/11/18 8:13 PM]) to serve the same request for [http://localhost:8090/setup/setupdbtype.action] and may be stuck (configured threshold for this StuckThreadDetectionValve is [60] seconds). There is/are [1] thread(s) in total that are monitored by this Valve and may be stuck. java.lang.Throwable at java.io.WinNTFileSystem.getLastModifiedTime(Native Method) at java.io.File.lastModified(File.java:943) at java.util.zip.ZipFile.(ZipFile.java:225) at java.util.zip.ZipFile.(ZipFile.java:155) at java.util.jar.JarFile.(JarFile.java:166) at java.util.jar.JarFile.(JarFile.java:130) at org.apache.tomcat.util.compat.JreCompat.jarFileNewInstance