Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Metabase v48+ does not run on Windows on H2 #39040

Closed
armgong opened this issue Feb 22, 2024 · 12 comments · Fixed by #39739
Closed

Metabase v48+ does not run on Windows on H2 #39040

armgong opened this issue Feb 22, 2024 · 12 comments · Fixed by #39739
Assignees
@qnkhuat
Labels
Operation/Database Migrations Issues with application DB migrations when launching Metabase Priority:P1 Security holes w/o exploit, crashing, setup/upgrade, login, broken common features, correctness .Regression Bugs that were previously fixed and/or bugs unintentionally shipped with new features. .Team/BackendComponents also known as BEC Type:Bug Product defects
Milestone
0.48.9

Comments

@armgong
Copy link

armgong commented Feb 22, 2024 •
edited by paoliniluis
Loading

Describe the bug

Just downloaded v49 and it won't run

To Reproduce

  1. set up a plain windows machine, download Metabase v49.x
  2. java -jar metabase.jar

Expected behavior

Metabase should start

Logs

2024-02-22 08:18:34,581 ERROR jdbcjobstore.JobStoreTX :: ClusterManager: Error managing cluster: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database!
org.quartz.JobPersistenceException: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database! [See nested exception: java.sql.SQLException: Connections could not be acquired from the underlying database!]
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.getConnection(JobStoreSupport.java:783)
        at org.quartz.impl.jdbcjobstore.JobStoreTX.getNonManagedTXConnection(JobStoreTX.java:71)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.doCheckin(JobStoreSupport.java:3322)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport$ClusterManager.manage(JobStoreSupport.java:3935)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport$ClusterManager.initialize(JobStoreSupport.java:3920)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.schedulerStarted(JobStoreSupport.java:692)
        at org.quartz.core.QuartzScheduler.start(QuartzScheduler.java:539)
        at org.quartz.impl.StdScheduler.start(StdScheduler.java:142)
        at clojurewerkz.quartzite.scheduler$start.invokeStatic(scheduler.clj:33)
        at clojurewerkz.quartzite.scheduler$start.invoke(scheduler.clj:29)
        at metabase.db.custom_migrations.DeleteAbandonmentEmailTask$with_connection_STAR___47404$with_transaction_STAR___47405.invoke(custom_migrations.clj:175)
        at toucan2.connection$bind_current_connectable_fn$fn__20897.invoke(connection.clj:104)
        at metabase.db.connection$do_transaction$thunk__32068.invoke(connection.clj:150)
        at metabase.db.connection$do_transaction.invokeStatic(connection.clj:165)
        at metabase.db.connection$do_transaction.invoke(connection.clj:146)
        at metabase.db.connection$do_with_transaction_primary_method_java_sql_Connection.invokeStatic(connection.clj:199)
        at metabase.db.connection$do_with_transaction_primary_method_java_sql_Connection.invoke(connection.clj:172)
        at clojure.lang.AFn.applyToHelper(AFn.java:165)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:457)
        at clojure.core$partial$fn__5908.invoke(core.clj:2643)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at methodical.impl.combo.threaded$fn__17987$fn__17988$fn__17991.invoke(threaded.clj:71)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957$fn__17961.invoke(threaded.clj:23)
        at clojure.lang.ArrayChunk.reduce(ArrayChunk.java:58)
        at clojure.core.protocols$fn__8244.invokeStatic(protocols.clj:136)
        at clojure.core.protocols$fn__8244.invoke(protocols.clj:124)
        at clojure.core.protocols$fn__8204$G__8199__8213.invoke(protocols.clj:19)
        at clojure.core.protocols$seq_reduce.invokeStatic(protocols.clj:31)
        at clojure.core.protocols$fn__8236.invokeStatic(protocols.clj:75)
        at clojure.core.protocols$fn__8236.invoke(protocols.clj:75)
        at clojure.core.protocols$fn__8178$G__8173__8191.invoke(protocols.clj:13)
        at clojure.core$reduce.invokeStatic(core.clj:6886)
        at clojure.core$reduce.invoke(core.clj:6868)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957.invoke(threaded.clj:21)
        at clojure.core$comp$fn__5876.invoke(core.clj:2588)
        at methodical.impl.combo.threaded$combine_with_threader$fn__17967.invoke(threaded.clj:44)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at toucan2.connection$do_with_transaction_around_method_toucan2_connection_default.invokeStatic(connection.clj:249)
        at toucan2.connection$do_with_transaction_around_method_toucan2_connection_default.invoke(connection.clj:245)
        at clojure.lang.AFn.applyToHelper(AFn.java:165)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:457)
        at clojure.core$partial$fn__5908.invoke(core.clj:2643)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at methodical.impl.standard$invoke_multifn.invokeStatic(standard.clj:58)
        at methodical.impl.standard$invoke_multifn.invoke(standard.clj:47)
        at methodical.impl.standard.StandardMultiFn.invoke(standard.clj:195)
        at metabase.db.custom_migrations.DeleteAbandonmentEmailTask$with_connection_STAR___47404.invoke(custom_migrations.clj:171)
        at toucan2.connection$bind_current_connectable_fn$fn__20897.invoke(connection.clj:104)
        at toucan2.connection$bind_current_connectable_fn$fn__20897.invoke(connection.clj:104)
        at toucan2.jdbc.connection$do_with_connection_primary_method_java_sql_Connection.invokeStatic(connection.clj:13)
        at toucan2.jdbc.connection$do_with_connection_primary_method_java_sql_Connection.invoke(connection.clj:11)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at clojure.core$partial$fn__5908.invoke(core.clj:2642)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at methodical.impl.combo.threaded$fn__17987$fn__17988$fn__17989.invoke(threaded.clj:70)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957$fn__17961.invoke(threaded.clj:23)
        at clojure.lang.ArrayChunk.reduce(ArrayChunk.java:58)
        at clojure.core.protocols$fn__8244.invokeStatic(protocols.clj:136)
        at clojure.core.protocols$fn__8244.invoke(protocols.clj:124)
        at clojure.core.protocols$fn__8204$G__8199__8213.invoke(protocols.clj:19)
        at clojure.core.protocols$seq_reduce.invokeStatic(protocols.clj:31)
        at clojure.core.protocols$fn__8236.invokeStatic(protocols.clj:75)
        at clojure.core.protocols$fn__8236.invoke(protocols.clj:75)
        at clojure.core.protocols$fn__8178$G__8173__8191.invoke(protocols.clj:13)
        at clojure.core$reduce.invokeStatic(core.clj:6886)
        at clojure.core$reduce.invoke(core.clj:6868)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957.invoke(threaded.clj:21)
        at clojure.core$comp$fn__5876.invoke(core.clj:2587)
        at methodical.impl.combo.threaded$combine_with_threader$fn__17967.invoke(threaded.clj:43)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at toucan2.connection$do_with_connection_around_method_toucan2_connection_default.invokeStatic(connection.clj:118)
        at toucan2.connection$do_with_connection_around_method_toucan2_connection_default.invoke(connection.clj:106)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at clojure.core$partial$fn__5908.invoke(core.clj:2642)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at methodical.impl.standard$invoke_multifn.invokeStatic(standard.clj:55)
        at methodical.impl.standard$invoke_multifn.invoke(standard.clj:47)
        at methodical.impl.standard.StandardMultiFn.invoke(standard.clj:193)
        at toucan2.connection$do_with_connection_primary_method_.invokeStatic(connection.clj:204)
        at toucan2.connection$do_with_connection_primary_method_.invoke(connection.clj:194)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at clojure.core$partial$fn__5908.invoke(core.clj:2642)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at methodical.impl.combo.threaded$fn__17987$fn__17988$fn__17989.invoke(threaded.clj:70)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957$fn__17961.invoke(threaded.clj:23)
        at clojure.lang.ArrayChunk.reduce(ArrayChunk.java:58)
        at clojure.core.protocols$fn__8244.invokeStatic(protocols.clj:136)
        at clojure.core.protocols$fn__8244.invoke(protocols.clj:124)
        at clojure.core.protocols$fn__8204$G__8199__8213.invoke(protocols.clj:19)
        at clojure.core.protocols$seq_reduce.invokeStatic(protocols.clj:31)
        at clojure.core.protocols$fn__8236.invokeStatic(protocols.clj:75)
        at clojure.core.protocols$fn__8236.invoke(protocols.clj:75)
        at clojure.core.protocols$fn__8178$G__8173__8191.invoke(protocols.clj:13)
        at clojure.core$reduce.invokeStatic(core.clj:6886)
        at clojure.core$reduce.invoke(core.clj:6868)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957.invoke(threaded.clj:21)
        at clojure.core$comp$fn__5876.invoke(core.clj:2587)
        at methodical.impl.combo.threaded$combine_with_threader$fn__17967.invoke(threaded.clj:43)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at toucan2.connection$do_with_connection_around_method_toucan2_connection_default.invokeStatic(connection.clj:118)
        at toucan2.connection$do_with_connection_around_method_toucan2_connection_default.invoke(connection.clj:106)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at clojure.core$partial$fn__5908.invoke(core.clj:2642)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at methodical.impl.standard$invoke_multifn.invokeStatic(standard.clj:55)
        at methodical.impl.standard$invoke_multifn.invoke(standard.clj:47)
        at methodical.impl.standard.StandardMultiFn.invoke(standard.clj:193)
        at metabase.db.custom_migrations.DeleteAbandonmentEmailTask.execute(custom_migrations.clj:171)
        at liquibase.change.custom.CustomChangeWrapper.generateStatements(CustomChangeWrapper.java:169)
        at liquibase.database.AbstractJdbcDatabase.executeStatements(AbstractJdbcDatabase.java:1271)
        at metabase.db.liquibase.h2.proxy$liquibase.database.core.H2Database$ff19274a.executeStatements(Unknown Source)
        at liquibase.changelog.ChangeSet.execute(ChangeSet.java:755)
        at liquibase.changelog.visitor.UpdateVisitor.executeAcceptedChange(UpdateVisitor.java:119)
        at liquibase.changelog.visitor.UpdateVisitor.visit(UpdateVisitor.java:68)
        at liquibase.changelog.ChangeLogIterator$2.lambda$run$0(ChangeLogIterator.java:133)
        at liquibase.Scope.lambda$child$0(Scope.java:186)
        at liquibase.Scope.child(Scope.java:195)
        at liquibase.Scope.child(Scope.java:185)
        at liquibase.Scope.child(Scope.java:164)
        at liquibase.changelog.ChangeLogIterator$2.run(ChangeLogIterator.java:122)
        at liquibase.Scope.lambda$child$0(Scope.java:186)
        at liquibase.Scope.child(Scope.java:195)
        at liquibase.Scope.child(Scope.java:185)
        at liquibase.Scope.child(Scope.java:164)
        at liquibase.Scope.child(Scope.java:252)
        at liquibase.Scope.child(Scope.java:256)
        at liquibase.changelog.ChangeLogIterator.run(ChangeLogIterator.java:89)
        at liquibase.command.core.AbstractUpdateCommandStep.lambda$run$0(AbstractUpdateCommandStep.java:110)
        at liquibase.Scope.lambda$child$0(Scope.java:186)
        at liquibase.Scope.child(Scope.java:195)
        at liquibase.Scope.child(Scope.java:185)
        at liquibase.Scope.child(Scope.java:164)
        at liquibase.command.core.AbstractUpdateCommandStep.run(AbstractUpdateCommandStep.java:108)
        at liquibase.command.core.UpdateCommandStep.run(UpdateCommandStep.java:105)
        at liquibase.command.CommandScope.execute(CommandScope.java:217)
        at liquibase.Liquibase.lambda$update$0(Liquibase.java:245)
        at liquibase.Scope.lambda$child$0(Scope.java:186)
        at liquibase.Scope.child(Scope.java:195)
        at liquibase.Scope.child(Scope.java:185)
        at liquibase.Scope.child(Scope.java:164)
        at liquibase.Liquibase.runInScope(Liquibase.java:1419)
        at liquibase.Liquibase.update(Liquibase.java:234)
        at liquibase.Liquibase.update(Liquibase.java:212)
        at liquibase.Liquibase.update(Liquibase.java:194)
        at metabase.db.liquibase$migrate_up_if_needed_BANG_.invokeStatic(liquibase.clj:240)
        at metabase.db.liquibase$migrate_up_if_needed_BANG_.invoke(liquibase.clj:222)
        at metabase.db.setup$migrate_BANG_$fn__50737.invoke(setup.clj:80)
        at metabase.db.liquibase$do_with_liquibase$f_STAR___48449.invoke(liquibase.clj:135)
        at metabase.db.liquibase$do_with_liquibase.invokeStatic(liquibase.clj:138)
        at metabase.db.liquibase$do_with_liquibase.invoke(liquibase.clj:126)
        at metabase.db.setup$migrate_BANG_.invokeStatic(setup.clj:75)
        at metabase.db.setup$migrate_BANG_.doInvoke(setup.clj:56)
        at clojure.lang.RestFn.invoke(RestFn.java:445)
        at metabase.db.setup$run_schema_migrations_BANG_.invokeStatic(setup.clj:148)
        at metabase.db.setup$run_schema_migrations_BANG_.invoke(setup.clj:142)
        at metabase.db.setup$setup_db_BANG_$fn__50767$fn__50768.invoke(setup.clj:166)
        at metabase.util.jvm$do_with_us_locale.invokeStatic(jvm.clj:239)
        at metabase.util.jvm$do_with_us_locale.invoke(jvm.clj:225)
        at metabase.db.setup$setup_db_BANG_$fn__50767.invoke(setup.clj:161)
        at metabase.db.setup$setup_db_BANG_.invokeStatic(setup.clj:160)
        at metabase.db.setup$setup_db_BANG_.invoke(setup.clj:154)
        at metabase.db$setup_db_BANG_$fn__50781.invoke(db.clj:69)
        at metabase.db$setup_db_BANG_.invokeStatic(db.clj:64)
        at metabase.db$setup_db_BANG_.invoke(db.clj:55)
        at metabase.core$init_BANG__STAR_.invokeStatic(core.clj:113)
        at metabase.core$init_BANG__STAR_.invoke(core.clj:98)
        at metabase.core$init_BANG_.invokeStatic(core.clj:156)
        at metabase.core$init_BANG_.invoke(core.clj:151)
        at metabase.core$start_normally.invokeStatic(core.clj:168)
        at metabase.core$start_normally.invoke(core.clj:162)
        at metabase.core$entrypoint.invokeStatic(core.clj:201)
        at metabase.core$entrypoint.doInvoke(core.clj:195)
        at clojure.lang.RestFn.invoke(RestFn.java:397)
        at clojure.lang.AFn.applyToHelper(AFn.java:152)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.Var.applyTo(Var.java:705)
        at clojure.core$apply.invokeStatic(core.clj:667)
        at clojure.core$apply.invoke(core.clj:662)
        at metabase.bootstrap$_main.invokeStatic(bootstrap.clj:31)
        at metabase.bootstrap$_main.doInvoke(bootstrap.clj:28)
        at clojure.lang.RestFn.invoke(RestFn.java:397)
        at clojure.lang.AFn.applyToHelper(AFn.java:152)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at metabase.bootstrap.main(Unknown Source)
Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database!
        at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
        at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
        at metabase.db.connection.ApplicationDB.getConnection(connection.clj:47)
        at metabase.task.ConnectionProvider.getConnection(task.clj:105)
        at org.quartz.utils.DBConnectionManager.getConnection(DBConnectionManager.java:108)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.getConnection(JobStoreSupport.java:780)
        ... 226 more
Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source.
        at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1507)
        at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:644)
        at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:554)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:758)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:685)
        ... 231 more
