Fail to start web server - Windows

Must-share information (formatted with Markdown):

  • sonarqube-8.9.1.44547
  • Installing Sonar Qube on New Server
  • Unzip the SonarQube, Installed JDK 11.0.10. Also make few chnages in properties file:-
  1. sonar.security.realm=LDAP

# Set to true when connecting to a LDAP server using a case-insensitive setup.
2. sonar.authenticator.downcase=true
ldap.user.baseDn=DC=MYCompanyDomain,DC=com
3. ldap.user.request=(&(objectClass=user)(sAMAccountName={login}))3. Extras :- sonar.secretKeyPath=C:\sonarqube\sonarqube-8.9.1.44547/conf/sonar-secret.txt (Copied file from Existing Server)

All the above settings are from old server where community edition already working.

Error I am getting:-

2021.07.13 16:14:20 WARN  web[][o.s.p.ProcessEntryPoint] Fail to start web
java.lang.RuntimeException: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-9000]]
	at com.google.common.base.Throwables.propagate(Throwables.java:241)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:77)
	at org.sonar.server.app.WebServer.start(WebServer.java:52)
	at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:97)
	at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:81)
	at org.sonar.server.app.WebServer.main(WebServer.java:99)
Caused by: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-9000]]
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:846)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:173)
	at org.apache.catalina.startup.Tomcat.start(Tomcat.java:440)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:72)
	... 4 common frames omitted
2021.07.13 16:14:20 INFO  web[][o.s.p.ProcessEntryPoint] Hard stopping process

Thanks
Sunil

Hi Sunil,

What do your server logs say?

 
Ann

Hi Ann

Above error is from the Web log file, copying again:-

2021.07.14 08:41:07 INFO  web[][o.s.p.ProcessEntryPoint] Starting web
2021.07.14 08:41:07 ERROR web[][o.a.c.c.StandardService] Failed to initialize connector [Connector[HTTP/1.1-9000]]
org.apache.catalina.LifecycleException: Protocol handler initialization failed
	at org.apache.catalina.connector.Connector.initInternal(Connector.java:1077)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:552)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:846)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:173)
	at org.apache.catalina.startup.Tomcat.start(Tomcat.java:440)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:72)
	at org.sonar.server.app.WebServer.start(WebServer.java:52)
	at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:97)
	at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:81)
	at org.sonar.server.app.WebServer.main(WebServer.java:99)
Caused by: java.net.BindException: Address already in use: bind
	at java.base/sun.nio.ch.Net.bind0(Native Method)
	at java.base/sun.nio.ch.Net.bind(Net.java:455)
	at java.base/sun.nio.ch.Net.bind(Net.java:447)
	at java.base/sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:227)
	at java.base/sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:80)
	at org.apache.tomcat.util.net.NioEndpoint.bind(NioEndpoint.java:222)
	at org.apache.tomcat.util.net.AbstractEndpoint.init(AbstractEndpoint.java:1143)
	at org.apache.tomcat.util.net.AbstractJsseEndpoint.init(AbstractJsseEndpoint.java:222)
	at org.apache.coyote.AbstractProtocol.init(AbstractProtocol.java:599)
	at org.apache.coyote.http11.AbstractHttp11Protocol.init(AbstractHttp11Protocol.java:80)
	at org.apache.catalina.connector.Connector.initInternal(Connector.java:1075)
	... 12 common frames omitted
2021.07.14 08:41:07 ERROR web[][o.s.s.a.EmbeddedTomcat] Fail to start web server
org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-9000]]
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:846)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:173)
	at org.apache.catalina.startup.Tomcat.start(Tomcat.java:440)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:72)
	at org.sonar.server.app.WebServer.start(WebServer.java:52)
	at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:97)
	at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:81)
	at org.sonar.server.app.WebServer.main(WebServer.java:99)
2021.07.14 08:41:07 WARN  web[][o.s.p.ProcessEntryPoint] Fail to start web
java.lang.RuntimeException: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-9000]]
	at com.google.common.base.Throwables.propagate(Throwables.java:241)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:77)
	at org.sonar.server.app.WebServer.start(WebServer.java:52)
	at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:97)
	at org.sonar.process.ProcessEntryPoint.launch(ProcessEntryPoint.java:81)
	at org.sonar.server.app.WebServer.main(WebServer.java:99)
