Unable to run SonarCube

Must-share information (formatted with Markdown):

  • which versions are you using (SonarQube, Scanner, Plugin, and any relevant extension)
  • how is SonarQube deployed: zip, Docker, Helm
  • what are you trying to achieve
  • what have you tried so far to achieve this

Do not share screenshots of logs – share the text itself (bonus points for being well-formatted)!

Hi all,

I am trying to run sonarcube (free version) on Windows 7 64 bit OS.

I have extracted the zip file downloaded from the website.

from the command prompt i navigate to run the two batch files available at J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\bin\windows-x86-64

But i get below mentioned error messages

  1. On running SonarService.bat
J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\bin\windows-x86-64>SonarService
.bat
The shell cannot be started. A failure occurred during initialization:
Could not load file or assembly 'System.Data, Version=4.0.0.0, Culture=neutral,
PublicKeyToken=b77a5c561934e089' or one of its dependencies. The module was expe
cted to contain an assembly manifest.
  1. On running StartSonar
J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\bin\windows-x86-64>StartSonar.b
at
Starting SonarQube...
2023.10.02 14:31:10 INFO  app[][o.s.a.AppFileSystem] Cleaning or creating temp d
irectory J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\temp
2023.10.02 14:31:10 INFO  app[][o.s.a.es.EsSettings] Elasticsearch listening on
[HTTP: 127.0.0.1:9001, TCP: 127.0.0.1:50040]
2023.10.02 14:31:11 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[ELASTI
CSEARCH] from [J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch]:
J:\elipse java\bin\java -Xms4m -Xmx64m -XX:+UseSerialGC -Dcli.name=server -Dcli.
script=./bin/elasticsearch -Dcli.libs=lib/tools/server-cli -Des.path.home=J:\son
arqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch -Des.path.conf=J:\sonar
qube-10.2.1.78527\sonarqube-10.2.1.78527\temp\conf\es -Des.distribution.type=tar
 -cp J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch\lib\*;J:\son
arqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch\lib\cli-launcher\* org.
elasticsearch.launcher.CliToolLauncher
2023.10.02 14:31:11 INFO  app[][o.s.a.SchedulerImpl] Waiting for Elasticsearch t
o be up and running

Please tell how to resolve these errors.

Number 1 surely looks like an error while number 2 is asking for some service to be running

Thanking you
Nakul

Hi Nakul,

Let’s focus on scenario 2, with StartSonar. What do your server logs say?

 
Ann

Hi G Ann Campbell,

Thank you for the reply

I navigated to logs folder and found the following files :-
access.log
es.2023-10-01.log
es.2023-10-02.log
es.2023-10-03.log
es.log
README.txt
sonar.2023-10-01.log
sonar.2023-10-02.log
sonar.log
web.log

I am posting the contents of sonar.log

2023.10.04 08:48:47 INFO  app[][o.s.a.AppFileSystem] Cleaning or creating temp directory J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\temp
2023.10.04 08:48:47 INFO  app[][o.s.a.es.EsSettings] Elasticsearch listening on [HTTP: 127.0.0.1:9001, TCP: 127.0.0.1:55247]
2023.10.04 08:48:48 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[ELASTICSEARCH] from [J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch]: J:\elipse java\bin\java -Xms4m -Xmx64m -XX:+UseSerialGC -Dcli.name=server -Dcli.script=./bin/elasticsearch -Dcli.libs=lib/tools/server-cli -Des.path.home=J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch -Des.path.conf=J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\temp\conf\es -Des.distribution.type=tar -cp J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch\lib\*;J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch\lib\cli-launcher\* org.elasticsearch.launcher.CliToolLauncher
2023.10.04 08:48:48 INFO  app[][o.s.a.SchedulerImpl] Waiting for Elasticsearch to be up and running
2023.10.04 08:49:23 INFO  app[][o.s.a.SchedulerImpl] Stopping SonarQube
2023.10.04 08:49:23 INFO  app[][o.s.a.SchedulerImpl] Sonarqube has been requested to stop
2023.10.04 08:49:23 INFO  app[][o.s.a.SchedulerImpl] Stopping [Compute Engine] process...
2023.10.04 08:49:23 INFO  app[][o.s.a.SchedulerImpl] Stopping [Web Server] process...
2023.10.04 08:49:23 INFO  app[][o.s.a.SchedulerImpl] Stopping [ElasticSearch] process...
2023.10.04 08:49:23 ERROR app[][o.s.a.p.EsManagedProcess] Failed to check status
org.elasticsearch.ElasticsearchException: java.lang.InterruptedException
	at org.elasticsearch.client.RestHighLevelClient.performClientRequest(RestHighLevelClient.java:2695)
	at org.elasticsearch.client.RestHighLevelClient.internalPerformRequest(RestHighLevelClient.java:2171)
	at org.elasticsearch.client.RestHighLevelClient.performRequest(RestHighLevelClient.java:2137)
	at org.elasticsearch.client.RestHighLevelClient.performRequestAndParseEntity(RestHighLevelClient.java:2105)
	at org.elasticsearch.client.ClusterClient.health(ClusterClient.java:151)
	at org.sonar.application.es.EsConnectorImpl.getClusterHealthStatus(EsConnectorImpl.java:79)
	at org.sonar.application.process.EsManagedProcess.checkStatus(EsManagedProcess.java:92)
	at org.sonar.application.process.EsManagedProcess.checkOperational(EsManagedProcess.java:84)
	at org.sonar.application.process.EsManagedProcess.isOperational(EsManagedProcess.java:62)
	at org.sonar.application.process.ManagedProcessHandler.refreshState(ManagedProcessHandler.java:223)
	at org.sonar.application.process.ManagedProcessHandler$EventWatcher.run(ManagedProcessHandler.java:288)
Caused by: java.lang.InterruptedException: null
	at java.base/java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1048)
	at org.elasticsearch.common.util.concurrent.BaseFuture$Sync.get(BaseFuture.java:243)
	at org.elasticsearch.common.util.concurrent.BaseFuture.get(BaseFuture.java:75)
	at org.elasticsearch.client.RestHighLevelClient.performClientRequest(RestHighLevelClient.java:2692)
	... 10 common frames omitted
