metabase_logs_v.40

Warning: environ value jdk-11.0.11+9 for key :java-version has been overwritten with 11.0.11
2021-11-03 05:00:36,948 INFO metabase.util :: Maximum memory available to JVM: 481.4 MB
2021-11-03 05:00:52,347 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
2021-11-03 05:01:01,377 INFO metabase.core ::
Metabase v0.40.0 (961caed master)

Copyright © 2021 Metabase, Inc.

Metabase Enterprise Edition extensions are NOT PRESENT.
2021-11-03 05:01:01,399 WARN metabase.core :: WARNING: You have enabled namespace tracing, which could log sensitive information like db passwords.
2021-11-03 05:01:01,403 INFO metabase.core :: Starting Metabase in STANDALONE mode
2021-11-03 05:01:01,511 INFO metabase.server :: Launching Embedded Jetty Webserver with config:
 {:port 3000, :host "0.0.0.0"}

2021-11-03 05:01:01,577 INFO metabase.core :: Starting Metabase version v0.40.0 (961caed master) ...
2021-11-03 05:01:01,776 INFO metabase.core :: System info:
 {"file.encoding" "UTF-8",
 "java.runtime.name" "OpenJDK Runtime Environment",
 "java.runtime.version" "11.0.11+9",
 "java.vendor" "AdoptOpenJDK",
 "java.vendor.url" "https://adoptopenjdk.net/",
 "java.version" "11.0.11",
 "java.vm.name" "OpenJDK 64-Bit Server VM",
 "java.vm.version" "11.0.11+9",
 "os.name" "Linux",
 "os.version" "4.14.243-185.433.amzn2.x86_64",
 "user.language" "en",
 "user.timezone" "GMT"}

2021-11-03 05:01:01,792 INFO metabase.plugins :: Loading plugins in /plugins...
2021-11-03 05:01:02,152 INFO util.files :: Extract file /modules/sparksql.metabase-driver.jar -> /plugins/sparksql.metabase-driver.jar
2021-11-03 05:01:02,840 INFO util.files :: Extract file /modules/bigquery.metabase-driver.jar -> /plugins/bigquery.metabase-driver.jar
2021-11-03 05:01:02,852 INFO util.files :: Extract file /modules/sqlserver.metabase-driver.jar -> /plugins/sqlserver.metabase-driver.jar
2021-11-03 05:01:02,875 INFO util.files :: Extract file /modules/sqlite.metabase-driver.jar -> /plugins/sqlite.metabase-driver.jar
2021-11-03 05:01:02,904 INFO util.files :: Extract file /modules/vertica.metabase-driver.jar -> /plugins/vertica.metabase-driver.jar
2021-11-03 05:01:02,911 INFO util.files :: Extract file /modules/redshift.metabase-driver.jar -> /plugins/redshift.metabase-driver.jar
2021-11-03 05:01:02,921 INFO util.files :: Extract file /modules/presto.metabase-driver.jar -> /plugins/presto.metabase-driver.jar
2021-11-03 05:01:02,923 INFO util.files :: Extract file /modules/druid.metabase-driver.jar -> /plugins/druid.metabase-driver.jar
2021-11-03 05:01:02,928 INFO util.files :: Extract file /modules/mongo.metabase-driver.jar -> /plugins/mongo.metabase-driver.jar
2021-11-03 05:01:02,956 INFO util.files :: Extract file /modules/google.metabase-driver.jar -> /plugins/google.metabase-driver.jar
2021-11-03 05:01:02,963 INFO util.files :: Extract file /modules/oracle.metabase-driver.jar -> /plugins/oracle.metabase-driver.jar
2021-11-03 05:01:02,967 INFO util.files :: Extract file /modules/snowflake.metabase-driver.jar -> /plugins/snowflake.metabase-driver.jar
2021-11-03 05:01:03,141 INFO util.files :: Extract file /modules/googleanalytics.metabase-driver.jar -> /plugins/googleanalytics.metabase-driver.jar
2021-11-03 05:01:03,514 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :hive-like...
2021-11-03 05:01:03,843 INFO driver.impl :: Registered abstract driver :sql  🚚
 ↙ Load driver :sql took 204.4 ms