Caused by: java.io.IOException: The process cannot access the file because another process has locked a portion of the file
        at java.base/java.io.FileInputStream.readBytes(Native Method)
        at java.base/java.io.FileInputStream.read(FileInputStream.java:279)
        at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:252)
        at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:292)
        at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:351)
        at java.base/java.io.FilterInputStream.read(FilterInputStream.java:107)
        at metabase.db.update_h2$head.invokeStatic(update_h2.clj:24)
        at metabase.db.update_h2$head.invoke(update_h2.clj:18)
        at metabase.db.update_h2$db_version.invokeStatic(update_h2.clj:45)
        at metabase.db.update_h2$db_version.invoke(update_h2.clj:38)
        at metabase.db.update_h2$update_if_needed_BANG_.invokeStatic(update_h2.clj:95)
        at metabase.db.update_h2$update_if_needed_BANG_.invoke(update_h2.clj:90)
        at metabase.db.data_source.DataSource.getConnection(data_source.clj:29)
        at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:161)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:213)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1176)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1163)
        at com.mchange.v2.resourcepool.BasicResourcePool.access$700(BasicResourcePool.java:44)
        at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1908)
        at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)
2024-02-22 08:18:34,599 INFO core.QuartzScheduler :: Scheduler MetabaseScheduler_$_DESKTOP-QL1FHM31708618686914 started.
2024-02-22 08:18:37,152 ERROR liquibase.changelog :: ChangeSet migrations/001_update_migrations.yaml::v46.00-086::calherries encountered an exception.
2024-02-22 08:18:37,152 ERROR core.ErrorLogger :: An error occurred while scanning for the next triggers to fire.
org.quartz.JobPersistenceException: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database! [See nested exception: java.sql.SQLException: Connections could not be acquired from the underlying database!]
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.getConnection(JobStoreSupport.java:783)
        at org.quartz.impl.jdbcjobstore.JobStoreTX.getNonManagedTXConnection(JobStoreTX.java:71)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.executeInNonManagedTXLock(JobStoreSupport.java:3861)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.acquireNextTriggers(JobStoreSupport.java:2802)
        at org.quartz.core.QuartzSchedulerThread.run(QuartzSchedulerThread.java:287)
Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database!
        at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
        at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
        at metabase.db.connection.ApplicationDB.getConnection(connection.clj:47)
        at metabase.task.ConnectionProvider.getConnection(task.clj:105)
        at org.quartz.utils.DBConnectionManager.getConnection(DBConnectionManager.java:108)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.getConnection(JobStoreSupport.java:780)
        ... 4 more
Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source.
        at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1507)
        at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:644)
        at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:554)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:758)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:685)
        ... 9 more
Caused by: java.io.IOException: The process cannot access the file because another process has locked a portion of the file
        at java.base/java.io.FileInputStream.readBytes(Native Method)
        at java.base/java.io.FileInputStream.read(FileInputStream.java:279)
        at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:252)
        at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:292)
        at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:351)
        at java.base/java.io.FilterInputStream.read(FilterInputStream.java:107)
        at metabase.db.update_h2$head.invokeStatic(update_h2.clj:24)
        at metabase.db.update_h2$head.invoke(update_h2.clj:18)
        at metabase.db.update_h2$db_version.invokeStatic(update_h2.clj:45)
        at metabase.db.update_h2$db_version.invoke(update_h2.clj:38)
        at metabase.db.update_h2$update_if_needed_BANG_.invokeStatic(update_h2.clj:95)
        at metabase.db.update_h2$update_if_needed_BANG_.invoke(update_h2.clj:90)
        at metabase.db.data_source.DataSource.getConnection(data_source.clj:29)
        at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:161)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:213)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1176)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1163)
        at com.mchange.v2.resourcepool.BasicResourcePool.access$700(BasicResourcePool.java:44)
        at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1908)
        at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)