2023.10.04 08:50:16 INFO  app[][o.s.a.SchedulerImpl] Process[ElasticSearch] is stopped
2023.10.04 08:50:16 INFO  app[][o.s.a.SchedulerImpl] SonarQube is stopped

Hi,

These logs indicate a problem with Elasticsearch. What shows up in es.log?

 
Ann

Hi,

es.log has following contents

2023.10.04 08:49:36 INFO  es[][o.e.n.Node] version[8.7.0], pid[2160], build[tar/09520b59b6bc1057340b55750186466ea715e30e/2023-03-27T16:31:09.816451435Z], OS[Windows 7/6.1/amd64], JVM[Eclipse Adoptium/OpenJDK 64-Bit Server VM/17.0.8/17.0.8+7]
2023.10.04 08:49:36 INFO  es[][o.e.n.Node] JVM home [J:\elipse java], using bundled JDK [false]
2023.10.04 08:49:36 INFO  es[][o.e.n.Node] JVM arguments [-Des.networkaddress.cache.ttl=60, -Des.networkaddress.cache.negative.ttl=10, -Djava.security.manager=allow, -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, -Dlog4j2.formatMsgNoLookups=true, -Djava.locale.providers=SPI,COMPAT, --add-opens=java.base/java.io=ALL-UNNAMED, -XX:+UseG1GC, -Djava.io.tmpdir=J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\temp, -XX:ErrorFile=J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\logs\es_hs_err_pid%p.log, -Xlog:disable, -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, -Djna.tmpdir=J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\temp, -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, -Dlog4j2.formatMsgNoLookups=true, -Djava.locale.providers=COMPAT, -Dcom.redhat.fips=false, -Xmx512m, -Xms512m, -XX:MaxDirectMemorySize=256m, -XX:+HeapDumpOnOutOfMemoryError, -XX:G1HeapRegionSize=4m, -XX:InitiatingHeapOccupancyPercent=30, -XX:G1ReservePercent=15, -Des.distribution.type=tar, --module-path=J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch\lib, --add-modules=jdk.net, -Djdk.module.main=org.elasticsearch.server]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [aggregations]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [analysis-common]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [apm]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [blob-cache]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [lang-painless]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [old-lucene-versions]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [parent-join]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [reindex]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [transport-netty4]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [x-pack-aggregate-metric]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [x-pack-core]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [x-pack-profiling]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] loaded module [x-pack-security]
2023.10.04 08:49:48 INFO  es[][o.e.p.PluginsService] no plugins loaded
2023.10.04 08:50:02 INFO  es[][o.e.e.NodeEnvironment] using [1] data paths, mounts [[MIS (J:)]], net usable_space [3.9gb], net total_space [48.8gb], types [NTFS]
2023.10.04 08:50:02 INFO  es[][o.e.e.NodeEnvironment] heap size [512mb], compressed ordinary object pointers [true]
2023.10.04 08:50:02 INFO  es[][o.e.n.Node] node name [sonarqube], node ID [ugf2J7NTSPWaBJq_zwdDYQ], cluster name [sonarqube], roles [ml, data_hot, transform, data_content, data_warm, master, remote_cluster_client, data, data_cold, ingest, data_frozen]
2023.10.04 08:50:07 INFO  es[][o.e.x.p.ProfilingPlugin] Profiling is enabled
2023.10.04 08:50:08 INFO  es[][o.e.x.s.Security] Security is disabled
2023.10.04 08:50:08 INFO  es[][o.e.t.n.NettyAllocator] creating NettyAllocator with the following configs: [name=unpooled, suggested_max_allocation_size=1mb, factors={es.unsafe.use_unpooled_allocator=null, g1gc_enabled=true, g1gc_region_size=4mb, heap_size=512mb}]
2023.10.04 08:50:08 INFO  es[][o.e.i.r.RecoverySettings] using rate limit [40mb] with [default=40mb, read=0b, write=0b, max=0b]
2023.10.04 08:50:09 INFO  es[][o.e.d.DiscoveryModule] using discovery type [single-node] and seed hosts providers [settings]
2023.10.04 08:50:11 INFO  es[][o.e.n.Node] initialized
2023.10.04 08:50:11 INFO  es[][o.e.n.Node] starting ...
2023.10.04 08:50:11 INFO  es[][o.e.t.TransportService] publish_address {127.0.0.1:55247}, bound_addresses {127.0.0.1:55247}
2023.10.04 08:50:13 WARN  es[][o.e.c.c.ClusterBootstrapService] this node is locked into cluster UUID [NLWCMLu6TjS_oDDmCyiXQQ] but [cluster.initial_master_nodes] is set to [sonarqube]; remove this setting to avoid possible data loss caused by subsequent cluster bootstrap attempts; for further information see https://www.elastic.co/guide/en/elasticsearch/reference/8.7/important-settings.html#initial_master_nodes
2023.10.04 08:50:14 INFO  es[][o.e.c.s.MasterService] elected-as-master ([1] nodes joined)[_FINISH_ELECTION_, {sonarqube}{ugf2J7NTSPWaBJq_zwdDYQ}{lBliWGh9SZevkNl8UEli0A}{sonarqube}{127.0.0.1}{127.0.0.1:55247}{cdfhilmrstw}{8.7.0} completing election], term: 3, version: 14, delta: master node changed {previous [], current [{sonarqube}{ugf2J7NTSPWaBJq_zwdDYQ}{lBliWGh9SZevkNl8UEli0A}{sonarqube}{127.0.0.1}{127.0.0.1:55247}{cdfhilmrstw}{8.7.0}]}
2023.10.04 08:50:14 INFO  es[][o.e.c.s.ClusterApplierService] master node changed {previous [], current [{sonarqube}{ugf2J7NTSPWaBJq_zwdDYQ}{lBliWGh9SZevkNl8UEli0A}{sonarqube}{127.0.0.1}{127.0.0.1:55247}{cdfhilmrstw}{8.7.0}]}, term: 3, version: 14, reason: Publication{term=3, version=14}
2023.10.04 08:50:15 INFO  es[][o.e.r.s.FileSettingsService] starting file settings watcher ...
2023.10.04 08:50:16 INFO  es[][o.e.h.AbstractHttpServerTransport] publish_address {127.0.0.1:9001}, bound_addresses {127.0.0.1:9001}
2023.10.04 08:50:16 INFO  es[][o.e.n.Node] started {sonarqube}{ugf2J7NTSPWaBJq_zwdDYQ}{lBliWGh9SZevkNl8UEli0A}{sonarqube}{127.0.0.1}{127.0.0.1:55247}{cdfhilmrstw}{8.7.0}{xpack.installed=true, rack_id=sonarqube}
2023.10.04 08:50:16 INFO  es[][o.e.c.c.NodeJoinExecutor] node-join: [{sonarqube}{ugf2J7NTSPWaBJq_zwdDYQ}{lBliWGh9SZevkNl8UEli0A}{sonarqube}{127.0.0.1}{127.0.0.1:55247}{cdfhilmrstw}{8.7.0}] with reason [completing election]
2023.10.04 08:50:16 INFO  es[][o.e.r.s.FileSettingsService] file settings service up and running [tid=33]
2023.10.04 08:50:16 INFO  es[][o.e.n.Node] stopping ...
2023.10.04 08:50:16 INFO  es[][o.e.r.s.FileSettingsService] shutting down watcher thread
2023.10.04 08:50:16 INFO  es[][o.e.r.s.FileSettingsService] watcher service stopped
2023.10.04 08:50:16 INFO  es[][o.e.n.Node] stopped
2023.10.04 08:50:16 INFO  es[][o.e.n.Node] closing ...
2023.10.04 08:50:16 INFO  es[][o.e.n.Node] closed

