Failed to read content of table schema_migrations v9.9 community fresh install

Hi all, I just wanted to run the 9.9 TLS

jdk is 17
fresh install
new schema on database: sonarqube9lts (previously created, empty)
new user/pass

roberto.gamarra@sq-server:~$ /opt/sonarqube/start-consoleSonarQube-LTS.sh
setting java home
starting sonarqube 9.9.4.87374-lts...
Running SonarQube...
Removed stale pid file: ./SonarQube.pid
2024.02.21 11:07:26 INFO  app[][o.s.a.AppFileSystem] Cleaning or creating temp directory /opt/sonarqube/9.9.4.87374-lts/temp
2024.02.21 11:07:26 INFO  app[][o.s.a.es.EsSettings] Elasticsearch listening on [HTTP: 127.0.0.1:9001, TCP: 127.0.0.1:38087]
2024.02.21 11:07:26 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[ELASTICSEARCH] from [/opt/sonarqube/9.9.4.87374-lts/elasticsearch]: /opt/sonarqube/9.9.4.87374-lts/elasticsearch/bin/elasticsearch
2024.02.21 11:07:26 INFO  app[][o.s.a.SchedulerImpl] Waiting for Elasticsearch to be up and running
2024.02.21 11:07:28 INFO  es[][o.e.n.Node] version[7.17.15], pid[101003], build[default/tar/0b8ecfb4378335f4689c4223d1f1115f16bef3ba/2023-11-10T22:03:46.987399016Z], OS[Linux/4.19.0-25-amd64/amd64], JVM[Eclipse Adoptium/OpenJDK 64-Bit Server VM/17.0.9/17.0.9+9]
2024.02.21 11:07:28 INFO  es[][o.e.n.Node] JVM home [/opt/java/jdk-17.0.9+9]
2024.02.21 11:07:28 INFO  es[][o.e.n.Node] JVM arguments [-XX:+UseG1GC, -Djava.io.tmpdir=/opt/sonarqube/9.9.4.87374-lts/temp, -XX:ErrorFile=/opt/sonarqube/9.9.4.87374-lts/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, -Djna.tmpdir=/opt/sonarqube/9.9.4.87374-lts/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, -Des.enforce.bootstrap.checks=true, -Xmx512m, -Xms512m, -XX:MaxDirectMemorySize=256m, -XX:+HeapDumpOnOutOfMemoryError, -Des.path.home=/opt/sonarqube/9.9.4.87374-lts/elasticsearch, -Des.path.conf=/opt/sonarqube/9.9.4.87374-lts/temp/conf/es, -Des.distribution.flavor=default, -Des.distribution.type=tar, -Des.bundled_jdk=false]
2024.02.21 11:07:29 INFO  es[][o.e.p.PluginsService] loaded module [analysis-common]
2024.02.21 11:07:29 INFO  es[][o.e.p.PluginsService] loaded module [lang-painless]
2024.02.21 11:07:29 INFO  es[][o.e.p.PluginsService] loaded module [parent-join]
2024.02.21 11:07:29 INFO  es[][o.e.p.PluginsService] loaded module [reindex]
2024.02.21 11:07:29 INFO  es[][o.e.p.PluginsService] loaded module [transport-netty4]
2024.02.21 11:07:29 INFO  es[][o.e.p.PluginsService] no plugins loaded
2024.02.21 11:07:29 INFO  es[][o.e.e.NodeEnvironment] using [1] data paths, mounts [[/ (/dev/mapper/debian--template--vg-root)]], net usable_space [103.8gb], net total_space [116.3gb], types [ext4]
2024.02.21 11:07:29 INFO  es[][o.e.e.NodeEnvironment] heap size [512mb], compressed ordinary object pointers [true]
2024.02.21 11:07:29 INFO  es[][o.e.n.Node] node name [sonarqube], node ID [GboCwlHXT9uiOkmTIDDdYg], cluster name [sonarqube], roles [data_frozen, master, remote_cluster_client, data, data_content, data_hot, data_warm, data_cold, ingest]
2024.02.21 11:07:34 INFO  es[][o.e.t.NettyAllocator] creating NettyAllocator with the following configs: [name=unpooled, suggested_max_allocation_size=256kb, factors={es.unsafe.use_unpooled_allocator=null, g1gc_enabled=true, g1gc_region_size=1mb, heap_size=512mb}]
2024.02.21 11:07:34 INFO  es[][o.e.i.r.RecoverySettings] using rate limit [40mb] with [default=40mb, read=0b, write=0b, max=0b]
2024.02.21 11:07:34 INFO  es[][o.e.d.DiscoveryModule] using discovery type [zen] and seed hosts providers [settings]
2024.02.21 11:07:34 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
2024.02.21 11:07:34 INFO  es[][o.e.n.Node] initialized
2024.02.21 11:07:34 INFO  es[][o.e.n.Node] starting ...
2024.02.21 11:07:35 INFO  es[][o.e.t.TransportService] publish_address {127.0.0.1:38087}, bound_addresses {127.0.0.1:38087}
2024.02.21 11:07:35 INFO  es[][o.e.b.BootstrapChecks] explicitly enforcing bootstrap checks
2024.02.21 11:07:35 INFO  es[][o.e.c.c.Coordinator] cluster UUID [yd_7MbSDToerx8-AgV5e6A]
2024.02.21 11:07:35 INFO  es[][o.e.c.s.MasterService] elected-as-master ([1] nodes joined)[{sonarqube}{GboCwlHXT9uiOkmTIDDdYg}{vSflzw3LTF-s1Zl3QVSWQw}{127.0.0.1}{127.0.0.1:38087}{cdfhimrsw} elect leader, _BECOME_MASTER_TASK_, _FINISH_ELECTION_], term: 2, version: 3, delta: master node changed {previous [], current [{sonarqube}{GboCwlHXT9uiOkmTIDDdYg}{vSflzw3LTF-s1Zl3QVSWQw}{127.0.0.1}{127.0.0.1:38087}{cdfhimrsw}]}
2024.02.21 11:07:35 INFO  es[][o.e.c.s.ClusterApplierService] master node changed {previous [], current [{sonarqube}{GboCwlHXT9uiOkmTIDDdYg}{vSflzw3LTF-s1Zl3QVSWQw}{127.0.0.1}{127.0.0.1:38087}{cdfhimrsw}]}, term: 2, version: 3, reason: Publication{term=2, version=3}
2024.02.21 11:07:35 INFO  es[][o.e.h.AbstractHttpServerTransport] publish_address {127.0.0.1:9001}, bound_addresses {127.0.0.1:9001}
2024.02.21 11:07:35 INFO  es[][o.e.n.Node] started
2024.02.21 11:07:35 INFO  es[][o.e.g.GatewayService] recovered [0] indices into cluster_state
2024.02.21 11:07:35 INFO  app[][o.s.a.SchedulerImpl] Process[es] is up
2024.02.21 11:07:35 INFO  app[][o.s.a.ProcessLauncherImpl] Launch process[WEB_SERVER] from [/opt/sonarqube/9.9.4.87374-lts]: /opt/java/jdk-17.0.9+9/bin/java -Djava.awt.headless=true -Dfile.encoding=UTF-8 -Djava.io.tmpdir=/opt/sonarqube/9.9.4.87374-lts/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 --add-exports=java.base/jdk.internal.ref=ALL-UNNAMED --add-opens=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 -Xms128m -XX:+HeapDumpOnOutOfMemoryError -Dhttp.nonProxyHosts=localhost|127.*|[::1] -cp ./lib/sonar-application-9.9.4.87374.jar:/opt/sonarqube/9.9.4.87374-lts/lib/jdbc/postgresql/postgresql-42.5.1.jar org.sonar.server.app.WebServer /opt/sonarqube/9.9.4.87374-lts/temp/sq-process914871355292227345properties
WARNING: A terminally deprecated method in java.lang.System has been called
WARNING: System::setSecurityManager has been called by org.sonar.process.PluginSecurityManager (file:/opt/sonarqube/9.9.4.87374-lts/lib/sonar-application-9.9.4.87374.jar)
WARNING: Please consider reporting this to the maintainers of org.sonar.process.PluginSecurityManager
WARNING: System::setSecurityManager will be removed in a future release
2024.02.21 11:07:36 INFO  web[][o.s.p.ProcessEntryPoint] Starting Web Server
2024.02.21 11:07:37 INFO  web[][o.s.s.p.LogServerVersion] SonarQube Server / 9.9.4.87374 / b2d40b5fe8e032f80f66415d8a7f8d6d343ed742
2024.02.21 11:07:37 INFO  web[][o.sonar.db.Database] Create JDBC data source for jdbc:postgresql://172.30.2.117:5432/sonarqube?currentSchema=sonarqube9lts
2024.02.21 11:07:37 INFO  web[][c.z.h.HikariDataSource] HikariPool-1 - Starting...
2024.02.21 11:07:38 INFO  web[][c.z.h.p.HikariPool] HikariPool-1 - Added connection org.postgresql.jdbc.PgConnection@63fdffcd
2024.02.21 11:07:38 INFO  web[][c.z.h.HikariDataSource] HikariPool-1 - Start completed.
2024.02.21 11:07:40 INFO  web[][o.s.s.p.ServerFileSystemImpl] SonarQube home: /opt/sonarqube/9.9.4.87374-lts
2024.02.21 11:07:40 INFO  web[][o.s.s.u.SystemPasscodeImpl] System authentication by passcode is disabled
2024.02.21 11:07:40 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@5ffd2b27-org.sonar.server.platform.db.migration.history.MigrationHistoryImpl': Initialization of bean failed; nested exception is java.lang.IllegalStateException: Failed to read content of table schema_migrations
org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'jdk.internal.loader.ClassLoaders$AppClassLoader@5ffd2b27-org.sonar.server.platform.db.migration.history.MigrationHistoryImpl': Initialization of bean failed; nested exception is java.lang.IllegalStateException: Failed to read content of table schema_migrations
        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.PlatformLevel2.start(PlatformLevel2.java:103)
        at org.sonar.server.platform.PlatformImpl.start(PlatformImpl.java:196)
        at org.sonar.server.platform.PlatformImpl.startLevel2Container(PlatformImpl.java:169)
        at org.sonar.server.platform.PlatformImpl.init(PlatformImpl.java:77)
        at org.sonar.server.platform.web.PlatformServletContextListener.contextInitialized(PlatformServletContextListener.java:43)
        at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4462)
        at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:4914)
        at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:171)
        at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1332)
        at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1322)
        at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
        at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
        at java.base/java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:145)
        at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:866)
        at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:794)
        at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:171)
        at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1332)
        at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1322)
        at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
        at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
        at java.base/java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:145)
        at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:866)
        at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:248)
        at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:171)
        at org.apache.catalina.core.StandardService.startInternal(StandardService.java:433)
        at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:171)
        at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:921)
        at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:171)
        at org.sonar.server.app.EmbeddedTomcat.start(EmbeddedTomcat.java:72)
        at org.sonar.server.app.WebServer.start(WebServer.java:55)
        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:104)