2024-02-22 08:18:37,152 ERROR jdbcjobstore.JobStoreTX :: MisfireHandler: Error handling misfires: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database!
org.quartz.JobPersistenceException: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database! [See nested exception: java.sql.SQLException: Connections could not be acquired from the underlying database!]
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.getConnection(JobStoreSupport.java:783)
        at org.quartz.impl.jdbcjobstore.JobStoreTX.getNonManagedTXConnection(JobStoreTX.java:71)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.doRecoverMisfires(JobStoreSupport.java:3246)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport$MisfireHandler.manage(JobStoreSupport.java:4012)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport$MisfireHandler.run(JobStoreSupport.java:4033)
Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database!
        at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
        at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
        at metabase.db.connection.ApplicationDB.getConnection(connection.clj:47)
        at metabase.task.ConnectionProvider.getConnection(task.clj:105)
        at org.quartz.utils.DBConnectionManager.getConnection(DBConnectionManager.java:108)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.getConnection(JobStoreSupport.java:780)
        ... 4 more
Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source.
        at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1507)
        at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:644)
        at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:554)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:758)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:685)
        ... 9 more
Caused by: java.io.IOException: The process cannot access the file because another process has locked a portion of the file
        at java.base/java.io.FileInputStream.readBytes(Native Method)
        at java.base/java.io.FileInputStream.read(FileInputStream.java:279)
        at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:252)
        at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:292)
        at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:351)
        at java.base/java.io.FilterInputStream.read(FilterInputStream.java:107)
        at metabase.db.update_h2$head.invokeStatic(update_h2.clj:24)
        at metabase.db.update_h2$head.invoke(update_h2.clj:18)
        at metabase.db.update_h2$db_version.invokeStatic(update_h2.clj:45)
        at metabase.db.update_h2$db_version.invoke(update_h2.clj:38)
        at metabase.db.update_h2$update_if_needed_BANG_.invokeStatic(update_h2.clj:95)
        at metabase.db.update_h2$update_if_needed_BANG_.invoke(update_h2.clj:90)
        at metabase.db.data_source.DataSource.getConnection(data_source.clj:29)
        at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:161)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:213)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1176)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1163)
        at com.mchange.v2.resourcepool.BasicResourcePool.access$700(BasicResourcePool.java:44)
        at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1908)
        at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)

UPDATE SUMMARY
Run:                        262
Previously run:               0
Filtered out:                18
-------------------------------
Total change sets:          280


FILTERED CHANGE SETS SUMMARY
DBMS mismatch:               18

2024-02-22 08:18:37,190 ERROR metabase.core :: Metabase Initialization FAILED
liquibase.exception.CommandExecutionException: liquibase.exception.LiquibaseException: liquibase.exception.MigrationFailedException: Migration failed for changeset migrations/001_update_migrations.yaml::v46.00-086::calherries:
     Reason: clojure.lang.ExceptionInfo: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database! {:toucan2/context-trace [["resolve connection" {:toucan2.connection/connectable org.h2.jdbc.JdbcConnection}] ["resolve connection" {:toucan2.connection/connectable nil}]]}
        at liquibase.command.CommandScope.execute(CommandScope.java:253)
        at liquibase.Liquibase.lambda$update$0(Liquibase.java:245)
        at liquibase.Scope.lambda$child$0(Scope.java:186)
        at liquibase.Scope.child(Scope.java:195)
        at liquibase.Scope.child(Scope.java:185)
        at liquibase.Scope.child(Scope.java:164)
        at liquibase.Liquibase.runInScope(Liquibase.java:1419)
        at liquibase.Liquibase.update(Liquibase.java:234)
        at liquibase.Liquibase.update(Liquibase.java:212)
        at liquibase.Liquibase.update(Liquibase.java:194)
        at metabase.db.liquibase$migrate_up_if_needed_BANG_.invokeStatic(liquibase.clj:240)
        at metabase.db.liquibase$migrate_up_if_needed_BANG_.invoke(liquibase.clj:222)
        at metabase.db.setup$migrate_BANG_$fn__50737.invoke(setup.clj:80)
        at metabase.db.liquibase$do_with_liquibase$f_STAR___48449.invoke(liquibase.clj:135)
        at metabase.db.liquibase$do_with_liquibase.invokeStatic(liquibase.clj:138)
        at metabase.db.liquibase$do_with_liquibase.invoke(liquibase.clj:126)
        at metabase.db.setup$migrate_BANG_.invokeStatic(setup.clj:75)
        at metabase.db.setup$migrate_BANG_.doInvoke(setup.clj:56)
        at clojure.lang.RestFn.invoke(RestFn.java:445)
        at metabase.db.setup$run_schema_migrations_BANG_.invokeStatic(setup.clj:148)
        at metabase.db.setup$run_schema_migrations_BANG_.invoke(setup.clj:142)
        at metabase.db.setup$setup_db_BANG_$fn__50767$fn__50768.invoke(setup.clj:166)
        at metabase.util.jvm$do_with_us_locale.invokeStatic(jvm.clj:239)
        at metabase.util.jvm$do_with_us_locale.invoke(jvm.clj:225)
        at metabase.db.setup$setup_db_BANG_$fn__50767.invoke(setup.clj:161)
        at metabase.db.setup$setup_db_BANG_.invokeStatic(setup.clj:160)
        at metabase.db.setup$setup_db_BANG_.invoke(setup.clj:154)
        at metabase.db$setup_db_BANG_$fn__50781.invoke(db.clj:69)
        at metabase.db$setup_db_BANG_.invokeStatic(db.clj:64)
        at metabase.db$setup_db_BANG_.invoke(db.clj:55)
        at metabase.core$init_BANG__STAR_.invokeStatic(core.clj:113)
        at metabase.core$init_BANG__STAR_.invoke(core.clj:98)
        at metabase.core$init_BANG_.invokeStatic(core.clj:156)
        at metabase.core$init_BANG_.invoke(core.clj:151)
        at metabase.core$start_normally.invokeStatic(core.clj:168)
        at metabase.core$start_normally.invoke(core.clj:162)
        at metabase.core$entrypoint.invokeStatic(core.clj:201)
        at metabase.core$entrypoint.doInvoke(core.clj:195)
        at clojure.lang.RestFn.invoke(RestFn.java:397)
        at clojure.lang.AFn.applyToHelper(AFn.java:152)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.Var.applyTo(Var.java:705)
        at clojure.core$apply.invokeStatic(core.clj:667)
        at clojure.core$apply.invoke(core.clj:662)
        at metabase.bootstrap$_main.invokeStatic(bootstrap.clj:31)
        at metabase.bootstrap$_main.doInvoke(bootstrap.clj:28)
        at clojure.lang.RestFn.invoke(RestFn.java:397)
        at clojure.lang.AFn.applyToHelper(AFn.java:152)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at metabase.bootstrap.main(Unknown Source)
Caused by: liquibase.exception.LiquibaseException: liquibase.exception.MigrationFailedException: Migration failed for changeset migrations/001_update_migrations.yaml::v46.00-086::calherries:
     Reason: clojure.lang.ExceptionInfo: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database! {:toucan2/context-trace [["resolve connection" {:toucan2.connection/connectable org.h2.jdbc.JdbcConnection}] ["resolve connection" {:toucan2.connection/connectable nil}]]}
        at liquibase.changelog.ChangeLogIterator.run(ChangeLogIterator.java:151)
        at liquibase.command.core.AbstractUpdateCommandStep.lambda$run$0(AbstractUpdateCommandStep.java:110)
        at liquibase.Scope.lambda$child$0(Scope.java:186)
        at liquibase.Scope.child(Scope.java:195)
        at liquibase.Scope.child(Scope.java:185)
        at liquibase.Scope.child(Scope.java:164)
        at liquibase.command.core.AbstractUpdateCommandStep.run(AbstractUpdateCommandStep.java:108)
        at liquibase.command.core.UpdateCommandStep.run(UpdateCommandStep.java:105)
        at liquibase.command.CommandScope.execute(CommandScope.java:217)
        ... 49 more
Caused by: liquibase.exception.MigrationFailedException: Migration failed for changeset migrations/001_update_migrations.yaml::v46.00-086::calherries:
     Reason: clojure.lang.ExceptionInfo: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database! {:toucan2/context-trace [["resolve connection" {:toucan2.connection/connectable org.h2.jdbc.JdbcConnection}] ["resolve connection" {:toucan2.connection/connectable nil}]]}
        at liquibase.changelog.ChangeSet.execute(ChangeSet.java:797)
        at liquibase.changelog.visitor.UpdateVisitor.executeAcceptedChange(UpdateVisitor.java:119)
        at liquibase.changelog.visitor.UpdateVisitor.visit(UpdateVisitor.java:68)
        at liquibase.changelog.ChangeLogIterator$2.lambda$run$0(ChangeLogIterator.java:133)
        at liquibase.Scope.lambda$child$0(Scope.java:186)
        at liquibase.Scope.child(Scope.java:195)
        at liquibase.Scope.child(Scope.java:185)
        at liquibase.Scope.child(Scope.java:164)
        at liquibase.changelog.ChangeLogIterator$2.run(ChangeLogIterator.java:122)
        at liquibase.Scope.lambda$child$0(Scope.java:186)
        at liquibase.Scope.child(Scope.java:195)
        at liquibase.Scope.child(Scope.java:185)
        at liquibase.Scope.child(Scope.java:164)
        at liquibase.Scope.child(Scope.java:252)
        at liquibase.Scope.child(Scope.java:256)
        at liquibase.changelog.ChangeLogIterator.run(ChangeLogIterator.java:89)
        ... 57 more