Hi,

Well, that’s all normal.

Whats left are web.log (the likeliest candidate) and ce.log. Anything there?

 
Ann

Hi

web.log has following contents:-


2023.10.01 20:59:28 INFO  web[][o.s.p.ProcessEntryPoint] Starting Web Server
2023.10.01 20:59:41 INFO  web[][o.s.s.p.LogServerVersion] SonarQube Server / 10.2.1.78527 / 953bf4f6bc7c449ecd33704e5fb450c19412f037
2023.10.01 20:59:42 INFO  web[][o.s.s.p.d.EmbeddedDatabase] Starting embedded database on port 9092 with url jdbc:h2:tcp://127.0.0.1:9092/sonar;NON_KEYWORDS=VALUE
2023.10.01 20:59:43 INFO  web[][o.s.s.p.d.EmbeddedDatabase] Embedded database started. Data stored in: J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data
2023.10.01 20:59:43 INFO  web[][o.s.d.DefaultDatabase] Create JDBC data source for jdbc:h2:tcp://127.0.0.1:9092/sonar;NON_KEYWORDS=VALUE
2023.10.01 20:59:43 INFO  web[][c.z.h.HikariDataSource] HikariPool-1 - Starting...
2023.10.01 20:59:43 INFO  web[][c.z.h.p.HikariPool] HikariPool-1 - Added connection conn2: url=jdbc:h2:tcp://127.0.0.1:9092/sonar user=
2023.10.01 20:59:43 INFO  web[][c.z.h.HikariDataSource] HikariPool-1 - Start completed.
2023.10.01 20:59:43 WARN  web[][o.s.db.dialect.H2] H2 database should be used for evaluation purpose only.
2023.10.01 20:59:46 INFO  web[][o.s.s.p.ServerFileSystemImpl] SonarQube home: J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527
2023.10.01 20:59:46 INFO  web[][o.s.s.u.SystemPasscodeImpl] System authentication by passcode is disabled
2023.10.01 20:59:47 INFO  web[][o.s.s.p.d.m.h.MigrationHistoryTableImpl] Creating table schema_migrations
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy C# Code Quality and Security / 9.8.0.76515 / c1515bad8ebe3e38e102b68fdec8c429669ec560
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Clean as You Code / 2.1.0.500 / 4a2d47cf125d03ebacf43536a3897c168deb1b0a
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Configuration detection for Code Quality and Security / 1.3.0.654 / 63073f0270b2c4754afa58eb8b5ea04e2eebf1a4
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Flex Code Quality and Security / 2.10.0.3458 / 3ef14c50cfd03e5b40a2270fc6e8edc5c49dedcd
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Go Code Quality and Security / 1.14.0.4481 / dcfff811316898a16bf1c6ff191dd3a5d84d3307
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy HTML Code Quality and Security / 3.9.0.3600 / c877f85377cbf43c325a924dff566e2511142dbd
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy IaC Code Quality and Security / 1.20.0.5654 / 45bdf29bcd98938351390d5b5905ee16e4616078
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy JaCoCo / 1.3.0.1538 / 74a7798c7cea687c72ed9df40c93eb7ea2a58c49
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Java Code Quality and Security / 7.24.0.32100 / c39dafc9f60909db5edeb18ecc30ef617d9b5e7d
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy JavaScript/TypeScript/CSS Code Quality and Security / 10.5.1.22382 / aee0b6ea75d253c96e97ab00eb1e96e1112ec2a7
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Kotlin Code Quality and Security / 2.17.0.2902 / f561c3df92dca4379ae526fc4c912f786328f009
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy PHP Code Quality and Security / 3.32.0.10180 / 077f75886ec0b948cdf94b61631a444bcd23a529
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Python Code Quality and Security / 4.7.0.12181 / eeaa409efe993ea779a0dacdbbe8e28e4879c975
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Ruby Code Quality and Security / 1.14.0.4481 / dcfff811316898a16bf1c6ff191dd3a5d84d3307
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Scala Code Quality and Security / 1.14.0.4481 / dcfff811316898a16bf1c6ff191dd3a5d84d3307
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy Text Code Quality and Security / 2.3.0.1632 / 66d70521dd5e12993a5614c3202f4c33ae0182bd
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy VB.NET Code Quality and Security / 9.8.0.76515 / c1515bad8ebe3e38e102b68fdec8c429669ec560
2023.10.01 20:59:51 INFO  web[][o.s.s.p.ServerPluginManager] Deploy XML Code Quality and Security / 2.10.0.4108 / 34b16ba369117f6befc0b1e0866a069a80f6db2e
2023.10.01 21:00:13 INFO  web[][o.s.s.e.EsClientProvider] Connected to local Elasticsearch: [http://nakul-PC:9001]
2023.10.01 21:00:14 WARN  web[][o.s.a.s.w.WebService$Action] Description is not set on action api/monitoring/metrics
2023.10.01 21:00:14 WARN  web[][o.s.a.s.w.WebService$Action] Since is not set on action api/monitoring/metrics
2023.10.01 21:00:14 WARN  web[][o.s.a.s.w.WebService$Action] The response example is not set on action api/monitoring/metrics
2023.10.01 21:00:14 WARN  web[][o.s.a.s.w.WebService$Action] The response example is not set on action api/system/liveness
2023.10.01 21:00:14 INFO  web[][o.s.s.p.d.m.AutoDbMigration] Automatically perform DB migration on fresh install
2023.10.01 21:00:14 INFO  web[][DbMigrations] Executing DB migrations...
2023.10.01 21:00:14 INFO  web[][DbMigrations] #1 'Create initial schema'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #1 'Create initial schema': success | time=446ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #2 'Populate initial schema'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #2 'Populate initial schema': success | time=82ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100000 'Remove orphan rules in Quality Profiles'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100000 'Remove orphan rules in Quality Profiles': success | time=40ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100001 'Drop index 'projects_module_uuid' in the 'Components' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100001 'Drop index 'projects_module_uuid' in the 'Components' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100002 'Drop column 'module_uuid' in the 'Components' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100002 'Drop column 'module_uuid' in the 'Components' table': success | time=22ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100003 'Drop column 'module_uuid_path' in the 'Components' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100003 'Drop column 'module_uuid_path' in the 'Components' table': success | time=15ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100004 'Drop column 'b_module_uuid' in the 'Components' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100004 'Drop column 'b_module_uuid' in the 'Components' table': success | time=16ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100005 'Drop column 'b_module_uuid_path' in the 'Components' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100005 'Drop column 'b_module_uuid_path' in the 'Components' table': success | time=15ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100006 'Drop index 'projects_root_uuid' in the 'Components' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100006 'Drop index 'projects_root_uuid' in the 'Components' table': success | time=5ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100007 'Drop column 'root_uuid' in the 'Components' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100007 'Drop column 'root_uuid' in the 'Components' table': success | time=18ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100008 'Update value of 'user_local' in the 'users' table'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100008 'Update value of 'user_local' in the 'users' table': success | time=35ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100009 'Make column 'user_local' not nullable in the 'users' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100009 'Make column 'user_local' not nullable in the 'users' table': success | time=20ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100010 'Create 'scim_groups' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100010 'Create 'scim_groups' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100011 'Create unique index on scim_groups.group_uuid'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100011 'Create unique index on scim_groups.group_uuid': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100012 'Log a warning message if 'sonar.scim.enabled' is used'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100012 'Log a warning message if 'sonar.scim.enabled' is used': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100013 'Drop 'sonar.scim.enabled' property'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100013 'Drop 'sonar.scim.enabled' property': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100014 'Drop any SCIM User provisioning, turning all users local'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100014 'Drop any SCIM User provisioning, turning all users local': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100015 'Add ncloc to 'Projects' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100015 'Add ncloc to 'Projects' table': success | time=20ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100016 'Populate ncloc in 'Projects' table'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #100016 'Populate ncloc in 'Projects' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101000 'Add 'scm_accounts' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101000 'Add 'scm_accounts' table': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101001 'Migrate scm accounts from 'users' to 'scm_accounts' table'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassRowSplitter] 1 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101001 'Migrate scm accounts from 'users' to 'scm_accounts' table': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101002 'Add index on 'scm_accounts.scm_account''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101002 'Add index on 'scm_accounts.scm_account'': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101003 'Add index on 'users.email''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101003 'Add index on 'users.email'': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101004 'Drop 'scm_accounts' column in 'users' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101004 'Drop 'scm_accounts' column in 'users' table': success | time=20ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101005 'Add column 'is_main' to 'project_branches' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101005 'Add column 'is_main' to 'project_branches' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101006 'Update value of 'is_main' in 'project_branches' table'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101006 'Update value of 'is_main' in 'project_branches' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101007 'Alter column 'is_main' in 'project_branches' table - make it not nullable'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101007 'Alter column 'is_main' in 'project_branches' table - make it not nullable': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101008 'Increase size of 'internal_properties.kee' from 20 to 40 characters'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101008 'Increase size of 'internal_properties.kee' from 20 to 40 characters': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101009 'Create column 'project_uuid' in 'user_tokens'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101009 'Create column 'project_uuid' in 'user_tokens': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101010 'Remove user tokens linked to unexistent project'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101010 'Remove user tokens linked to unexistent project': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101011 'Populate 'project_key' in 'user_tokens''...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101011 'Populate 'project_key' in 'user_tokens'': success | time=8ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101012 'Drop column 'project_key' in 'user_tokens'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101012 'Drop column 'project_key' in 'user_tokens': success | time=9ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101013 'Increase size of 'ce_queue.task_type' from 15 to 40 characters'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101013 'Increase size of 'ce_queue.task_type' from 15 to 40 characters': success | time=3ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101014 'Increase size of 'ce_activity.task_type' from 15 to 40 characters'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101014 'Increase size of 'ce_activity.task_type' from 15 to 40 characters': success | time=3ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101015 'Add 'external_groups' table.'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101015 'Add 'external_groups' table.': success | time=6ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101016 'Add index on 'external_groups(external_identity_provider, external_id).'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101016 'Add index on 'external_groups(external_identity_provider, external_id).': success | time=5ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101017 'Add 'code_variants' column in 'issues' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101017 'Add 'code_variants' column in 'issues' table': success | time=12ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101018 'Fix different uuids for subportfolios'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101018 'Fix different uuids for subportfolios': success | time=9ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101019 'Add report_schedules table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101019 'Add report_schedules table': success | time=4ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101020 'Add report_subscriptions table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101020 'Add report_subscriptions table': success | time=5ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101021 'Add report_schedules unique index'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101021 'Add report_schedules unique index': success | time=3ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101022 'Add report_subscriptions unique index'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101022 'Add report_subscriptions unique index': success | time=2ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101023 'Rename column 'component_uuid' to 'entity_uuid' in the 'properties' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101023 'Rename column 'component_uuid' to 'entity_uuid' in the 'properties' table': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101024 'Populate report_schedules table'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101024 'Populate report_schedules table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101025 'Populate report_subscriptions table'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101025 'Populate report_subscriptions table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101026 'Remove report properties'...
2023.10.01 21:00:15 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:15 INFO  web[][DbMigrations] #101026 'Remove report properties': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102000 'Rename 'component_uuid' in 'user_roles' table to 'entity_uuid''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102000 'Rename 'component_uuid' in 'user_roles' table to 'entity_uuid'': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102001 'Rename 'component_uuid' in 'group_roles' table to 'entity_uuid''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102001 'Rename 'component_uuid' in 'group_roles' table to 'entity_uuid'': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102002 'Drop index 'group_roles_component_uuid' in 'group_roles''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102002 'Drop index 'group_roles_component_uuid' in 'group_roles'': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102003 'Create index 'entity_uuid_user_roles' in 'group_roles' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102003 'Create index 'entity_uuid_user_roles' in 'group_roles' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102004 'Drop index 'user_roles_component_uuid' in 'user_roles' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102004 'Drop index 'user_roles_component_uuid' in 'user_roles' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102005 'Create index 'user_roles_entity_uuid' in 'user_roles''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102005 'Create index 'user_roles_entity_uuid' in 'user_roles'': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102006 'Drop index 'ce_activity_component' in 'ce_activity''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102006 'Drop index 'ce_activity_component' in 'ce_activity'': success | time=13ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102007 'Rename 'main_component_uuid' in 'ce_activity' table to 'entity_uuid''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102007 'Rename 'main_component_uuid' in 'ce_activity' table to 'entity_uuid'': success | time=7ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102008 'Create index 'ce_activity_entity_uuid' in 'ce_activity' table''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102008 'Create index 'ce_activity_entity_uuid' in 'ce_activity' table'': success | time=3ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102009 'Drop index 'ce_queue_main_component' in 'ce_queue' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102009 'Drop index 'ce_queue_main_component' in 'ce_queue' table': success | time=3ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102010 'Rename 'main_component_uuid' in 'ce_queue' table to 'entity_uuid''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102010 'Rename 'main_component_uuid' in 'ce_queue' table to 'entity_uuid'': success | time=1ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102011 'Create index 'ce_queue_entity_uuid' in 'ce_queue' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102011 'Create index 'ce_queue_entity_uuid' in 'ce_queue' table': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102012 'Drop 'project_mappings' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102012 'Drop 'project_mappings' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102013 'Drop index on 'components.main_branch_project_uuid'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102013 'Drop index on 'components.main_branch_project_uuid': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102014 'Drop column 'main_branch_project_uuid' in the components table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102014 'Drop column 'main_branch_project_uuid' in the components table': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102015 'Drop index 'component_uuid' in 'webhook_deliveries' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102015 'Drop index 'component_uuid' in 'webhook_deliveries' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102016 'Rename 'component_uuid' in 'webhook_deliveries' table to 'project_uuid''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102016 'Rename 'component_uuid' in 'webhook_deliveries' table to 'project_uuid'': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102018 'Drop index 'component_uuid' in 'snapshots' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102018 'Drop index 'component_uuid' in 'snapshots' table': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102019 'Rename 'component_uuid' in 'snapshots' table to 'root_component_uuid''...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102019 'Rename 'component_uuid' in 'snapshots' table to 'root_component_uuid'': success | time=0ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102020 'Create index 'snapshots_root_component_uuid' in 'snapshots' table'...
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102020 'Create index 'snapshots_root_component_uuid' in 'snapshots' table': success | time=10ms
2023.10.01 21:00:15 INFO  web[][DbMigrations] #102021 'Create 'purged' column in 'snapshots' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102021 'Create 'purged' column in 'snapshots' table': success | time=8ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102022 'Populate 'purged' column in 'snapshots' table'...
2023.10.01 21:00:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102022 'Populate 'purged' column in 'snapshots' table': success | time=13ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102023 'Make 'purged' column not nullable in 'snapshots' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102023 'Make 'purged' column not nullable in 'snapshots' table': success | time=6ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102024 'Drop 'purge_status' column in 'snapshots' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102024 'Drop 'purge_status' column in 'snapshots' table': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102025 'Rename 'build_date' in 'snapshots' table to 'analysis_date'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102025 'Rename 'build_date' in 'snapshots' table to 'analysis_date': success | time=11ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102030 'Create table 'anticipated_transitions''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102030 'Create table 'anticipated_transitions'': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102031 'Increase size of 'ce_queue.is_last_key' from 55 to 80 characters'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102031 'Increase size of 'ce_queue.is_last_key' from 55 to 80 characters': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102032 'Increase size of 'ce_queue.main_is_last_key' from 55 to 80 characters'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102032 'Increase size of 'ce_queue.main_is_last_key' from 55 to 80 characters': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102033 'Add column 'clean_code_attribute' in 'rules' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102033 'Add column 'clean_code_attribute' in 'rules' table': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102034 'Populate 'clean_code_attribute' column in 'rules' table'...
2023.10.01 21:00:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102034 'Populate 'clean_code_attribute' column in 'rules' table': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102036 'Create 'rules_default_impacts' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102036 'Create 'rules_default_impacts' table': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102037 'Create unique constraint index on 'rules_default_impacts' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102037 'Create unique constraint index on 'rules_default_impacts' table': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102038 'Create 'issues_impacts' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102038 'Create 'issues_impacts' table': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102039 'Create unique constraint index on 'issues_impacts' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102039 'Create unique constraint index on 'issues_impacts' table': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102040 'Populate default impacts for existing rules'...
2023.10.01 21:00:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102040 'Populate default impacts for existing rules': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102041 'Fix sqale_index metric description in 'metrics' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102041 'Fix sqale_index metric description in 'metrics' table': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102042 'Create table 'github_orgs_groups''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102042 'Create table 'github_orgs_groups'': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102043 'Create 'previous_non_compliant_value' in 'new_code_periods' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102043 'Create 'previous_non_compliant_value' in 'new_code_periods' table': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102044 'Update column 'value' and populate column 'previous_non_compliant_value' in 'new_code_periods' table'...
2023.10.01 21:00:16 INFO  web[][o.s.s.p.d.m.s.MassUpdate] 0 rows processed (0 items/sec)
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102044 'Update column 'value' and populate column 'previous_non_compliant_value' in 'new_code_periods' table': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102045 'Alter 'project_uuid' in 'user_dismissed_messages' - make it nullable'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102045 'Alter 'project_uuid' in 'user_dismissed_messages' - make it nullable': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102046 'Create index 'project_branches_project_uuid' in 'project_branches' table'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102046 'Create index 'project_branches_project_uuid' in 'project_branches' table': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102047 'Drop index 'idx_wbhk_dlvrs_wbhk_uuid' in 'webhook_deliveries''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102047 'Drop index 'idx_wbhk_dlvrs_wbhk_uuid' in 'webhook_deliveries'': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102048 'Create index 'wb_webhook_uuid_created_at' in 'webhook_deliveries''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102048 'Create index 'wb_webhook_uuid_created_at' in 'webhook_deliveries'': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102049 'Drop index 'wd_project_uuid' in 'webhook_deliveries''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102049 'Drop index 'wd_project_uuid' in 'webhook_deliveries'': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102050 'Create index 'wd_project_uuid_created_at' in 'webhook_deliveries''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102050 'Create index 'wd_project_uuid_created_at' in 'webhook_deliveries'': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102051 'Drop index 'ce_task_uuid' in 'webhook_deliveries''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102051 'Drop index 'ce_task_uuid' in 'webhook_deliveries'': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102052 'Create index 'wd_task_uuid_created_at' in 'webhook_deliveries''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102052 'Create index 'wd_task_uuid_created_at' in 'webhook_deliveries'': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102053 'Create index 'wd_created_at' in 'webhook_deliveries''...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102053 'Create index 'wd_created_at' in 'webhook_deliveries'': success | time=0ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102054 'Insert property github.userConsentementForPermissionProvisioningRequired'...
2023.10.01 21:00:16 INFO  web[][DbMigrations] #102054 'Insert property github.userConsentementForPermissionProvisioningRequired': success | time=10ms
2023.10.01 21:00:16 INFO  web[][DbMigrations] Executed DB migrations: success | time=1297ms
2023.10.01 21:00:16 INFO  web[][o.s.s.p.Platform] Database has been automatically updated
2023.10.01 21:00:16 INFO  web[][o.s.s.p.w.MasterServletFilter] Initializing servlet filter org.sonar.server.platform.web.WebServiceFilter@1e120628 [pattern=UrlPattern{inclusions=[/api/system/migrate_db.*, ...], exclusions=[/api/components/update_key, ...]}]
2023.10.01 21:00:16 INFO  web[][o.s.s.p.DetectPluginChange] Detect plugin changes
2023.10.01 21:00:37 INFO  web[][o.h.v.i.util.Version] HV000001: Hibernate Validator null
2023.10.01 21:00:37 INFO  web[][o.s.s.e.IndexCreator] Create index [metadatas]
2023.10.01 21:00:39 INFO  web[][o.s.s.a.EmbeddedTomcat] HTTP connector enabled on port 9000
2023.10.01 21:01:39 INFO  web[][o.s.s.e.IndexCreator] Create mapping metadatas
2023.10.01 21:02:11 WARN  web[][o.s.c.a.AnnotationConfigApplicationContext] Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'jdk.internal.loader.ClassLoaders$AppClassLoader@659e0bfd-org.sonar.server.es.IndexCreator': Initialization of bean failed; nested exception is org.sonar.server.es.ElasticsearchException: Fail to execute es requestES get request for key 'dbVendor' on index 'metadatas'
2023.10.01 21:02:11 ERROR web[][o.s.s.p.Platform] Background initialization failed. Stopping SonarQube
org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'jdk.internal.loader.ClassLoaders$AppClassLoader@659e0bfd-org.sonar.server.es.IndexCreator': Initialization of bean failed; nested exception is org.sonar.server.es.ElasticsearchException: Fail to execute es requestES get request for key 'dbVendor' on index 'metadatas'
	at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:628)
	at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:542)
	at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:335)
	at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234)
	at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:333)
	at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:208)
	at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:955)
	at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:920)
	at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:583)
	at org.sonar.core.platform.SpringComponentContainer.startComponents(SpringComponentContainer.java:187)
	at org.sonar.server.platform.platformlevel.PlatformLevel.start(PlatformLevel.java:80)
	at org.sonar.server.platform.platformlevel.PlatformLevel4.start(PlatformLevel4.java:662)
	at org.sonar.server.platform.PlatformImpl.start(PlatformImpl.java:214)
	at org.sonar.server.platform.PlatformImpl.startLevel34Containers(PlatformImpl.java:194)
	at org.sonar.server.platform.PlatformImpl$AutoStarterRunnable.runIfNotAborted(PlatformImpl.java:362)
	at org.sonar.server.platform.PlatformImpl$1.doRun(PlatformImpl.java:113)
	at org.sonar.server.platform.PlatformImpl$AutoStarterRunnable.run(PlatformImpl.java:346)
	at java.base/java.lang.Thread.run(Thread.java:833)