2021-11-03 05:01:03,863 INFO driver.impl :: Registered abstract driver :sql-jdbc (parents: [:sql]) 🚚
Load driver :sql-jdbc took 357.9 ms
2021-11-03 05:01:03,880 INFO driver.impl :: Registered abstract driver :hive-like (parents: [:sql-jdbc]) 🚚
2021-11-03 05:01:03,882 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :sparksql...
2021-11-03 05:01:03,884 INFO driver.impl :: Registered driver :sparksql (parents: [:hive-like]) 🚚
2021-11-03 05:01:03,898 INFO plugins.dependencies :: Plugin 'Metabase BigQuery Driver' depends on plugin 'Metabase Google Drivers Shared Dependencies'
2021-11-03 05:01:03,901 INFO plugins.dependencies :: Metabase BigQuery Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? false
2021-11-03 05:01:03,903 INFO plugins.dependencies :: Plugins with unsatisfied deps: ["Metabase BigQuery Driver"]
2021-11-03 05:01:03,920 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :sqlserver...
2021-11-03 05:01:03,922 INFO driver.impl :: Registered driver :sqlserver (parents: [:sql-jdbc]) 🚚
2021-11-03 05:01:03,933 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :sqlite...
2021-11-03 05:01:03,939 INFO driver.impl :: Registered driver :sqlite (parents: [:sql-jdbc]) 🚚
2021-11-03 05:01:03,945 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.

2021-11-03 05:01:03,953 INFO plugins.dependencies :: Metabase Vertica Driver dependency {:class com.vertica.jdbc.Driver} satisfied? false
2021-11-03 05:01:03,954 INFO plugins.dependencies :: Plugins with unsatisfied deps: ["Metabase Vertica Driver" "Metabase BigQuery Driver"]
2021-11-03 05:01:03,964 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :redshift...
2021-11-03 05:01:03,985 INFO driver.impl :: Registered driver :postgres (parents: [:sql-jdbc]) 🚚
Load driver :postgres took 48.0 ms
2021-11-03 05:01:04,019 INFO driver.impl :: Registered driver :redshift (parents: [:postgres]) 🚚
2021-11-03 05:01:04,031 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :presto...
2021-11-03 05:01:04,034 INFO driver.impl :: Registered driver :presto (parents: [:sql]) 🚚
2021-11-03 05:01:04,050 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :druid...
2021-11-03 05:01:04,054 INFO driver.impl :: Registered driver :druid  🚚
2021-11-03 05:01:04,073 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :mongo...
2021-11-03 05:01:04,082 INFO driver.impl :: Registered driver :mongo  🚚
2021-11-03 05:01:04,088 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :google...
2021-11-03 05:01:04,090 INFO driver.impl :: Registered abstract driver :google  🚚
2021-11-03 05:01:04,095 INFO plugins.dependencies :: Metabase BigQuery Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? true
2021-11-03 05:01:04,097 DEBUG plugins.initialize :: Dependencies satisfied; these plugins will now be loaded: ["Metabase BigQuery Driver"]
2021-11-03 05:01:04,098 WARN plugins.lazy-loaded-driver :: Warning: plugin manifest for :bigquery does not include connection properties
2021-11-03 05:01:04,098 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :bigquery...
2021-11-03 05:01:04,103 INFO driver.impl :: Registered driver :bigquery (parents: [:sql :google]) 🚚
2021-11-03 05:01:04,113 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.