Caused by: clojure.lang.ExceptionInfo: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database! {:toucan2/context-trace [["resolve connection" {:toucan2.connection/connectable org.h2.jdbc.JdbcConnection}] ["resolve connection" {:toucan2.connection/connectable nil}]]}
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.getConnection(JobStoreSupport.java:783)
        at org.quartz.impl.jdbcjobstore.JobStoreTX.getNonManagedTXConnection(JobStoreTX.java:71)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.executeInNonManagedTXLock(JobStoreSupport.java:3854)
        at org.quartz.impl.jdbcjobstore.JobStoreTX.executeInLock(JobStoreTX.java:93)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.removeTrigger(JobStoreSupport.java:1428)
        at org.quartz.core.QuartzScheduler.unscheduleJob(QuartzScheduler.java:1065)
        at org.quartz.impl.StdScheduler.unscheduleJob(StdScheduler.java:311)
        at clojurewerkz.quartzite.scheduler$delete_trigger.invokeStatic(scheduler.clj:94)
        at clojurewerkz.quartzite.scheduler$delete_trigger.invoke(scheduler.clj:90)
        at metabase.db.custom_migrations.DeleteAbandonmentEmailTask$with_connection_STAR___47404$with_transaction_STAR___47405.invoke(custom_migrations.clj:176)
        at toucan2.connection$bind_current_connectable_fn$fn__20897.invoke(connection.clj:104)
        at metabase.db.connection$do_transaction$thunk__32068.invoke(connection.clj:150)
        at metabase.db.connection$do_transaction.invokeStatic(connection.clj:165)
        at metabase.db.connection$do_transaction.invoke(connection.clj:146)
        at metabase.db.connection$do_with_transaction_primary_method_java_sql_Connection.invokeStatic(connection.clj:199)
        at metabase.db.connection$do_with_transaction_primary_method_java_sql_Connection.invoke(connection.clj:172)
        at clojure.lang.AFn.applyToHelper(AFn.java:165)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:457)
        at clojure.core$partial$fn__5908.invoke(core.clj:2643)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at methodical.impl.combo.threaded$fn__17987$fn__17988$fn__17991.invoke(threaded.clj:71)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957$fn__17961.invoke(threaded.clj:23)
        at clojure.lang.ArrayChunk.reduce(ArrayChunk.java:58)
        at clojure.core.protocols$fn__8244.invokeStatic(protocols.clj:136)
        at clojure.core.protocols$fn__8244.invoke(protocols.clj:124)
        at clojure.core.protocols$fn__8204$G__8199__8213.invoke(protocols.clj:19)
        at clojure.core.protocols$seq_reduce.invokeStatic(protocols.clj:31)
        at clojure.core.protocols$fn__8236.invokeStatic(protocols.clj:75)
        at clojure.core.protocols$fn__8236.invoke(protocols.clj:75)
        at clojure.core.protocols$fn__8178$G__8173__8191.invoke(protocols.clj:13)
        at clojure.core$reduce.invokeStatic(core.clj:6886)
        at clojure.core$reduce.invoke(core.clj:6868)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957.invoke(threaded.clj:21)
        at clojure.core$comp$fn__5876.invoke(core.clj:2588)
        at methodical.impl.combo.threaded$combine_with_threader$fn__17967.invoke(threaded.clj:44)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at toucan2.connection$do_with_transaction_around_method_toucan2_connection_default.invokeStatic(connection.clj:249)
        at toucan2.connection$do_with_transaction_around_method_toucan2_connection_default.invoke(connection.clj:245)
        at clojure.lang.AFn.applyToHelper(AFn.java:165)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:457)
        at clojure.core$partial$fn__5908.invoke(core.clj:2643)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at methodical.impl.standard$invoke_multifn.invokeStatic(standard.clj:58)
        at methodical.impl.standard$invoke_multifn.invoke(standard.clj:47)
        at methodical.impl.standard.StandardMultiFn.invoke(standard.clj:195)
        at metabase.db.custom_migrations.DeleteAbandonmentEmailTask$with_connection_STAR___47404.invoke(custom_migrations.clj:171)
        at toucan2.connection$bind_current_connectable_fn$fn__20897.invoke(connection.clj:104)
        at toucan2.connection$bind_current_connectable_fn$fn__20897.invoke(connection.clj:104)
        at toucan2.jdbc.connection$do_with_connection_primary_method_java_sql_Connection.invokeStatic(connection.clj:13)
        at toucan2.jdbc.connection$do_with_connection_primary_method_java_sql_Connection.invoke(connection.clj:11)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at clojure.core$partial$fn__5908.invoke(core.clj:2642)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at methodical.impl.combo.threaded$fn__17987$fn__17988$fn__17989.invoke(threaded.clj:70)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957$fn__17961.invoke(threaded.clj:23)
        at clojure.lang.ArrayChunk.reduce(ArrayChunk.java:58)
        at clojure.core.protocols$fn__8244.invokeStatic(protocols.clj:136)
        at clojure.core.protocols$fn__8244.invoke(protocols.clj:124)
        at clojure.core.protocols$fn__8204$G__8199__8213.invoke(protocols.clj:19)
        at clojure.core.protocols$seq_reduce.invokeStatic(protocols.clj:31)
        at clojure.core.protocols$fn__8236.invokeStatic(protocols.clj:75)
        at clojure.core.protocols$fn__8236.invoke(protocols.clj:75)
        at clojure.core.protocols$fn__8178$G__8173__8191.invoke(protocols.clj:13)
        at clojure.core$reduce.invokeStatic(core.clj:6886)
        at clojure.core$reduce.invoke(core.clj:6868)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957.invoke(threaded.clj:21)
        at clojure.core$comp$fn__5876.invoke(core.clj:2587)
        at methodical.impl.combo.threaded$combine_with_threader$fn__17967.invoke(threaded.clj:43)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at toucan2.connection$do_with_connection_around_method_toucan2_connection_default.invokeStatic(connection.clj:118)
        at toucan2.connection$do_with_connection_around_method_toucan2_connection_default.invoke(connection.clj:106)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at clojure.core$partial$fn__5908.invoke(core.clj:2642)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at methodical.impl.standard$invoke_multifn.invokeStatic(standard.clj:55)
        at methodical.impl.standard$invoke_multifn.invoke(standard.clj:47)
        at methodical.impl.standard.StandardMultiFn.invoke(standard.clj:193)
        at toucan2.connection$do_with_connection_primary_method_.invokeStatic(connection.clj:204)
        at toucan2.connection$do_with_connection_primary_method_.invoke(connection.clj:194)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at clojure.core$partial$fn__5908.invoke(core.clj:2642)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at methodical.impl.combo.threaded$fn__17987$fn__17988$fn__17989.invoke(threaded.clj:70)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957$fn__17961.invoke(threaded.clj:23)
        at clojure.lang.ArrayChunk.reduce(ArrayChunk.java:58)
        at clojure.core.protocols$fn__8244.invokeStatic(protocols.clj:136)
        at clojure.core.protocols$fn__8244.invoke(protocols.clj:124)
        at clojure.core.protocols$fn__8204$G__8199__8213.invoke(protocols.clj:19)
        at clojure.core.protocols$seq_reduce.invokeStatic(protocols.clj:31)
        at clojure.core.protocols$fn__8236.invokeStatic(protocols.clj:75)
        at clojure.core.protocols$fn__8236.invoke(protocols.clj:75)
        at clojure.core.protocols$fn__8178$G__8173__8191.invoke(protocols.clj:13)
        at clojure.core$reduce.invokeStatic(core.clj:6886)
        at clojure.core$reduce.invoke(core.clj:6868)
        at methodical.impl.combo.threaded$reducer_fn$fn__17957.invoke(threaded.clj:21)
        at clojure.core$comp$fn__5876.invoke(core.clj:2587)
        at methodical.impl.combo.threaded$combine_with_threader$fn__17967.invoke(threaded.clj:43)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at toucan2.connection$do_with_connection_around_method_toucan2_connection_default.invokeStatic(connection.clj:118)
        at toucan2.connection$do_with_connection_around_method_toucan2_connection_default.invoke(connection.clj:106)
        at clojure.lang.AFn.applyToHelper(AFn.java:160)
        at clojure.lang.AFn.applyTo(AFn.java:144)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:436)
        at clojure.core$partial$fn__5908.invoke(core.clj:2642)
        at clojure.lang.AFn.applyToHelper(AFn.java:156)
        at clojure.lang.RestFn.applyTo(RestFn.java:132)
        at clojure.lang.AFunction$1.doInvoke(AFunction.java:31)
        at clojure.lang.RestFn.invoke(RestFn.java:421)
        at methodical.impl.standard$invoke_multifn.invokeStatic(standard.clj:55)
        at methodical.impl.standard$invoke_multifn.invoke(standard.clj:47)
        at methodical.impl.standard.StandardMultiFn.invoke(standard.clj:193)
        at metabase.db.custom_migrations.DeleteAbandonmentEmailTask.execute(custom_migrations.clj:171)
        at liquibase.change.custom.CustomChangeWrapper.generateStatements(CustomChangeWrapper.java:169)
        at liquibase.database.AbstractJdbcDatabase.executeStatements(AbstractJdbcDatabase.java:1271)
        at metabase.db.liquibase.h2.proxy$liquibase.database.core.H2Database$ff19274a.executeStatements(Unknown Source)
        at liquibase.changelog.ChangeSet.execute(ChangeSet.java:755)
        ... 72 more
Caused by: org.quartz.JobPersistenceException: Failed to obtain DB connection from data source 'db': java.sql.SQLException: Connections could not be acquired from the underlying database! [See nested exception: java.sql.SQLException: Connections could not be acquired from the underlying database!]
        ... 226 more