Caused by: org.sonar.server.es.ElasticsearchException: Fail to execute es requestES get request for key 'dbVendor' on index 'metadatas'
	at org.sonar.server.es.EsClient.execute(EsClient.java:336)
	at org.sonar.server.es.EsClient.get(EsClient.java:182)
	at org.sonar.server.es.metadata.MetadataIndexImpl.getMetadata(MetadataIndexImpl.java:92)
	at org.sonar.server.es.metadata.MetadataIndexImpl.getDbVendor(MetadataIndexImpl.java:83)
	at org.sonar.server.es.metadata.EsDbCompatibilityImpl.hasSameDbVendor(EsDbCompatibilityImpl.java:37)
	at org.sonar.server.es.metadata.EsDbCompatibilityImpl.markAsCompatible(EsDbCompatibilityImpl.java:43)
	at org.sonar.server.es.IndexCreator.checkDbCompatibility(IndexCreator.java:191)
	at org.sonar.server.es.IndexCreator.start(IndexCreator.java:87)
	at org.sonar.core.platform.StartableBeanPostProcessor.postProcessBeforeInitialization(StartableBeanPostProcessor.java:33)
	at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyBeanPostProcessorsBeforeInitialization(AbstractAutowireCapableBeanFactory.java:440)
	at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1796)
	at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:620)
	... 17 common frames omitted