Caused by: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-9000]]
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:846)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:136)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:173)
	at org.apache.catalina.startup.Tomcat.start(Tomcat.java:440)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:72)
	... 4 common frames omitted
2021.07.14 08:41:07 INFO  web[][o.s.p.ProcessEntryPoint] Hard stopping process

Below is the Sonar Log:-

--> Wrapper Started as Service
Launching a JVM...
Wrapper (Version 3.2.3) http://wrapper.tanukisoftware.org
  Copyright 1999-2006 Tanuki Software, Inc.  All Rights Reserved.

2021.07.14 08:40:54 INFO  app[][o.s.a.AppFileSystem] Cleaning or creating temp directory C:\sonarqube\sonarqube-8.9.1.44547\temp
2021.07.14 08:40:54 INFO  app[][o.s.a.es.EsSettings] Elasticsearch listening on [HTTP: 127.0.0.1:9001, TCP: 127.0.0.1:55009]
2021.07.14 08:40:54 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[[key='es', ipcIndex=1, logFilenamePrefix=es]] from [C:\sonarqube\sonarqube-8.9.1.44547\elasticsearch]: C:\Program Files\Java\jdk-11.0.10\bin\java -XX:+UseConcMarkSweepGC -XX:CMSInitiatingOccupancyFraction=75 -XX:+UseCMSInitiatingOccupancyOnly -Djava.io.tmpdir=C:\sonarqube\sonarqube-8.9.1.44547\temp -XX:ErrorFile=../logs/es_hs_err_pid%p.log -Des.networkaddress.cache.ttl=60 -Des.networkaddress.cache.negative.ttl=10 -XX:+AlwaysPreTouch -Xss1m -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djna.nosys=true -XX:-OmitStackTraceInFastThrow -Dio.netty.noUnsafe=true -Dio.netty.noKeySetOptimization=true -Dio.netty.recycler.maxCapacityPerThread=0 -Dio.netty.allocator.numDirectArenas=0 -Dlog4j.shutdownHookEnabled=false -Dlog4j2.disable.jmx=true -Djava.locale.providers=COMPAT -Des.enforce.bootstrap.checks=true -Xmx512m -Xms512m -XX:MaxDirectMemorySize=256m -XX:+HeapDumpOnOutOfMemoryError -Delasticsearch -Des.path.home=C:\sonarqube\sonarqube-8.9.1.44547\elasticsearch -Des.path.conf=C:\sonarqube\sonarqube-8.9.1.44547\temp\conf\es -cp lib/* org.elasticsearch.bootstrap.Elasticsearch
2021.07.14 08:40:55 INFO  app[][o.s.a.SchedulerImpl] Waiting for Elasticsearch to be up and running
Java HotSpot(TM) 64-Bit Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
2021.07.14 08:41:06 INFO  app[][o.s.a.SchedulerImpl] Process[es] is up
2021.07.14 08:41:06 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[[key='web', ipcIndex=2, logFilenamePrefix=web]] from [C:\sonarqube\sonarqube-8.9.1.44547]: C:\Program Files\Java\jdk-11.0.10\bin\java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djava.io.tmpdir=C:\sonarqube\sonarqube-8.9.1.44547\temp -XX:-OmitStackTraceInFastThrow --add-opens=java.base/java.util=ALL-UNNAMED --add-opens=java.base/java.lang=ALL-UNNAMED --add-opens=java.base/java.io=ALL-UNNAMED --add-opens=java.rmi/sun.rmi.transport=ALL-UNNAMED -Xmx512m -Xms128m -XX:+HeapDumpOnOutOfMemoryError -Dhttp.nonProxyHosts=localhost|127.*|[::1] -cp ./lib/sonar-application-8.9.1.44547.jar;C:\sonarqube\sonarqube-8.9.1.44547\lib\jdbc\mssql\mssql-jdbc-9.2.0.jre11.jar org.sonar.server.app.WebServer C:\sonarqube\sonarqube-8.9.1.44547\temp\sq-process5956429560141353793properties
2021.07.14 08:41:07 INFO  app[][o.s.a.SchedulerImpl] Process[web] is stopped
2021.07.14 08:41:07 INFO  app[][o.s.a.SchedulerImpl] Process[es] is stopped
2021.07.14 08:41:07 INFO  app[][o.s.a.SchedulerImpl] SonarQube is stopped
<-- Wrapper Stopped

Log from es log file:-

2021.07.14 08:40:57 INFO  es[][o.e.n.Node] version[7.12.1], pid[708], build[unknown/unknown/3186837139b9c6b6d23c3200870651f10d3343b7/2021-04-20T20:56:39.040728659Z], OS[Windows Server 2016/10.0/amd64], JVM[Oracle Corporation/Java HotSpot(TM) 64-Bit Server VM/11.0.10/11.0.10+8-LTS-162]
2021.07.14 08:40:57 INFO  es[][o.e.n.Node] JVM home [C:\Program Files\Java\jdk-11.0.10]
2021.07.14 08:40:57 INFO  es[][o.e.n.Node] JVM arguments [-XX:+UseConcMarkSweepGC, -XX:CMSInitiatingOccupancyFraction=75, -XX:+UseCMSInitiatingOccupancyOnly, -Djava.io.tmpdir=C:\sonarqube\sonarqube-8.9.1.44547\temp, -XX:ErrorFile=../logs/es_hs_err_pid%p.log, -Des.networkaddress.cache.ttl=60, -Des.networkaddress.cache.negative.ttl=10, -XX:+AlwaysPreTouch, -Xss1m, -Djava.awt.headless=true, -Dfile.encoding=UTF-8, -Djna.nosys=true, -XX:-OmitStackTraceInFastThrow, -Dio.netty.noUnsafe=true, -Dio.netty.noKeySetOptimization=true, -Dio.netty.recycler.maxCapacityPerThread=0, -Dio.netty.allocator.numDirectArenas=0, -Dlog4j.shutdownHookEnabled=false, -Dlog4j2.disable.jmx=true, -Djava.locale.providers=COMPAT, -Des.enforce.bootstrap.checks=true, -Xmx512m, -Xms512m, -XX:MaxDirectMemorySize=256m, -XX:+HeapDumpOnOutOfMemoryError, -Delasticsearch, -Des.path.home=C:\sonarqube\sonarqube-8.9.1.44547\elasticsearch, -Des.path.conf=C:\sonarqube\sonarqube-8.9.1.44547\temp\conf\es]
2021.07.14 08:40:58 INFO  es[][o.e.p.PluginsService] loaded module [analysis-common]
2021.07.14 08:40:58 INFO  es[][o.e.p.PluginsService] loaded module [lang-painless]
2021.07.14 08:40:58 INFO  es[][o.e.p.PluginsService] loaded module [parent-join]
2021.07.14 08:40:58 INFO  es[][o.e.p.PluginsService] loaded module [percolator]
2021.07.14 08:40:58 INFO  es[][o.e.p.PluginsService] loaded module [transport-netty4]
2021.07.14 08:40:58 INFO  es[][o.e.p.PluginsService] no plugins loaded
2021.07.14 08:40:58 INFO  es[][o.e.e.NodeEnvironment] using [1] data paths, mounts [[(C:)]], net usable_space [466.2gb], net total_space [511.5gb], types [NTFS]
2021.07.14 08:40:58 INFO  es[][o.e.e.NodeEnvironment] heap size [494.9mb], compressed ordinary object pointers [true]
2021.07.14 08:40:58 INFO  es[][o.e.n.Node] node name [sonarqube], node ID [ppdKwbWoTNqUTVCGoGlyEA], cluster name [sonarqube], roles [master, remote_cluster_client, data, ingest]
2021.07.14 08:41:02 INFO  es[][o.e.t.NettyAllocator] creating NettyAllocator with the following configs: [name=unpooled, suggested_max_allocation_size=1mb, factors={es.unsafe.use_unpooled_allocator=null, g1gc_enabled=false, g1gc_region_size=0b, heap_size=494.9mb}]
2021.07.14 08:41:02 INFO  es[][o.e.d.DiscoveryModule] using discovery type [zen] and seed hosts providers [settings]
2021.07.14 08:41:03 INFO  es[][o.e.g.DanglingIndicesState] gateway.auto_import_dangling_indices is disabled, dangling indices will not be automatically detected or imported and must be managed manually
2021.07.14 08:41:03 INFO  es[][o.e.n.Node] initialized
2021.07.14 08:41:03 INFO  es[][o.e.n.Node] starting ...
2021.07.14 08:41:03 INFO  es[][o.e.t.TransportService] publish_address {127.0.0.1:55009}, bound_addresses {127.0.0.1:55009}
2021.07.14 08:41:04 INFO  es[][o.e.b.BootstrapChecks] explicitly enforcing bootstrap checks
2021.07.14 08:41:04 INFO  es[][o.e.c.c.Coordinator] cluster UUID [MQjmmpoASmOiT4qJRt0mPA]
2021.07.14 08:41:04 INFO  es[][o.e.c.s.MasterService] elected-as-master ([1] nodes joined)[{sonarqube}{ppdKwbWoTNqUTVCGoGlyEA}{UGHTAa0WS7qy3853ChsYvw}{127.0.0.1}{127.0.0.1:55009}{dimr}{rack_id=sonarqube} elect leader, _BECOME_MASTER_TASK_, _FINISH_ELECTION_], term: 21, version: 459, delta: master node changed {previous [], current [{sonarqube}{ppdKwbWoTNqUTVCGoGlyEA}{UGHTAa0WS7qy3853ChsYvw}{127.0.0.1}{127.0.0.1:55009}{dimr}{rack_id=sonarqube}]}
2021.07.14 08:41:04 INFO  es[][o.e.c.s.ClusterApplierService] master node changed {previous [], current [{sonarqube}{ppdKwbWoTNqUTVCGoGlyEA}{UGHTAa0WS7qy3853ChsYvw}{127.0.0.1}{127.0.0.1:55009}{dimr}{rack_id=sonarqube}]}, term: 21, version: 459, reason: Publication{term=21, version=459}
2021.07.14 08:41:04 INFO  es[][o.e.h.AbstractHttpServerTransport] publish_address {127.0.0.1:9001}, bound_addresses {127.0.0.1:9001}
2021.07.14 08:41:04 INFO  es[][o.e.n.Node] started
2021.07.14 08:41:04 INFO  es[][o.e.g.GatewayService] recovered [7] indices into cluster_state
2021.07.14 08:41:06 INFO  es[][o.e.c.r.a.AllocationService] Cluster health status changed from [RED] to [GREEN] (reason: [shards started [[metadatas][0]]]).

Thanks
Sunil

Hi Sunil,

In fact, that doesn’t look like the same log/error from the first to the second post. Anyway, what do you make of the error in web.log?

 
Ann

What do you mean?
the first part is from Web.log

Hi,

Here’s an excerpt of the error from the first post

Here’s the web.log error from the second post:

 
Ann

java.lang.RuntimeException: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-9000]]
Caused by: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-9000]]

I’m was having a similar error.

2021.08.05 11:05:50 INFO web[o.s.p.ProcessEntryPoint] Starting web
2021.08.05 11:05:51 ERROR web[o.a.c.c.StandardService] Failed to initialize connector [Connector[HTTP/1.1-9000]]

Found this:

It says:

Sonarqube’s default port is always 9000 no matter running it against the jenkins server or local server, but it can be configured if the 9000 port is already in use in your project.

Open the config file under folder / conf /sonar.properties

Web context. When set, it must start with forward slash (for example /sonarqube).

The default value is root context (empty value).#sonar.web.context=# TCP port for incoming HTTP connections. Default value is 9000.

#sonar.web.port=9000

Uncomment this line and change it to the port you like. That is!

It now works.