Caused by: java.sql.SQLException: Connections could not be acquired from the underlying database!
        at com.mchange.v2.sql.SqlUtils.toSQLException(SqlUtils.java:118)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:692)
        at com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource.getConnection(AbstractPoolBackedDataSource.java:140)
        at metabase.db.connection.ApplicationDB.getConnection(connection.clj:47)
        at metabase.task.ConnectionProvider.getConnection(task.clj:105)
        at org.quartz.utils.DBConnectionManager.getConnection(DBConnectionManager.java:108)
        at org.quartz.impl.jdbcjobstore.JobStoreSupport.getConnection(JobStoreSupport.java:780)
        ... 225 more
Caused by: com.mchange.v2.resourcepool.CannotAcquireResourceException: A ResourcePool could not acquire a resource from its primary factory or source.
        at com.mchange.v2.resourcepool.BasicResourcePool.awaitAvailable(BasicResourcePool.java:1507)
        at com.mchange.v2.resourcepool.BasicResourcePool.prelimCheckoutResource(BasicResourcePool.java:644)
        at com.mchange.v2.resourcepool.BasicResourcePool.checkoutResource(BasicResourcePool.java:554)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutAndMarkConnectionInUse(C3P0PooledConnectionPool.java:758)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool.checkoutPooledConnection(C3P0PooledConnectionPool.java:685)
        ... 230 more
Caused by: java.io.IOException: The process cannot access the file because another process has locked a portion of the file
        at java.base/java.io.FileInputStream.readBytes(Native Method)
        at java.base/java.io.FileInputStream.read(FileInputStream.java:279)
        at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:252)
        at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:292)
        at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:351)
        at java.base/java.io.FilterInputStream.read(FilterInputStream.java:107)
        at metabase.db.update_h2$head.invokeStatic(update_h2.clj:24)
        at metabase.db.update_h2$head.invoke(update_h2.clj:18)
        at metabase.db.update_h2$db_version.invokeStatic(update_h2.clj:45)
        at metabase.db.update_h2$db_version.invoke(update_h2.clj:38)
        at metabase.db.update_h2$update_if_needed_BANG_.invokeStatic(update_h2.clj:95)
        at metabase.db.update_h2$update_if_needed_BANG_.invoke(update_h2.clj:90)
        at metabase.db.data_source.DataSource.getConnection(data_source.clj:29)
        at com.mchange.v2.c3p0.WrapperConnectionPoolDataSource.getPooledConnection(WrapperConnectionPoolDataSource.java:161)
        at com.mchange.v2.c3p0.impl.C3P0PooledConnectionPool$1PooledConnectionResourcePoolManager.acquireResource(C3P0PooledConnectionPool.java:213)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquire(BasicResourcePool.java:1176)
        at com.mchange.v2.resourcepool.BasicResourcePool.doAcquireAndDecrementPendingAcquiresWithinLockOnSuccess(BasicResourcePool.java:1163)
        at com.mchange.v2.resourcepool.BasicResourcePool.access$700(BasicResourcePool.java:44)
        at com.mchange.v2.resourcepool.BasicResourcePool$ScatteredAcquireTask.run(BasicResourcePool.java:1908)
        at com.mchange.v2.async.ThreadPoolAsynchronousRunner$PoolThread.run(ThreadPoolAsynchronousRunner.java:696)
2024-02-22 08:18:37,223 INFO metabase.core :: Metabase Shutting Down ...
2024-02-22 08:18:37,223 INFO metabase.server :: Shutting Down Embedded Jetty Webserver
2024-02-22 08:18:37,229 INFO metabase.core :: Metabase Shutdown COMPLETE

Information about your Metabase installation

Win 10 Home Java 11

Severity

P1

Additional context

No response
@armgong armgong added .Needs Triage Type:Bug Product defects labels Feb 22, 2024
@armgong armgong changed the title fail to start metabase 0.48.6 with h2 database on windows 10 fail to start metabase 0.48.6 with h2 database on windows 10 LTSC 21H2 19044.4046 Feb 22, 2024
@paoliniluis
Copy link
Contributor

Please try with Java 11

@armgong
Copy link
Author

armgong commented Feb 22, 2024 •
edited
Loading

Please try with Java 11

test with jdk 11.0.22_7 and 17.0.10+7 and 21.0.2_13 , windows 10 LSTC and windows 11 22H2 and Windows server 2022 , all them run failed.
win10 and win11 are running in windows 2022 hyper-v host , Windows server 2022 run on bare metal.

0.47.x is ok , but 0.48 and master branch build failed start on windows.
Furthermore 0.47.x and 0.48 and master branch run ok on linux

@paoliniluis
Copy link
Contributor

why do you run the master branch and not the release?

@armgong
Copy link
Author

armgong commented Feb 22, 2024

why do you run the master branch and not the release?
Curiosity, after found 0.47.x run ok ,but 0.48.x failed to start . so move to master branch see the problem still exist or not ?

@armgong
Copy link
Author

armgong commented Feb 22, 2024

another report about this problem.
https://discourse.metabase.com/t/metabase-installation-fail/80878

@paoliniluis paoliniluis changed the title fail to start metabase 0.48.6 with h2 database on windows 10 LTSC 21H2 19044.4046 Metabase v48+ does not run on Windows Feb 22, 2024
@paoliniluis paoliniluis changed the title Metabase v48+ does not run on Windows Metabase v48+ does not run on Windows on H2 Feb 22, 2024
@paoliniluis paoliniluis added Priority:P1 Security holes w/o exploit, crashing, setup/upgrade, login, broken common features, correctness .Regression Bugs that were previously fixed and/or bugs unintentionally shipped with new features. .Product/Pothole and removed .Needs Triage labels Feb 22, 2024
@paoliniluis
Copy link
Contributor

Just tested it, it won't work

@armgong
Copy link
Author

armgong commented Feb 23, 2024

a bit more info maybe help to locate the bug.
fisrt install and config 0.47, then run 0.48 on h2 database 0.47 created , metabase will start successful.

@rkapl123
Copy link

a bit more info maybe help to locate the bug. fisrt install and config 0.47, then run 0.48 on h2 database 0.47 created , metabase will start successful.

I can confirm that this works, however the error messages are still there.

@grimlinda56
Copy link

grimlinda56 commented Mar 2, 2024 •
edited
Loading

Just FYI- Downloaded release version 48.7 today and experienced the exact same problem. Downloaded 47.13 and it worked no prob.

@EDIflyer
Copy link

EDIflyer commented Mar 3, 2024 •
edited
Loading

Glad to have found this bug as was pulling my hair out trying to work out what was going on!

Fresh VM
Running openJDK 17.0.6
Downloaded latest Metabase version from website - v0.48.7 (c192db1)

Could get it to display initial loading splashscreen on localhost:3000 but then would crash out.

Full startup as follows:
startup_output.txt

(tried a few other v.0.48 versions and v.0.49-RC1 but no change, v.0.47 works OK for fresh usage)

@darksciencebase darksciencebase added Operation/Database Migrations Issues with application DB migrations when launching Metabase .Team/BackendComponents also known as BEC and removed .Product/Pothole labels Mar 5, 2024
@qnkhuat qnkhuat self-assigned this Mar 7, 2024
@qnkhuat qnkhuat mentioned this issue Mar 7, 2024
Merged
@qnkhuat
Copy link
Contributor

qnkhuat commented Mar 7, 2024

Here is the fix #39739, I've tested it using a VM. It would be nice if someone who runs native Windows could test it. You can download a jar from our CI here

@qnkhuat qnkhuat mentioned this issue Mar 7, 2024
Open
@armgong
Copy link
Author

armgong commented Mar 7, 2024

thanks , test on win10 , issue fixed.

java -jar metabase.jar
Warning: environ value C:\Program Files\Eclipse Adoptium\jdk-17.0.10.7-hotspot\ for key :java-home has been overwritten with C:\Program Files\Eclipse Adoptium\jdk-17.0.10.7-hotspot
2024-03-07 11:25:31,485 INFO metabase.util :: Maximum memory available to JVM: 4.0 GB
2024-03-07 11:25:33,407 WARN db.env :: WARNING: Using Metabase with an H2 application database is not recommended for production deployments. For production deployments, we highly recommend using Postgres, MySQL, or MariaDB instead. If you decide to continue to use H2, please be sure to back up the database file regularly. For more information, see https://metabase.com/docs/latest/operations-guide/migrating-from-h2.html
2024-03-07 11:25:33,594 INFO util.encryption :: Saved credentials encryption is DISABLED for this Metabase instance.

 For more information, see https://metabase.com/docs/latest/operations-guide/encrypting-database-details-at-rest.html
2024-03-07 11:25:39,485 INFO driver.impl :: Registered abstract driver :sql
2024-03-07 11:25:39,500 INFO driver.impl :: Registered abstract driver :sql-jdbc (parents: [:sql])
2024-03-07 11:25:39,500 INFO metabase.util :: Load driver :sql-jdbc took 79.6 ms
2024-03-07 11:25:39,500 INFO driver.impl :: Registered driver :h2 (parents: [:sql-jdbc])
2024-03-07 11:25:39,688 INFO driver.impl :: Registered driver :mysql (parents: [:sql-jdbc])
2024-03-07 11:25:39,735 INFO driver.impl :: Registered driver :postgres (parents: [:sql-jdbc])
2024-03-07 11:25:41,391 INFO metabase.core ::
Metabase vUNKNOWN (4e1dc28)

Copyright ? 2024 Metabase, Inc.