Caused by: org.elasticsearch.ElasticsearchStatusException: Elasticsearch exception [type=no_shard_available_action_exception, reason=No shard available for [get [metadatas][dbVendor]: routing [null]]]
	at org.elasticsearch.rest.BytesRestResponse.errorFromXContent(BytesRestResponse.java:178)
	at org.elasticsearch.client.RestHighLevelClient.parseEntity(RestHighLevelClient.java:2484)
	at org.elasticsearch.client.RestHighLevelClient.parseResponseException(RestHighLevelClient.java:2461)
	at org.elasticsearch.client.RestHighLevelClient.internalPerformRequest(RestHighLevelClient.java:2184)
	at org.elasticsearch.client.RestHighLevelClient.performRequest(RestHighLevelClient.java:2137)
	at org.elasticsearch.client.RestHighLevelClient.performRequestAndParseEntity(RestHighLevelClient.java:2105)
	at org.elasticsearch.client.RestHighLevelClient.get(RestHighLevelClient.java:986)
	at org.sonar.server.es.EsClient.lambda$get$17(EsClient.java:182)
	at org.sonar.server.es.EsClient.execute(EsClient.java:334)
	... 28 common frames omitted
	Suppressed: org.elasticsearch.client.ResponseException: method [GET], host [http://nakul-PC:9001], URI [/metadatas/_doc/dbVendor?stored_fields=value], status line [HTTP/1.1 503 Service Unavailable]
{"error":{"root_cause":[{"type":"no_shard_available_action_exception","reason":"No shard available for [get [metadatas][dbVendor]: routing [null]]"}],"type":"no_shard_available_action_exception","reason":"No shard available for [get [metadatas][dbVendor]: routing [null]]"},"status":503}
		at org.elasticsearch.client.RestClient.convertResponse(RestClient.java:347)
		at org.elasticsearch.client.RestClient.performRequest(RestClient.java:313)
		at org.elasticsearch.client.RestClient.performRequest(RestClient.java:288)
		at org.elasticsearch.client.RestHighLevelClient.performClientRequest(RestHighLevelClient.java:2699)
		at org.elasticsearch.client.RestHighLevelClient.internalPerformRequest(RestHighLevelClient.java:2171)
		... 33 common frames omitted
2023.10.01 21:02:12 INFO  web[][o.s.p.ProcessEntryPoint] Hard stopping process
2023.10.01 21:02:12 INFO  web[][c.z.h.HikariDataSource] HikariPool-1 - Shutdown initiated...
2023.10.01 21:02:12 INFO  web[][c.z.h.HikariDataSource] HikariPool-1 - Shutdown completed.
2023.10.01 21:02:12 INFO  web[][o.s.s.p.d.EmbeddedDatabase] Embedded database stopped

  1. There is no file named

Hi,

I don’t understand why we don’t see this in es.log, but I think this is the root cause:

What’s the percentage of free disc space on the SonarQube host? Elasticsearch wants at least 5% free, IIRC (regardless of how much space that actually represents).

Can you free up some disc space and restart?

 
Ann

I had downloaded the sonarcube from the website

sonarcube download link

I Extracted the contents from zip file in a drive which has total 48 GB space and 4.58 GB free space.

I am posting the contents of es.log also

`

2023.10.03 17:10:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:11:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:12:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:13:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:14:48 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:16:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:17:48 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:19:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:20:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:21:48 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:23:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:24:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:25:48 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:26:48 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:27:48 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:29:18 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:30:22 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:31:52 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:33:22 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:34:22 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:35:52 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:36:52 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:38:22 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:39:52 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:40:52 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:42:22 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete
2023.10.03 17:43:22 WARN  es[][o.e.c.r.a.DiskThresholdMonitor] high disk watermark [90%] exceeded on [ugf2J7NTSPWaBJq_zwdDYQ][sonarqube][J:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\data\es8] free: 3.9gb[8%], shards will be relocated away from this node; currently relocating away shards totalling [0] bytes; the node is expected to continue to exceed the high disk watermark when these relocations are complete


`

this is part of the log. complete log cannot be pasted here. the limit of characters is 50000 only

HI,

The log tells the story. Free up some disc space and you should be good to go.

 
HTH,
Ann

Hi

I have freed the sufficient disk space and ran

  1. StartSonar.bat

  2. SonarService.bat(in this order)

Upon running SonarService i get below error


F:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\bin\windows-x86-64>SonarService
.bat
The shell cannot be started. A failure occurred during initialization:
Could not load file or assembly 'System.Data, Version=4.0.0.0, Culture=neutral,
PublicKeyToken=b77a5c561934e089' or one of its dependencies. The module was expe
cted to contain an assembly manifest.

Any leads to resolve this error and to give projects to sonar for evaluation?

Thanking you
Nakul

Hi Nakul,

What happened when you ran StartSonar.bat? Were you able to access SonarQube?

 
Ann

Hi Ann,

Thank you for the reply,

Upon executing StartSonar.bat I got following result

F:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\bin\windows-x86-64>StartSonar.b
at
Starting SonarQube...
2023.10.10 22:03:20 INFO  app[][o.s.a.AppFileSystem] Cleaning or creating temp d
irectory F:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\temp
2023.10.10 22:03:21 INFO  app[][o.s.a.es.EsSettings] Elasticsearch listening on
[HTTP: 127.0.0.1:9001, TCP: 127.0.0.1:63136]
2023.10.10 22:03:22 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[ELASTI
CSEARCH] from [F:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch]:
J:\elipse java\bin\java -Xms4m -Xmx64m -XX:+UseSerialGC -Dcli.name=server -Dcli.
script=./bin/elasticsearch -Dcli.libs=lib/tools/server-cli -Des.path.home=F:\son
arqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch -Des.path.conf=F:\sonar
qube-10.2.1.78527\sonarqube-10.2.1.78527\temp\conf\es -Des.distribution.type=tar
 -cp F:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch\lib\*;F:\son
arqube-10.2.1.78527\sonarqube-10.2.1.78527\elasticsearch\lib\cli-launcher\* org.
elasticsearch.launcher.CliToolLauncher
2023.10.10 22:03:22 INFO  app[][o.s.a.SchedulerImpl] Waiting for Elasticsearch t
o be up and running
2023.10.10 22:06:41 INFO  app[][o.s.a.SchedulerImpl] Process[es] is up
2023.10.10 22:06:41 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[WEB_SE
RVER] from [F:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527]: J:\elipse java\bi
n\java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djava.io.tmpdir=F:\sonarq
ube-10.2.1.78527\sonarqube-10.2.1.78527\temp -XX:-OmitStackTraceInFastThrow --ad
d-opens=java.base/java.util=ALL-UNNAMED --add-opens=java.base/java.lang=ALL-UNNA
MED --add-opens=java.base/java.io=ALL-UNNAMED --add-opens=java.rmi/sun.rmi.trans
port=ALL-UNNAMED --add-exports=java.base/jdk.internal.ref=ALL-UNNAMED --add-open
s=java.base/java.nio=ALL-UNNAMED --add-opens=java.base/sun.nio.ch=ALL-UNNAMED --
add-opens=java.management/sun.management=ALL-UNNAMED --add-opens=jdk.management/
com.sun.management.internal=ALL-UNNAMED -Dcom.redhat.fips=false -Xmx512m -Xms128
m -XX:+HeapDumpOnOutOfMemoryError -Dhttp.nonProxyHosts=localhost|127.*|[::1] -cp
 ./lib/sonar-application-10.2.1.78527.jar;F:\sonarqube-10.2.1.78527\sonarqube-10
.2.1.78527\lib\jdbc\h2\h2-2.1.214.jar org.sonar.server.app.WebServer F:\sonarqub
e-10.2.1.78527\sonarqube-10.2.1.78527\temp\sq-process14284851663665678644propert
ies
WARNING: A terminally deprecated method in java.lang.System has been called
WARNING: System::setSecurityManager has been called by org.sonar.process.PluginS
ecurityManager (file:/F:/sonarqube-10.2.1.78527/sonarqube-10.2.1.78527/lib/sonar
-application-10.2.1.78527.jar)
WARNING: Please consider reporting this to the maintainers of org.sonar.process.
PluginSecurityManager
WARNING: System::setSecurityManager will be removed in a future release
2023.10.10 22:17:36 INFO  app[][o.s.a.SchedulerImpl] Process[web] is up
2023.10.10 22:17:36 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[COMPUT
E_ENGINE] from [F:\sonarqube-10.2.1.78527\sonarqube-10.2.1.78527]: J:\elipse jav
a\bin\java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djava.io.tmpdir=F:\so
narqube-10.2.1.78527\sonarqube-10.2.1.78527\temp -XX:-OmitStackTraceInFastThrow
--add-opens=java.base/java.util=ALL-UNNAMED --add-exports=java.base/jdk.internal
.ref=ALL-UNNAMED --add-opens=java.base/java.lang=ALL-UNNAMED --add-opens=java.ba
se/java.nio=ALL-UNNAMED --add-opens=java.base/sun.nio.ch=ALL-UNNAMED --add-opens
=java.management/sun.management=ALL-UNNAMED --add-opens=jdk.management/com.sun.m
anagement.internal=ALL-UNNAMED -Dcom.redhat.fips=false -Xmx512m -Xms128m -XX:+He
apDumpOnOutOfMemoryError -Dhttp.nonProxyHosts=localhost|127.*|[::1] -cp ./lib/so
nar-application-10.2.1.78527.jar;F:\sonarqube-10.2.1.78527\sonarqube-10.2.1.7852
7\lib\jdbc\h2\h2-2.1.214.jar org.sonar.ce.app.CeServer F:\sonarqube-10.2.1.78527
\sonarqube-10.2.1.78527\temp\sq-process7415218223551734303properties
2023.10.10 22:17:39 WARN  app[][startup] #######################################
#############################################################################
2023.10.10 22:17:39 WARN  app[][startup] Default Administrator credentials are s
till being used. Make sure to change the password or deactivate the account.
2023.10.10 22:17:39 WARN  app[][startup] #######################################
#############################################################################
WARNING: A terminally deprecated method in java.lang.System has been called
WARNING: System::setSecurityManager has been called by org.sonar.process.PluginS
ecurityManager (file:/F:/sonarqube-10.2.1.78527/sonarqube-10.2.1.78527/lib/sonar
-application-10.2.1.78527.jar)
WARNING: Please consider reporting this to the maintainers of org.sonar.process.
PluginSecurityManager
WARNING: System::setSecurityManager will be removed in a future release
2023.10.10 22:19:21 INFO  app[][o.s.a.SchedulerImpl] Process[ce] is up
2023.10.10 22:19:21 INFO  app[][o.s.a.SchedulerImpl] SonarQube is operational

It says that SonarQube is operational.

Hi,

This means the instance itself is configured correctly and ready to run.

It’s the run-as-a-service part that’s a problem. Googling the error leads me to believe the underlying problem is an inability to run the PowerShell script that’s called under the covers. That’s out of scope for me, but maybe the internet can help.

 
Ann

Hi Ann,

Thank you for the reply.

please tell how did you arrive at this conclusion.

I dont have any powershell installed as I am still using Windows 7.

Thanks.

Hi,

From your logs:

Sorry, I don’t keep up with Windows. You’ll need PowerShell to run the service script.

 
Ann

Hi Ann

I managed to get a developer’s PowerShell. Now when I execute Sonarservice.bat
This is the Error message which i get.

Are there any extra Windows utliity which needs to be installed ?

Thanks

Hi,

What does that error message mean to you?

 
Ann

A windows service named installutil.exe should be started before ServerExplorer along with

`

`

windows service administrative tool