Caused by: java.lang.IllegalStateException: Failed to read content of table schema_migrations
        at org.sonar.server.platform.db.migration.history.MigrationHistoryImpl.getLastMigrationNumber(MigrationHistoryImpl.java:75)
        at org.sonar.server.platform.db.migration.history.MigrationHistoryMeddler.meddle(MigrationHistoryMeddler.java:45)
        at org.sonar.server.platform.db.migration.history.MigrationHistoryImpl.start(MigrationHistoryImpl.java:54)
        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)
        ... 44 common frames omitted
Caused by: org.postgresql.util.PSQLException: ERROR: no existe la relación «schema_migrations»
  Position: 21
        at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2676)
        at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2366)
        at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:356)
        at org.postgresql.jdbc.PgStatement.executeInternal(PgStatement.java:496)
        at org.postgresql.jdbc.PgStatement.execute(PgStatement.java:413)
        at org.postgresql.jdbc.PgStatement.executeWithFlags(PgStatement.java:333)
        at org.postgresql.jdbc.PgStatement.executeCachedSql(PgStatement.java:319)
        at org.postgresql.jdbc.PgStatement.executeWithFlags(PgStatement.java:295)
        at org.postgresql.jdbc.PgStatement.executeQuery(PgStatement.java:244)
        at com.zaxxer.hikari.pool.ProxyStatement.executeQuery(ProxyStatement.java:110)
        at com.zaxxer.hikari.pool.HikariProxyStatement.executeQuery(HikariProxyStatement.java)
        at org.sonar.server.platform.db.migration.history.MigrationHistoryImpl.selectVersions(MigrationHistoryImpl.java:97)
        at org.sonar.server.platform.db.migration.history.MigrationHistoryImpl.getLastMigrationNumber(MigrationHistoryImpl.java:68)
        ... 50 common frames omitted