Metabase Enterprise Edition extensions are NOT PRESENT.
2024-03-07 11:25:41,407 INFO metabase.core :: Starting Metabase in STANDALONE mode
2024-03-07 11:25:41,454 INFO metabase.server :: Launching Embedded Jetty Webserver with config:
 {:port 3000}

2024-03-07 11:25:41,532 INFO metabase.core :: Starting Metabase version vUNKNOWN (4e1dc28) ...
2024-03-07 11:25:41,532 INFO metabase.core :: System info:
 {"file.encoding" "GBK",
 "java.runtime.name" "OpenJDK Runtime Environment",
 "java.runtime.version" "17.0.10+7",
 "java.vendor" "Eclipse Adoptium",
 "java.vendor.url" "https://adoptium.net/",
 "java.version" "17.0.10",
 "java.vm.name" "OpenJDK 64-Bit Server VM",
 "java.vm.version" "17.0.10+7",
 "os.name" "Windows 10",
 "os.version" "10.0",
 "user.language" "zh",
 "user.timezone" "Asia/Shanghai"}

2024-03-07 11:25:41,532 INFO metabase.plugins :: Loading plugins in D:\downloads\plugins...
2024-03-07 11:25:41,719 INFO util.files :: Extract file /modules/druid.metabase-driver.jar -> D:\downloads\plugins\druid.metabase-driver.jar
2024-03-07 11:25:41,750 INFO util.files :: Extract file /modules/googleanalytics.metabase-driver.jar -> D:\downloads\plugins\googleanalytics.metabase-driver.jar
2024-03-07 11:25:41,797 INFO util.files :: Extract file /modules/sparksql.metabase-driver.jar -> D:\downloads\plugins\sparksql.metabase-driver.jar
2024-03-07 11:25:41,891 INFO util.files :: Extract file /modules/bigquery-cloud-sdk.metabase-driver.jar -> D:\downloads\plugins\bigquery-cloud-sdk.metabase-driver.jar
2024-03-07 11:25:42,188 INFO util.files :: Extract file /modules/sqlite.metabase-driver.jar -> D:\downloads\plugins\sqlite.metabase-driver.jar
2024-03-07 11:25:42,235 INFO util.files :: Extract file /modules/snowflake.metabase-driver.jar -> D:\downloads\plugins\snowflake.metabase-driver.jar
2024-03-07 11:25:42,735 INFO util.files :: Extract file /modules/oracle.metabase-driver.jar -> D:\downloads\plugins\oracle.metabase-driver.jar
2024-03-07 11:25:42,735 INFO util.files :: Extract file /modules/presto-jdbc.metabase-driver.jar -> D:\downloads\plugins\presto-jdbc.metabase-driver.jar
2024-03-07 11:25:42,829 INFO util.files :: Extract file /modules/vertica.metabase-driver.jar -> D:\downloads\plugins\vertica.metabase-driver.jar
2024-03-07 11:25:42,829 INFO util.files :: Extract file /modules/athena.metabase-driver.jar -> D:\downloads\plugins\athena.metabase-driver.jar
2024-03-07 11:25:42,954 INFO util.files :: Extract file /modules/redshift.metabase-driver.jar -> D:\downloads\plugins\redshift.metabase-driver.jar
2024-03-07 11:25:42,954 INFO util.files :: Extract file /modules/sqlserver.metabase-driver.jar -> D:\downloads\plugins\sqlserver.metabase-driver.jar
2024-03-07 11:25:42,969 INFO util.files :: Extract file /modules/mongo.metabase-driver.jar -> D:\downloads\plugins\mongo.metabase-driver.jar
2024-03-07 11:25:43,219 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :athena...
2024-03-07 11:25:43,219 INFO driver.impl :: Registered driver :athena (parents: [:sql-jdbc])
2024-03-07 11:25:43,266 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :bigquery-cloud-sdk...
2024-03-07 11:25:43,266 INFO driver.impl :: Registered driver :bigquery-cloud-sdk (parents: [:sql])
2024-03-07 11:25:43,266 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :druid...
2024-03-07 11:25:43,266 INFO driver.impl :: Registered driver :druid
2024-03-07 11:25:43,282 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :googleanalytics...
2024-03-07 11:25:43,282 INFO driver.impl :: Registered driver :googleanalytics
2024-03-07 11:25:43,297 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :mongo...
2024-03-07 11:25:43,297 INFO driver.impl :: Registered driver :mongo
2024-03-07 11:25:43,329 INFO plugins.dependencies :: Metabase cannot initialize plugin Metabase Oracle Driver due to required dependencies. Metabase requires the Oracle JDBC driver in order to connect to Oracle databases, but we can't ship it as part of Metabase due to licensing restrictions. See https://metabase.com/docs/latest/administration-guide/databases/oracle.html for more details.

2024-03-07 11:25:43,329 INFO plugins.dependencies :: Metabase Oracle Driver dependency {:class oracle.jdbc.OracleDriver} satisfied? false
2024-03-07 11:25:43,329 INFO plugins.dependencies :: Plugins with unsatisfied deps: ["Metabase Oracle Driver"]
2024-03-07 11:25:43,375 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :presto-jdbc...
2024-03-07 11:25:43,375 INFO driver.impl :: Registered driver :presto-jdbc (parents: [:sql-jdbc])
2024-03-07 11:25:43,375 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :redshift...
2024-03-07 11:25:43,375 INFO driver.impl :: Registered driver :redshift (parents: [:postgres])
2024-03-07 11:25:43,407 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :snowflake...
2024-03-07 11:25:43,407 INFO driver.impl :: Registered driver :snowflake (parents: [:sql-jdbc])
2024-03-07 11:25:43,422 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :hive-like...
2024-03-07 11:25:43,438 INFO driver.impl :: Registered abstract driver :hive-like (parents: [:sql-jdbc])
2024-03-07 11:25:43,438 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :sparksql...
2024-03-07 11:25:43,438 INFO driver.impl :: Registered driver :sparksql (parents: [:hive-like])
2024-03-07 11:25:43,438 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :sqlite...
2024-03-07 11:25:43,454 INFO driver.impl :: Registered driver :sqlite (parents: [:sql-jdbc])
2024-03-07 11:25:43,485 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :sqlserver...
2024-03-07 11:25:43,485 INFO driver.impl :: Registered driver :sqlserver (parents: [:sql-jdbc])
2024-03-07 11:25:43,485 INFO plugins.dependencies :: Metabase cannot initialize plugin Metabase Vertica Driver due to required dependencies. Metabase requires the Vertica JDBC driver in order to connect to Vertica databases, but we can't ship it as part of Metabase due to licensing restrictions. See https://metabase.com/docs/latest/administration-guide/databases/vertica.html for more details.

2024-03-07 11:25:43,485 INFO plugins.dependencies :: Metabase Vertica Driver dependency {:class com.vertica.jdbc.Driver} satisfied? false
2024-03-07 11:25:43,485 INFO plugins.dependencies :: Plugins with unsatisfied deps: ["Metabase Oracle Driver" "Metabase Vertica Driver"]
2024-03-07 11:25:43,500 INFO metabase.core :: Setting up and migrating Metabase DB. Please sit tight, this may take a minute...
2024-03-07 11:25:43,516 INFO db.setup :: Verifying h2 Database Connection ...
2024-03-07 11:25:43,813 INFO db.setup :: Successfully verified H2 2.1.214 (2022-06-13) application database connection.
2024-03-07 11:25:43,813 INFO db.setup :: Checking if a database downgrade is required...
2024-03-07 11:25:44,547 INFO db.setup :: Running Database Migrations...
2024-03-07 11:25:44,547 INFO db.setup :: Setting up Liquibase...
2024-03-07 11:25:44,766 INFO db.liquibase :: Updating liquibase table to reflect consolidated changeset filenames
2024-03-07 11:25:44,782 INFO db.liquibase :: No migration lock found.
2024-03-07 11:25:44,782 INFO db.liquibase :: Migration lock acquired.
2024-03-07 11:25:44,797 INFO db.setup :: Liquibase is ready.
2024-03-07 11:25:44,797 INFO db.liquibase :: Checking if Database has unrun migrations...
2024-03-07 11:25:45,422 INFO db.liquibase :: Database has unrun migrations. Checking if migration lock is taken...
2024-03-07 11:25:45,438 INFO db.liquibase :: No migration lock found.
2024-03-07 11:25:45,438 INFO db.liquibase :: Migration lock acquired.
2024-03-07 11:25:45,704 INFO db.liquibase :: Running 265 migrations ...
2024-03-07 11:25:47,079 INFO impl.StdSchedulerFactory :: Using default implementation for ThreadExecutor
2024-03-07 11:25:47,095 INFO core.SchedulerSignalerImpl :: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2024-03-07 11:25:47,095 INFO core.QuartzScheduler :: Quartz Scheduler v.2.3.2 created.
2024-03-07 11:25:47,095 INFO jdbcjobstore.JobStoreTX :: Using db table-based data access locking (synchronization). 2024-03-07 11:25:47,095 INFO jdbcjobstore.JobStoreTX :: JobStoreTX initialized.
2024-03-07 11:25:47,095 INFO core.QuartzScheduler :: Scheduler meta-data: Quartz Scheduler (v2.3.2) 'MetabaseScheduler' with instanceId 'win10-hyperv1709781947079'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads.
  Using job-store 'org.quartz.impl.jdbcjobstore.JobStoreTX' - which supports persistence. and is clustered.

2024-03-07 11:25:47,095 INFO impl.StdSchedulerFactory :: Quartz scheduler 'MetabaseScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2024-03-07 11:25:47,095 INFO impl.StdSchedulerFactory :: Quartz scheduler version: 2.3.2
2024-03-07 11:25:47,126 INFO core.QuartzScheduler :: Scheduler MetabaseScheduler_$_win10-hyperv1709781947079 started.
2024-03-07 11:25:47,139 INFO core.QuartzScheduler :: Scheduler MetabaseScheduler_$_win10-hyperv1709781947079 shutting down.
2024-03-07 11:25:47,141 INFO core.QuartzScheduler :: Scheduler MetabaseScheduler_$_win10-hyperv1709781947079 paused.

