Sonarqube Process exited with exit value [es]: 143

jpsudre · September 29, 2019, 1:34am

Must-share information (formatted with Markdown):

which versions are you using (SonarQube: sonarqube-7.9.1, Scanner: 4.0.0.1744, Plugin: JAVA jdk-11.0.4_osx-x64, MAC OSX 10.9.5):
what are you trying to achieve
Start sonarqube server
what have you tried so far to achieve this

Launching the server from the terminal and getting: Process exited with exit value [es]: 143
See below. Any help will be appreciated.

Running SonarQube…

wrapper  | --> Wrapper Started as Console
wrapper  | Launching a JVM...
jvm 1    | Wrapper (Version 3.2.3) http://wrapper.tanukisoftware.org
jvm 1    |   Copyright 1999-2006 Tanuki Software, Inc.  All Rights Reserved.
jvm 1    | 
jvm 1    | 2019.09.28 21:12:20 INFO  app[][o.s.a.AppFileSystem] Cleaning or creating temp directory /Applications/SonarQube/temp
jvm 1    | 2019.09.28 21:12:20 INFO  app[][o.s.a.es.EsSettings] Elasticsearch listening on /127.0.0.1:9001
jvm 1    | 2019.09.28 21:12:21 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[[key='es', ipcIndex=1, logFilenamePrefix=es]] from [/Applications/SonarQube/elasticsearch]: /Applications/SonarQube/elasticsearch/bin/elasticsearch
jvm 1    | 2019.09.28 21:12:21 INFO  app[][o.s.a.SchedulerImpl] Waiting for Elasticsearch to be up and running
jvm 1    | 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.
jvm 1    | 2019.09.28 21:12:23 INFO  app[][o.e.p.PluginsService] no modules loaded
jvm 1    | 2019.09.28 21:12:23 INFO  app[][o.e.p.PluginsService] loaded plugin [org.elasticsearch.transport.Netty4Plugin]
jvm 1    | 2019.09.28 21:13:11 INFO  app[][o.s.a.SchedulerImpl] Process[es] is up
jvm 1    | 2019.09.28 21:13:11 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[[key='web', ipcIndex=2, logFilenamePrefix=web]] from [/Applications/SonarQube]: /Library/Java/JavaVirtualMachines/jdk-11.0.4.jdk/Contents/Home/bin/java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djava.io.tmpdir=/Applications/SonarQube/temp --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/common/*:/Applications/SonarQube/lib/jdbc/h2/h2-1.3.176.jar org.sonar.server.app.WebServer /Applications/SonarQube/temp/sq-process5880016969985533031properties
jvm 1    | 2019.09.28 21:13:26 INFO  app[][o.s.a.SchedulerImpl] Process[web] is stopped
jvm 1    | 2019.09.28 21:13:26 WARN  app[][o.s.a.p.AbstractManagedProcess] Process exited with exit value [es]: 143
jvm 1    | 2019.09.28 21:13:26 INFO  app[][o.s.a.SchedulerImpl] Process[es] is stopped
jvm 1    | 2019.09.28 21:13:26 INFO  app[][o.s.a.SchedulerImpl] SonarQube is stopped
wrapper  | <-- Wrapper Stopped

see error log (es.log) below:

2019.09.28 21:12:43 INFO  es[][o.e.e.NodeEnvironment] using [1] data paths, mounts [[/ (/dev/disk0s2)]], net usable_space [88.9gb], net total_space [232gb], types [hfs]
2019.09.28 21:12:43 INFO  es[][o.e.e.NodeEnvironment] heap size [495.3mb], compressed ordinary object pointers [true]
2019.09.28 21:12:43 INFO  es[][o.e.n.Node] node name [sonarqube], node ID [KRFOIqPYRqGM5ra9XJQxlw]
2019.09.28 21:12:43 INFO  es[][o.e.n.Node] version[6.8.0], pid[12987], build[default/tar/65b6179/2019-05-15T20:06:13.172855Z], OS[Mac OS X/10.9.5/x86_64], JVM[Oracle Corporation/Java HotSpot(TM) 64-Bit Server VM/11.0.4/11.0.4+10-LTS]
2019.09.28 21:12:43 INFO  es[][o.e.n.Node] JVM arguments [-XX:+UseConcMarkSweepGC, -XX:CMSInitiatingOccupancyFraction=75, -XX:+UseCMSInitiatingOccupancyOnly, -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, -Dlog4j.shutdownHookEnabled=false, -Dlog4j2.disable.jmx=true, -Djava.io.tmpdir=/Applications/SonarQube/temp, -XX:ErrorFile=../logs/es_hs_err_pid%p.log, -Xms512m, -Xmx512m, -XX:+HeapDumpOnOutOfMemoryError, -Des.path.home=/Applications/SonarQube/elasticsearch, -Des.path.conf=/Applications/SonarQube/temp/conf/es, -Des.distribution.flavor=default, -Des.distribution.type=tar]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] loaded module [analysis-common]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] loaded module [lang-painless]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] loaded module [mapper-extras]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] loaded module [parent-join]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] loaded module [percolator]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] loaded module [reindex]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] loaded module [repository-url]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] loaded module [transport-netty4]
2019.09.28 21:12:48 INFO  es[][o.e.p.PluginsService] no plugins loaded
2019.09.28 21:12:56 WARN  es[][o.e.d.c.s.Settings] [http.enabled] setting was deprecated in Elasticsearch and will be removed in a future release! See the breaking changes documentation for the next major version.
2019.09.28 21:13:02 INFO  es[ ][o.e.d.DiscoveryModule] using discovery type [zen] and host providers [settings]
2019.09.28 21:13:04 INFO  es[][o.e.n.Node] initialized
2019.09.28 21:13:04 INFO  es[][o.e.n.Node] starting ...
2019.09.28 21:13:06 INFO  es[][o.e.t.TransportService] publish_address {127.0.0.1:9001}, bound_addresses {127.0.0.1:9001}
2019.09.28 21:13:10 INFO  es[][o.e.c.s.MasterService] zen-disco-elected-as-master ([0] nodes joined), reason: new_master {sonarqube}{KRFOIqPYRqGM5ra9XJQxlw}{2rQS-dsITFu5Sj4tCwAj4w}{127.0.0.1}{127.0.0.1:9001}{rack_id=sonarqube}
2019.09.28 21:13:10 INFO  es[][o.e.c.s.ClusterApplierService] new_master {sonarqube}{KRFOIqPYRqGM5ra9XJQxlw}{2rQS-dsITFu5Sj4tCwAj4w}{127.0.0.1}{127.0.0.1:9001}{rack_id=sonarqube}, reason: apply cluster state (from master [master {sonarqube}{KRFOIqPYRqGM5ra9XJQxlw}{2rQS-dsITFu5Sj4tCwAj4w}{127.0.0.1}{127.0.0.1:9001}{rack_id=sonarqube} committed version [1] source [zen-disco-elected-as-master ([0] nodes joined)]])
2019.09.28 21:13:10 INFO  es[][o.e.n.Node] started
2019.09.28 21:13:10 INFO  es[][o.e.g.GatewayService] recovered [0] indices into cluster_state
2019.09.28 21:13:26 INFO  es[][o.e.n.Node] stopping ...
2019.09.28 21:13:26 INFO  es[][o.e.n.Node] stopped
2019.09.28 21:13:26 INFO  es[][o.e.n.Node] closing ...
2019.09.28 21:13:26 INFO  es[][o.e.n.Node] closed

Thanks JP

ganncamp · September 30, 2019, 11:58am

Hi JP,

Welcome to the community!

What shows up in web.log?

Ann

jpsudre · October 2, 2019, 11:35am

Hi Ann,

here is the web log:

2019.09.28 20:56:08 INFO  web[][o.s.p.ProcessEntryPoint] Starting web
2019.09.28 20:56:15 ERROR web[][o.s.s.a.EmbeddedTomcat] Fail to start web server
org.apache.catalina.LifecycleException: Failed to initialize component [StandardServer[-1]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:112)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:140)
	at org.apache.catalina.startup.Tomcat.start(Tomcat.java:427)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:65)
	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 component [StandardService[Tomcat]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:112)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:875)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
	... 7 common frames omitted
Caused by: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-80]]
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
	... 9 common frames omitted
2019.09.28 20:56:15 WARN  web[][o.s.p.ProcessEntryPoint] Fail to start web
java.lang.RuntimeException: org.apache.catalina.LifecycleException: Failed to initialize component [StandardServer[-1]]
	at com.google.common.base.Throwables.propagate(Throwables.java:160)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:69)
	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 component [StandardServer[-1]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:112)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:140)
	at org.apache.catalina.startup.Tomcat.start(Tomcat.java:427)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:65)
	... 4 common frames omitted
Caused by: org.apache.catalina.LifecycleException: Failed to initialize component [StandardService[Tomcat]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:112)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:875)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
	... 7 common frames omitted
Caused by: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-80]]
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
	... 9 common frames omitted
2019.09.28 20:56:15 INFO  web[][o.s.p.ProcessEntryPoint] Hard stopping process
2019.09.28 21:13:21 INFO  web[][o.s.p.ProcessEntryPoint] Starting web
2019.09.28 21:13:25 ERROR web[][o.s.s.a.EmbeddedTomcat] Fail to start web server
org.apache.catalina.LifecycleException: Failed to initialize component [StandardServer[-1]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:112)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:140)
	at org.apache.catalina.startup.Tomcat.start(Tomcat.java:427)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:65)
	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 component [StandardService[Tomcat]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:112)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:875)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
	... 7 common frames omitted
Caused by: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-80]]
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
	... 9 common frames omitted
2019.09.28 21:13:25 WARN  web[][o.s.p.ProcessEntryPoint] Fail to start web
java.lang.RuntimeException: org.apache.catalina.LifecycleException: Failed to initialize component [StandardServer[-1]]
	at com.google.common.base.Throwables.propagate(Throwables.java:160)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:69)
	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 component [StandardServer[-1]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:112)
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:140)
	at org.apache.catalina.startup.Tomcat.start(Tomcat.java:427)
	at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:65)
	... 4 common frames omitted
Caused by: org.apache.catalina.LifecycleException: Failed to initialize component [StandardService[Tomcat]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:112)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:875)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
	... 7 common frames omitted
Caused by: org.apache.catalina.LifecycleException: Failed to initialize connector [Connector[HTTP/1.1-80]]
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:107)
	... 9 common frames omitted
2019.09.28 21:13:25 INFO  web[][o.s.p.ProcessEntryPoint] Hard stopping process

Thank you,

JP

sns-seb · October 4, 2019, 10:23am

Hello,

From this line in the web.log, I’m wondering if you’re attempting to start SQ with Web server HTTP port opened on port 80.

If so, I’m guessing this is failing because opening port on values lower than 1024 requires root privileges (at least on Unix, not sure about Mac) and you can’t be doing that with SQ can not run as root.

Could it be it?

gmoglie · February 16, 2020, 10:09pm

Hi! Check in your /opt/sonarqube/conf/sonar.propeties , this line:

sonar.jdbc.url=jdbc:postgresql://localhost/your_db_schema

For example, if you inster this in PG

CREATE DATABASE db_sonarqube;
…
…
\q

Your line is going to be like this:
sonar.jdbc.url=jdbc:postgresql://localhost/db_sonarqube

Bye.