2021-11-03 05:01:04,119 INFO plugins.dependencies :: Metabase Oracle Driver dependency {:class oracle.jdbc.OracleDriver} satisfied? false
2021-11-03 05:01:04,122 INFO plugins.dependencies :: Plugins with unsatisfied deps: ["Metabase Vertica Driver" "Metabase Oracle Driver"]
2021-11-03 05:01:04,189 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :snowflake...
2021-11-03 05:01:04,199 INFO driver.impl :: Registered driver :snowflake (parents: [:sql-jdbc]) 🚚
2021-11-03 05:01:04,208 INFO plugins.dependencies :: Plugin 'Metabase Google Analytics Driver' depends on plugin 'Metabase Google Drivers Shared Dependencies'
2021-11-03 05:01:04,210 INFO plugins.dependencies :: Metabase Google Analytics Driver dependency {:plugin Metabase Google Drivers Shared Dependencies} satisfied? true
2021-11-03 05:01:04,211 DEBUG plugins.lazy-loaded-driver :: Registering lazy loading driver :googleanalytics...
2021-11-03 05:01:04,212 INFO driver.impl :: Registered driver :googleanalytics (parents: [:google]) 🚚
2021-11-03 05:01:04,231 INFO driver.impl :: Registered driver :h2 (parents: [:sql-jdbc]) 🚚
2021-11-03 05:01:04,266 INFO driver.impl :: Registered driver :mysql (parents: [:sql-jdbc]) 🚚
2021-11-03 05:01:04,302 INFO metabase.core :: Setting up and migrating Metabase DB. Please sit tight, this may take a minute...
2021-11-03 05:01:04,310 INFO db.setup :: Verifying postgres Database Connection ...
2021-11-03 05:01:05,630 INFO db.setup :: Successfully verified PostgreSQL 11.12 application database connection. ✅
2021-11-03 05:01:05,632 INFO db.setup :: Running Database Migrations...
2021-11-03 05:01:05,685 INFO db.setup :: Setting up Liquibase...
2021-11-03 05:01:05,777 INFO db.setup :: Liquibase is ready.
2021-11-03 05:01:05,782 INFO db.liquibase :: Checking if Database has unrun migrations...
2021-11-03 05:01:09,427 INFO db.liquibase :: Database has unrun migrations. Waiting for migration lock to be cleared...
2021-11-03 05:01:09,449 INFO db.liquibase :: Migration lock is cleared. Running migrations...
2021-11-03 05:01:09,489 INFO db.setup :: Database Migrations Current ...  ✅
2021-11-03 05:01:09,524 INFO db.data-migrations :: Running all necessary data migrations, this may take a minute.
2021-11-03 05:01:09,601 INFO db.data-migrations :: Finished running data migrations.
Database setup took 5.3 s
2021-11-03 05:01:10,074 INFO metabase.events :: Starting events listener: :metabase.events.dependencies/Dependencies 👂
2021-11-03 05:01:10,098 INFO metabase.events :: Starting events listener: :metabase.events.activity-feed/ActivityFeed 👂
2021-11-03 05:01:10,105 INFO metabase.events :: Starting events listener: :metabase.events.metabot-lifecycle/MetaBotLifecycle 👂
2021-11-03 05:01:10,107 INFO metabase.events :: Starting events listener: :metabase.events.last-login/LastLogin 👂
2021-11-03 05:01:10,108 INFO metabase.events :: Starting events listener: :metabase.events.notifications/Notifications 👂
2021-11-03 05:01:10,108 INFO metabase.events :: Starting events listener: :metabase.events.sync-database/Sync 👂
2021-11-03 05:01:10,109 INFO metabase.events :: Starting events listener: :metabase.events.driver-notifications/DriverNotifications 👂
2021-11-03 05:01:10,110 INFO metabase.events :: Starting events listener: :metabase.events.revision/Revisions 👂
2021-11-03 05:01:10,114 INFO metabase.events :: Starting events listener: :metabase.events.view-log/ViewLog 👂
2021-11-03 05:01:10,439 INFO metabase.task :: Initializing task SyncDatabases 📆
2021-11-03 05:01:10,936 INFO metabase.task :: Initializing task CheckForNewVersions 📆
2021-11-03 05:01:10,984 INFO metabase.task :: Initializing task SendAnonymousUsageStats 📆
2021-11-03 05:01:11,019 INFO metabase.task :: Initializing task SendAbandomentEmails 📆
2021-11-03 05:01:11,049 INFO metabase.task :: Initializing task SendPulses 📆
2021-11-03 05:01:11,077 INFO metabase.task :: Initializing task SendFollowUpEmails 📆
2021-11-03 05:01:11,105 INFO metabase.task :: Initializing task TaskHistoryCleanup 📆
2021-11-03 05:01:11,151 INFO driver.impl :: Initializing driver :sql...
2021-11-03 05:01:11,158 INFO driver.impl :: Initializing driver :sql-jdbc...
2021-11-03 05:01:11,159 INFO driver.impl :: Initializing driver :postgres...
2021-11-03 05:01:11,184 INFO metabase.core :: Metabase Initialization COMPLETE
2021-11-03 05:06:00,324 INFO task.sync-databases :: Starting sync task for Database 1.
2021-11-03 05:06:00,345 INFO sync.util :: STARTING: Sync metadata for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:00,351 INFO driver.impl :: Initializing driver :h2...
2021-11-03 05:06:00,357 INFO sync.util :: STARTING: step 'sync-timezone' for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:01,295 INFO sync.util :: FINISHED: step 'sync-timezone' for h2 Database 1 'Sample Dataset' (934.2 ms)
2021-11-03 05:06:01,297 INFO sync.util :: STARTING: step 'sync-tables' for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:01,472 INFO sync-metadata.tables :: Updating description for tables: (Table  'PUBLIC.PEOPLE' Table  'PUBLIC.REVIEWS' Table  'PUBLIC.ORDERS' Table  'PUBLIC.PRODUCTS')
2021-11-03 05:06:01,473 INFO sync.util :: FINISHED: step 'sync-tables' for h2 Database 1 'Sample Dataset' (175.0 ms)
2021-11-03 05:06:01,478 INFO sync.util :: STARTING: step 'sync-fields' for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:02,076 INFO sync.util :: FINISHED: step 'sync-fields' for h2 Database 1 'Sample Dataset' (595.6 ms)
2021-11-03 05:06:02,083 INFO sync.util :: STARTING: step 'sync-fks' for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:02,133 INFO sync.util :: FINISHED: step 'sync-fks' for h2 Database 1 'Sample Dataset' (49.8 ms)
2021-11-03 05:06:02,136 INFO sync.util :: STARTING: step 'sync-metabase-metadata' for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:02,575 INFO sync.util :: FINISHED: step 'sync-metabase-metadata' for h2 Database 1 'Sample Dataset' (439.2 ms)
2021-11-03 05:06:02,737 INFO sync.util :: FINISHED: Sync metadata for h2 Database 1 'Sample Dataset' (2.4 s)
2021-11-03 05:06:02,741 INFO sync.util :: STARTING: Analyze data for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:02,754 INFO sync.util :: STARTING: step 'fingerprint-fields' for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:02,787 INFO sync.analyze :: fingerprint-fields Analyzed [*******···········································] 😞   15% Table 3 'PUBLIC.PEOPLE'
2021-11-03 05:06:02,809 INFO sync.analyze :: fingerprint-fields Analyzed [***************···································] 😕   31% Table 4 'PUBLIC.REVIEWS'
2021-11-03 05:06:02,826 INFO sync.util :: FINISHED: step 'fingerprint-fields' for h2 Database 1 'Sample Dataset' (71.8 ms)
2021-11-03 05:06:02,827 INFO sync.util :: STARTING: step 'classify-fields' for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:02,838 INFO sync.analyze :: classify-fields Analyzed [***********************···························] 😬   46% Table 3 'PUBLIC.PEOPLE'
2021-11-03 05:06:02,856 INFO sync.analyze :: classify-fields Analyzed [******************************····················] 😌   62% Table 4 'PUBLIC.REVIEWS'
2021-11-03 05:06:02,857 INFO sync.util :: FINISHED: step 'classify-fields' for h2 Database 1 'Sample Dataset' (30.3 ms)
2021-11-03 05:06:02,859 INFO sync.util :: STARTING: step 'classify-tables' for h2 Database 1 'Sample Dataset'
2021-11-03 05:06:02,875 INFO sync.analyze :: classify-tables Analyzed [**************************************············] 😋   77% Table 3 'PUBLIC.PEOPLE'
2021-11-03 05:06:02,882 INFO sync.analyze :: classify-tables Analyzed [**********************************************····] 😍   92% Table 4 'PUBLIC.REVIEWS'
2021-11-03 05:06:02,883 INFO sync.util :: FINISHED: step 'classify-tables' for h2 Database 1 'Sample Dataset' (24.0 ms)
2021-11-03 05:06:02,912 INFO sync.util :: FINISHED: Analyze data for h2 Database 1 'Sample Dataset' (170.3 ms)
2021-11-03 05:16:09,673 INFO i18n.impl :: Reading available locales from locales.clj...