2024-03-07 11:25:47,141 INFO core.QuartzScheduler :: Scheduler MetabaseScheduler_$_win10-hyperv1709781947079 shutdown complete.
2024-03-07 11:25:47,532 INFO db.custom-migrations :: No forward migration for DowngradeDashboardTab

UPDATE SUMMARY
Run:                        265
Previously run:               0
Filtered out:                18
-------------------------------
Total change sets:          283


FILTERED CHANGE SETS SUMMARY
DBMS mismatch:               18

2024-03-07 11:25:48,688 INFO db.liquibase :: Migration complete in 3.0 s
2024-03-07 11:25:48,688 INFO db.setup :: Database Migrations Current ...
2024-03-07 11:25:48,688 INFO metabase.util :: Database setup took 5.2 s
2024-03-07 11:25:48,735 INFO metabase.core :: Looks like this is a new installation ... preparing setup wizard
2024-03-07 11:25:48,844 INFO metabase.core :: Please use the following URL to setup your Metabase installation:

http://localhost:3000/setup/


2024-03-07 11:25:48,875 INFO metabase.events :: Loading events namespace: metabase.events.audit-log
2024-03-07 11:25:48,938 INFO metabase.events :: Loading events namespace: metabase.events.driver-notifications
2024-03-07 11:25:48,954 INFO metabase.events :: Loading events namespace: metabase.events.last-login
2024-03-07 11:25:48,969 INFO metabase.events :: Loading events namespace: metabase.events.persisted-info
2024-03-07 11:25:48,985 INFO metabase.events :: Loading events namespace: metabase.events.recent-views
2024-03-07 11:25:48,985 INFO metabase.events :: Loading events namespace: metabase.events.revision
2024-03-07 11:25:49,032 INFO metabase.events :: Loading events namespace: metabase.events.schema
2024-03-07 11:25:49,032 INFO metabase.events :: Loading events namespace: metabase.events.sync-database
2024-03-07 11:25:49,047 INFO metabase.events :: Loading events namespace: metabase.events.view-log
2024-03-07 11:25:49,110 INFO metabase.sample-data :: Loading sample database
2024-03-07 11:25:49,204 INFO util.files :: Extract file /sample-database.db.mv.db -> D:\downloads\plugins\sample-database.db.mv.db
2024-03-07 11:25:49,266 INFO driver.impl :: Initializing driver :sql...
2024-03-07 11:25:49,266 INFO driver.impl :: Initializing driver :sql-jdbc...
2024-03-07 11:25:49,266 INFO driver.impl :: Initializing driver :h2...
2024-03-07 11:25:49,391 INFO task.sync-databases :: Scheduling sync/analyze for database 1: trigger: metabase.task.sync-and-analyze.trigger.1
2024-03-07 11:25:49,391 INFO task.sync-databases :: Scheduling field-values for database 1: trigger: metabase.task.update-field-values.trigger.1
2024-03-07 11:25:49,391 INFO sync.util :: STARTING: Sync h2 Database 1 ''Sample Database''
2024-03-07 11:25:49,407 INFO sync.util :: STARTING: Sync metadata for h2 Database 1 ''Sample Database''
2024-03-07 11:25:49,511 INFO sync.util :: STARTING: step ''sync-dbms-version'' for h2 Database 1 ''Sample Database''

2024-03-07 11:25:49,532 INFO sync.util :: FINISHED: step ''sync-dbms-version'' for h2 Database 1 ''Sample Database'' (30.9 ms)
2024-03-07 11:25:49,548 INFO sync.util :: STARTING: step ''sync-timezone'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:49,548 INFO sync-metadata.sync-timezone :: :h2 database 1 default timezone is "Asia/Shanghai"
2024-03-07 11:25:49,563 INFO sync.util :: FINISHED: step ''sync-timezone'' for h2 Database 1 ''Sample Database'' (20.3 ms)
2024-03-07 11:25:49,563 INFO sync.util :: STARTING: step ''sync-tables'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:49,594 INFO sync-metadata.tables :: Found new tables: (Table  ''PUBLIC.ANALYTIC_EVENTS'' Table  ''PUBLIC.FEEDBACK'' Table  ''PUBLIC.PEOPLE'' Table  ''PUBLIC.REVIEWS'' Table  ''PUBLIC.ORDERS'' Table  ''PUBLIC.ACCOUNTS'' Table  ''PUBLIC.INVOICES'' Table  ''PUBLIC.PRODUCTS'')
2024-03-07 11:25:49,766 INFO sync-metadata.tables :: Updating table metadata for Table 8 ''PUBLIC.PRODUCTS''
2024-03-07 11:25:49,766 INFO sync-metadata.tables :: Updating table metadata for Table 3 ''PUBLIC.PEOPLE''
2024-03-07 11:25:49,766 INFO sync-metadata.tables :: Updating table metadata for Table 7 ''PUBLIC.INVOICES''
2024-03-07 11:25:49,766 INFO sync-metadata.tables :: Updating table metadata for Table 5 ''PUBLIC.ORDERS''
2024-03-07 11:25:49,782 INFO sync-metadata.tables :: Updating table metadata for Table 4 ''PUBLIC.REVIEWS''
2024-03-07 11:25:49,782 INFO sync-metadata.tables :: Updating table metadata for Table 2 ''PUBLIC.FEEDBACK''
2024-03-07 11:25:49,782 INFO sync-metadata.tables :: Updating table metadata for Table 1 ''PUBLIC.ANALYTIC_EVENTS'' 2024-03-07 11:25:49,782 INFO sync-metadata.tables :: Updating table metadata for Table 6 ''PUBLIC.ACCOUNTS''
2024-03-07 11:25:49,813 INFO sync.util :: FINISHED: step ''sync-tables'' for h2 Database 1 ''Sample Database'' (250.1 ms)
2024-03-07 11:25:49,813 INFO sync.util :: STARTING: step ''sync-fields'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:50,485 INFO sync.util :: FINISHED: step ''sync-fields'' for h2 Database 1 ''Sample Database'' (664.9 ms)
2024-03-07 11:25:50,485 INFO sync.util :: STARTING: step ''sync-fks'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:50,579 INFO sync-metadata.fks :: Marking foreign key from Table 1 ''PUBLIC.ANALYTIC_EVENTS'' Field 5 ''ACCOUNT_ID'' -> Table 6 ''PUBLIC.ACCOUNTS'' Field 48 ''ID''
2024-03-07 11:25:50,625 INFO sync-metadata.fks :: Marking foreign key from Table 2 ''PUBLIC.FEEDBACK'' Field 9 ''ACCOUNT_ID'' -> Table 6 ''PUBLIC.ACCOUNTS'' Field 48 ''ID''
2024-03-07 11:25:50,657 INFO sync-metadata.fks :: Marking foreign key from Table 4 ''PUBLIC.REVIEWS'' Field 32 ''PRODUCT_ID'' -> Table 8 ''PUBLIC.PRODUCTS'' Field 68 ''ID''
2024-03-07 11:25:50,688 INFO sync-metadata.fks :: Marking foreign key from Table 5 ''PUBLIC.ORDERS'' Field 40 ''USER_ID'' -> Table 3 ''PUBLIC.PEOPLE'' Field 15 ''ID''
2024-03-07 11:25:50,719 INFO sync-metadata.fks :: Marking foreign key from Table 5 ''PUBLIC.ORDERS'' Field 37 ''PRODUCT_ID'' -> Table 8 ''PUBLIC.PRODUCTS'' Field 68 ''ID''
2024-03-07 11:25:50,753 INFO sync-metadata.fks :: Marking foreign key from Table 7 ''PUBLIC.INVOICES'' Field 60 ''ACCOUNT_ID'' -> Table 6 ''PUBLIC.ACCOUNTS'' Field 48 ''ID''
2024-03-07 11:25:50,766 INFO sync.util :: FINISHED: step ''sync-fks'' for h2 Database 1 ''Sample Database'' (283.1 ms)
2024-03-07 11:25:50,766 INFO sync.util :: STARTING: step ''sync-indexes'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:50,954 INFO sync-metadata.indexes :: Marking Field 4 as indexed
2024-03-07 11:25:50,954 INFO sync-metadata.indexes :: Marking Field 5 as indexed
2024-03-07 11:25:50,985 INFO sync-metadata.indexes :: Marking Field 9 as indexed
2024-03-07 11:25:50,985 INFO sync-metadata.indexes :: Marking Field 8 as indexed
2024-03-07 11:25:51,016 INFO sync-metadata.indexes :: Marking Field 15 as indexed
2024-03-07 11:25:51,032 INFO sync-metadata.indexes :: Marking Field 32 as indexed
2024-03-07 11:25:51,032 INFO sync-metadata.indexes :: Marking Field 29 as indexed
2024-03-07 11:25:51,141 INFO sync-metadata.indexes :: Marking Field 40 as indexed
2024-03-07 11:25:51,141 INFO sync-metadata.indexes :: Marking Field 34 as indexed
2024-03-07 11:25:51,141 INFO sync-metadata.indexes :: Marking Field 37 as indexed
2024-03-07 11:25:51,188 INFO sync-metadata.indexes :: Marking Field 48 as indexed
2024-03-07 11:25:51,219 INFO sync-metadata.indexes :: Marking Field 59 as indexed
2024-03-07 11:25:51,219 INFO sync-metadata.indexes :: Marking Field 60 as indexed
2024-03-07 11:25:51,251 INFO sync-metadata.indexes :: Marking Field 68 as indexed
2024-03-07 11:25:51,266 INFO sync.util :: FINISHED: step ''sync-indexes'' for h2 Database 1 ''Sample Database'' (493.4 ms)
2024-03-07 11:25:51,266 INFO sync.util :: STARTING: step ''sync-metabase-metadata'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:51,594 INFO sync.util :: FINISHED: step ''sync-metabase-metadata'' for h2 Database 1 ''Sample Database'' (326.8 ms)
2024-03-07 11:25:51,594 INFO sync.util :: STARTING: step ''sync-table-privileges'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:51,594 INFO sync.util :: FINISHED: step ''sync-table-privileges'' for h2 Database 1 ''Sample Database'' (1.1 ms)
2024-03-07 11:25:51,625 INFO sync.util :: FINISHED: Sync metadata for h2 Database 1 ''Sample Database'' (2.2 s)
2024-03-07 11:25:51,641 INFO sync.util :: STARTING: Analyze data for h2 Database 1 ''Sample Database''
2024-03-07 11:25:51,641 INFO sync.util :: STARTING: step ''fingerprint-fields'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:53,485 INFO sync.analyze :: fingerprint-fields Analyzed [*****··················
···························]    12% Table 3 ''PUBLIC.PEOPLE''
2024-03-07 11:25:54,907 INFO sync.analyze :: fingerprint-fields Analyzed [***********···············
························]    24% Table 6 ''PUBLIC.ACCOUNTS''
2024-03-07 11:25:55,657 INFO sync.util :: FINISHED: step ''fingerprint-fields'' for h2 Database 1 ''Sample Database'' (4.0 s)
2024-03-07 11:25:55,657 INFO sync.util :: STARTING: step ''classify-fields'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:55,695 INFO sync.analyze :: classify-fields Analyzed [******************·············
···················]    36% Table 1 ''PUBLIC.ANALYTIC_EVENTS''
2024-03-07 11:25:55,782 INFO sync.analyze :: classify-fields Analyzed [***********************···········
················]    48% Table 4 ''PUBLIC.REVIEWS''
2024-03-07 11:25:55,891 INFO sync.analyze :: classify-fields Analyzed [******************************·······
·············]    60% Table 7 ''PUBLIC.INVOICES''
2024-03-07 11:25:55,922 INFO sync.util :: FINISHED: step ''classify-fields'' for h2 Database 1 ''Sample Database'' (264.4 ms)
2024-03-07 11:25:55,922 INFO sync.util :: STARTING: step ''classify-tables'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:55,938 INFO sync.analyze :: classify-tables Analyzed [************************************····
··········]    72% Table 2 ''PUBLIC.FEEDBACK''
2024-03-07 11:25:55,954 INFO sync.analyze :: classify-tables Analyzed [*****************************************··
·······]    84% Table 5 ''PUBLIC.ORDERS''
2024-03-07 11:25:55,969 INFO sync.analyze :: classify-tables Analyzed [***********************************************···]    96% Table 8 ''PUBLIC.PRODUCTS''
2024-03-07 11:25:55,969 INFO sync.util :: FINISHED: step ''classify-tables'' for h2 Database 1 ''Sample Database'' (37.5 ms)
2024-03-07 11:25:56,047 INFO sync.util :: FINISHED: Analyze data for h2 Database 1 ''Sample Database'' (4.4 s)
2024-03-07 11:25:56,047 INFO sync.util :: STARTING: Cache field values in h2 Database 1 ''Sample Database''
2024-03-07 11:25:56,047 INFO sync.util :: STARTING: step ''delete-expired-advanced-field-values'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:56,329 INFO sync.util :: FINISHED: step ''delete-expired-advanced-field-values'' for h2 Database 1 ''Sample Database'' (281.8 ms)
2024-03-07 11:25:56,344 INFO sync.util :: STARTING: step ''update-field-values'' for h2 Database 1 ''Sample Database''
2024-03-07 11:25:56,938 INFO models.field-values :: Field BODY was previously automatically set to show a list widget, but now has 231 values. Switching Field to use a search widget instead.
2024-03-07 11:25:57,485 INFO sync.util :: FINISHED: step ''update-field-values'' for h2 Database 1 ''Sample Database'' (1.1 s)
2024-03-07 11:25:57,485 INFO sync.util :: FINISHED: Cache field values in h2 Database 1 ''Sample Database'' (1.4 s) 2024-03-07 11:25:57,485 INFO sync.util :: FINISHED: Sync h2 Database 1 ''Sample Database'' (8.1 s)
2024-03-07 11:25:57,501 INFO impl.StdSchedulerFactory :: Using default implementation for ThreadExecutor
2024-03-07 11:25:57,501 INFO core.SchedulerSignalerImpl :: Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl
2024-03-07 11:25:57,501 INFO core.QuartzScheduler :: Quartz Scheduler v.2.3.2 created.
2024-03-07 11:25:57,501 INFO jdbcjobstore.JobStoreTX :: Using db table-based data access locking (synchronization). 2024-03-07 11:25:57,516 INFO jdbcjobstore.JobStoreTX :: JobStoreTX initialized.
2024-03-07 11:25:57,516 INFO core.QuartzScheduler :: Scheduler meta-data: Quartz Scheduler (v2.3.2) 'MetabaseScheduler' with instanceId 'win10-hyperv1709781957501'
  Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally.
  NOT STARTED.
  Currently in standby mode.
  Number of jobs executed: 0
  Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads.
  Using job-store 'org.quartz.impl.jdbcjobstore.JobStoreTX' - which supports persistence. and is clustered.