2024.02.21 11:07:40 INFO  web[][c.z.h.HikariDataSource] HikariPool-1 - Shutdown initiated...
2024.02.21 11:07:40 INFO  web[][c.z.h.HikariDataSource] HikariPool-1 - Shutdown completed.
2024.02.21 11:07:40 WARN  web[][o.a.c.l.WebappClassLoaderBase] The web application [ROOT] appears to have started a thread named [HikariPool-1 connection closer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:\n java.base@17.0.9/java.lang.Thread.currentThread(Native Method)\n java.base@17.0.9/java.lang.ThreadLocal.isPresent(ThreadLocal.java:183)\n java.base@17.0.9/java.lang.Thread.exit(Thread.java:849)
2024.02.21 11:07:40 INFO  web[][o.s.s.a.EmbeddedTomcat] HTTP connector enabled on port 9000
2024.02.21 11:07:40 INFO  web[][o.s.p.ProcessEntryPoint] Hard stopping process
2024.02.21 11:07:40 INFO  app[][o.s.a.SchedulerImpl] Process[Web Server] is stopped
2024.02.21 11:07:40 WARN  app[][o.s.a.p.AbstractManagedProcess] Process exited with exit value [ElasticSearch]: 143
2024.02.21 11:07:40 INFO  app[][o.s.a.SchedulerImpl] Process[ElasticSearch] is stopped
2024.02.21 11:07:40 INFO  app[][o.s.a.SchedulerImpl] SonarQube is stopped

I didn’t start before with H2 embedded database

any ideas ?

Hi,

At a guess, you started up an instance of SonarQube and it got interrupted while it was setting up its schema. Since you’re going for a fresh install, your best bet is to just clean out the schema SonarQube is using and start SonarQube up again.

 
HTH,
Ann

there was nothing on the database, only the database and the role
checked again, and is clean

Hi,

Are you sure SonarQube is pointed to the schema you think it is?

 
Ann

yep, the problem was the user’s privilleges :frowning:

up and running!

1 Like