2024-03-07 11:25:57,516 INFO impl.StdSchedulerFactory :: Quartz scheduler 'MetabaseScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
2024-03-07 11:25:57,516 INFO impl.StdSchedulerFactory :: Quartz scheduler version: 2.3.2
2024-03-07 11:25:57,610 INFO core.QuartzScheduler :: Scheduler MetabaseScheduler_$_win10-hyperv1709781957501 paused.

2024-03-07 11:25:57,610 INFO metabase.task :: Task scheduler initialized into standby mode.
2024-03-07 11:25:57,610 INFO metabase.task :: Initializing task SyncDatabases
2024-03-07 11:25:57,672 INFO models.database :: :h2 Database 'Sample Database' sync/analyze schedules have changed!

 Sync metadata was: '0 50 * * * ? *' is now: '0 59 * * * ? *'
 Cache FieldValues was: '0 50 0 * * ? *', is now: '0 0 4 * * ? *'
2024-03-07 11:25:57,704 INFO task.sync-databases :: Scheduling sync/analyze for database 1: trigger: metabase.task.sync-and-analyze.trigger.1
2024-03-07 11:25:57,719 INFO task.sync-databases :: Scheduling field-values for database 1: trigger: metabase.task.update-field-values.trigger.1
2024-03-07 11:25:57,735 INFO task.sync-databases :: Updated default schedules for %d databases 1
2024-03-07 11:25:57,751 INFO metabase.task :: Initializing task PersistRefresh
2024-03-07 11:25:57,751 INFO metabase.task :: Initializing task CheckForNewVersions
2024-03-07 11:25:57,751 INFO metabase.task :: Initializing task PersistPrune
2024-03-07 11:25:57,766 INFO metabase.task :: Initializing task SendAnonymousUsageStats
2024-03-07 11:25:57,766 INFO metabase.task :: Initializing task ModelIndexValues
2024-03-07 11:25:57,782 INFO metabase.task :: Initializing task RefreshSlackChannelsAndUsers
2024-03-07 11:25:57,782 INFO metabase.task :: Initializing task TruncateAuditTables
2024-03-07 11:25:57,782 INFO metabase.task :: Initializing task SendPulses
2024-03-07 11:25:57,782 INFO metabase.task :: Initializing task SendFollowUpEmails
2024-03-07 11:25:57,782 INFO metabase.task :: Initializing task SendCreatorSentimentEmails
2024-03-07 11:25:57,797 INFO metabase.task :: Initializing task TaskHistoryCleanup
2024-03-07 11:25:57,797 INFO metabase.task :: Initializing task SendWarnPulseRemovalEmail
2024-03-07 11:25:57,797 INFO core.QuartzScheduler :: Scheduler MetabaseScheduler_$_win10-hyperv1709781957501 started.
2024-03-07 11:25:57,837 INFO metabase.task :: Task scheduler started
2024-03-07 11:25:57,855 INFO task.refresh-slack-channel-user-cache :: Slack is not configured, not refreshing slack user/channel cache.
2024-03-07 11:25:57,864 INFO metabase.core :: Metabase Initialization COMPLETE in 31.8 s
2024-03-07 11:26:05,314 INFO jdbcjobstore.JobStoreTX :: ClusterManager: detected 1 failed or restarted instances.
2024-03-07 11:26:05,315 INFO jdbcjobstore.JobStoreTX :: ClusterManager: Scanning for instance "win10-hyperv1709781947079"'s failed in-progress jobs.

@qnkhuat qnkhuat added this to the 0.48.9 milestone Mar 12, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@qnkhuat qnkhuat

Labels
Operation/Database Migrations Issues with application DB migrations when launching Metabase Priority:P1 Security holes w/o exploit, crashing, setup/upgrade, login, broken common features, correctness .Regression Bugs that were previously fixed and/or bugs unintentionally shipped with new features. .Team/BackendComponents also known as BEC Type:Bug Product defects
Projects
None yet
Milestone
0.48.9
Development

Successfully merging a pull request may close this issue.

Fix unable to start on windows with h2 metabase/metabase
7 participants
@paoliniluis @armgong @rkapl123 @EDIflyer @qnkhuat @grimlinda56 @darksciencebase