Package: puppetdb Version: 6.2.0-3 Severity: grave Justification: renders package unusable
Hi there! I've hit a bug with a new installation of puppetdb on buster (e.g. I've re-created my puppetmaster vagrant box) where puppetdb would fail to start, erroring out on an SQL upgrade of the database schema during the first service start. I'll include the error log lower down since it's pretty long. I've found a bug report on pupperware (puppet packaged up in docker containers) that describes exactly the same problem, identifies a faulty postgresql 9.6.x version and seems to point to an upstream bug report that contains a fix. https://github.com/puppetlabs/pupperware/issues/82 Since in buster we're using postgresql-11, we've had to identify which version had introduced the problem. I'm not sure about the exact minor version of postgres, but for certain when downgrading the debian package to postgres-11 version 11.3-1, then puppetdb is able to start and complete its schema upgrade. So the bug must have been introduced somewhere between 11.3 and 11.4 The upstream bug report says that there might be a fix for puppetdb available: https://tickets.puppetlabs.com/browse/PDB-4422 It might be interesting to test applying the fix from the most appropriate branch (I'm not sure whether 6.0 or 6.3 makes more sense) and then test a new install with postgresql-11 version 11.4-1 to see if it goes through the schema upgrade successfully. Here's the puppetdb log that shows the error happening during the first run of a new puppetdb 6.2.0-3 install with postgresql-11 version 11.4-1: ----------------------8<----------------------------8<-------------------------- 2019-07-15T05:11:14.759-04:00 INFO [p.p.c.services] PuppetDB version 6.2.0 2019-07-15T05:11:14.760-04:00 WARN [c.z.h.HikariConfig] The initializationFailFast propery is deprecated, see initializationFailTimeout 2019-07-15T05:11:14.761-04:00 INFO [c.z.h.HikariDataSource] PDBMigrationsPool - Starting... 2019-07-15T05:11:14.763-04:00 INFO [c.z.h.HikariDataSource] PDBMigrationsPool - Start completed. 2019-07-15T05:11:15.098-04:00 INFO [p.p.s.migrate] Applying database migration version 28 2019-07-15T05:11:15.564-04:00 INFO [p.p.s.migrate] Applied database migration version 28 in 465 ms 2019-07-15T05:11:15.564-04:00 INFO [p.p.s.migrate] Applying database migration version 29 2019-07-15T05:11:15.865-04:00 INFO [p.p.s.migrate] Applied database migration version 29 in 301 ms 2019-07-15T05:11:15.865-04:00 INFO [p.p.s.migrate] Applying database migration version 30 2019-07-15T05:11:15.870-04:00 INFO [p.p.s.migrate] Applied database migration version 30 in 5 ms 2019-07-15T05:11:15.870-04:00 INFO [p.p.s.migrate] Applying database migration version 31 2019-07-15T05:11:15.897-04:00 INFO [p.p.s.migrate] Applied database migration version 31 in 26 ms 2019-07-15T05:11:15.897-04:00 INFO [p.p.s.migrate] Applying database migration version 32 2019-07-15T05:11:15.916-04:00 INFO [p.p.s.migrate] Applied database migration version 32 in 19 ms 2019-07-15T05:11:15.917-04:00 INFO [p.p.s.migrate] Applying database migration version 33 2019-07-15T05:11:15.940-04:00 INFO [p.p.s.migrate] Applied database migration version 33 in 23 ms 2019-07-15T05:11:15.940-04:00 INFO [p.p.s.migrate] Applying database migration version 34 2019-07-15T05:11:15.992-04:00 INFO [p.p.s.migrate] Applied database migration version 34 in 52 ms 2019-07-15T05:11:15.992-04:00 INFO [p.p.s.migrate] Applying database migration version 35 2019-07-15T05:11:15.993-04:00 INFO [p.p.s.migrate] Applied database migration version 35 in 1 ms 2019-07-15T05:11:15.993-04:00 INFO [p.p.s.migrate] Applying database migration version 36 2019-07-15T05:11:15.995-04:00 INFO [p.p.s.migrate] Applied database migration version 36 in 2 ms 2019-07-15T05:11:15.995-04:00 INFO [p.p.s.migrate] Applying database migration version 37 2019-07-15T05:11:15.997-04:00 INFO [p.p.s.migrate] Applied database migration version 37 in 2 ms 2019-07-15T05:11:15.997-04:00 INFO [p.p.s.migrate] Applying database migration version 38 2019-07-15T05:11:15.999-04:00 INFO [p.p.s.migrate] Applied database migration version 38 in 1 ms 2019-07-15T05:11:15.999-04:00 INFO [p.p.s.migrate] Applying database migration version 39 2019-07-15T05:11:16.055-04:00 INFO [p.p.s.migrate] Applied database migration version 39 in 56 ms 2019-07-15T05:11:16.056-04:00 INFO [p.p.s.migrate] Applying database migration version 40 2019-07-15T05:11:16.096-04:00 INFO [p.p.s.migrate] Applied database migration version 40 in 40 ms 2019-07-15T05:11:16.097-04:00 INFO [p.p.s.migrate] Applying database migration version 41 2019-07-15T05:11:16.099-04:00 INFO [p.p.s.migrate] Applied database migration version 41 in 3 ms 2019-07-15T05:11:16.100-04:00 INFO [p.p.s.migrate] Applying database migration version 42 2019-07-15T05:11:16.192-04:00 INFO [p.p.s.migrate] Applied database migration version 42 in 92 ms 2019-07-15T05:11:16.192-04:00 INFO [p.p.s.migrate] Applying database migration version 43 2019-07-15T05:11:16.232-04:00 INFO [p.p.s.migrate] Applied database migration version 43 in 40 ms 2019-07-15T05:11:16.232-04:00 INFO [p.p.s.migrate] Applying database migration version 44 2019-07-15T05:11:16.235-04:00 INFO [p.p.s.migrate] Applied database migration version 44 in 3 ms 2019-07-15T05:11:16.235-04:00 INFO [p.p.s.migrate] Applying database migration version 45 2019-07-15T05:11:16.244-04:00 INFO [p.p.s.migrate] Applied database migration version 45 in 9 ms 2019-07-15T05:11:16.244-04:00 INFO [p.p.s.migrate] Applying database migration version 46 2019-07-15T05:11:16.246-04:00 INFO [p.p.s.migrate] Applied database migration version 46 in 2 ms 2019-07-15T05:11:16.247-04:00 INFO [p.p.s.migrate] Applying database migration version 47 2019-07-15T05:11:16.297-04:00 INFO [p.p.s.migrate] Applied database migration version 47 in 50 ms 2019-07-15T05:11:16.297-04:00 INFO [p.p.s.migrate] Applying database migration version 48 2019-07-15T05:11:16.308-04:00 INFO [p.p.s.migrate] Applied database migration version 48 in 11 ms 2019-07-15T05:11:16.309-04:00 INFO [p.p.s.migrate] Applying database migration version 49 2019-07-15T05:11:16.311-04:00 INFO [p.p.s.migrate] Applied database migration version 49 in 1 ms 2019-07-15T05:11:16.311-04:00 INFO [p.p.s.migrate] Applying database migration version 50 2019-07-15T05:11:16.315-04:00 INFO [p.p.s.migrate] Applied database migration version 50 in 4 ms 2019-07-15T05:11:16.315-04:00 INFO [p.p.s.migrate] Applying database migration version 51 2019-07-15T05:11:16.344-04:00 INFO [p.p.s.migrate] Applied database migration version 51 in 29 ms 2019-07-15T05:11:16.344-04:00 INFO [p.p.s.migrate] Applying database migration version 52 2019-07-15T05:11:16.387-04:00 INFO [p.p.s.migrate] Applied database migration version 52 in 43 ms 2019-07-15T05:11:16.388-04:00 INFO [p.p.s.migrate] Applying database migration version 53 2019-07-15T05:11:16.397-04:00 INFO [p.p.s.migrate] Applied database migration version 53 in 10 ms 2019-07-15T05:11:16.397-04:00 INFO [p.p.s.migrate] Applying database migration version 54 2019-07-15T05:11:16.399-04:00 INFO [p.p.s.migrate] Applied database migration version 54 in 2 ms 2019-07-15T05:11:16.399-04:00 INFO [p.p.s.migrate] Applying database migration version 55 2019-07-15T05:11:16.404-04:00 INFO [p.p.s.migrate] Applied database migration version 55 in 5 ms 2019-07-15T05:11:16.405-04:00 INFO [p.p.s.migrate] Applying database migration version 56 2019-07-15T05:11:16.427-04:00 INFO [p.p.s.migrate] Applied database migration version 56 in 22 ms 2019-07-15T05:11:16.428-04:00 INFO [p.p.s.migrate] Applying database migration version 57 2019-07-15T05:11:16.453-04:00 INFO [p.p.s.migrate] Applied database migration version 57 in 26 ms 2019-07-15T05:11:16.454-04:00 INFO [p.p.s.migrate] Applying database migration version 58 2019-07-15T05:11:16.456-04:00 INFO [p.p.s.migrate] Applied database migration version 58 in 2 ms 2019-07-15T05:11:16.456-04:00 INFO [p.p.s.migrate] Applying database migration version 59 2019-07-15T05:11:16.463-04:00 INFO [p.p.s.migrate] Applied database migration version 59 in 7 ms 2019-07-15T05:11:16.464-04:00 INFO [p.p.s.migrate] Applying database migration version 60 2019-07-15T05:11:16.476-04:00 INFO [p.p.s.migrate] Cleaning up orphaned edges 2019-07-15T05:11:16.488-04:00 INFO [p.p.s.migrate] Applied database migration version 60 in 24 ms 2019-07-15T05:11:16.488-04:00 INFO [p.p.s.migrate] Applying database migration version 61 2019-07-15T05:11:16.496-04:00 INFO [p.p.s.migrate] Applied database migration version 61 in 8 ms 2019-07-15T05:11:16.496-04:00 INFO [p.p.s.migrate] Applying database migration version 62 2019-07-15T05:11:16.505-04:00 INFO [p.p.s.migrate] Applied database migration version 62 in 9 ms 2019-07-15T05:11:16.505-04:00 INFO [p.p.s.migrate] Applying database migration version 63 2019-07-15T05:11:16.513-04:00 INFO [p.p.s.migrate] Applied database migration version 63 in 8 ms 2019-07-15T05:11:16.514-04:00 INFO [p.p.s.migrate] Applying database migration version 64 2019-07-15T05:11:16.514-04:00 INFO [p.p.s.migrate] [1/8] Cleaning up unreferenced facts... 2019-07-15T05:11:16.515-04:00 INFO [p.p.s.migrate] [2/8] Creating new fact storage tables... 2019-07-15T05:11:16.525-04:00 INFO [p.p.s.migrate] [3/8] Copying unique fact values into fact_values 2019-07-15T05:11:16.527-04:00 INFO [p.p.s.migrate] [4/8] Reconstructing facts to refer to fact_values... 2019-07-15T05:11:16.529-04:00 INFO [p.p.s.migrate] [5/8] Cleaning up duplicate null values... 2019-07-15T05:11:16.532-04:00 INFO [p.p.s.migrate] [6/8] Computing fact value hashes... 2019-07-15T05:11:16.535-04:00 INFO [p.p.s.migrate] [7/8] Indexing fact_values table... 2019-07-15T05:11:16.553-04:00 INFO [p.p.s.migrate] [8/8] Indexing facts table... 2019-07-15T05:11:16.568-04:00 INFO [p.p.s.migrate] Applied database migration version 64 in 54 ms 2019-07-15T05:11:16.568-04:00 INFO [p.p.s.migrate] Applying database migration version 65 2019-07-15T05:11:16.604-04:00 ERROR [p.p.s.migrate] Caught SQLException during migration java.sql.BatchUpdateException: Batch entry 1 alter table resource_events alter column status type text, alter column property type text, alter column containing_class type text, alter column file type text was aborted: ERROR: relation "resource_events_status_for_corrective_change_idx" already exists Call getNextException to see other errors in the batch. at org.postgresql.jdbc.BatchResultHandler.handleError(BatchResultHandler.java:148) at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2184) at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:481) at org.postgresql.jdbc.PgStatement.executeBatch(PgStatement.java:840) at com.zaxxer.hikari.pool.ProxyStatement.executeBatch(ProxyStatement.java:128) at com.zaxxer.hikari.pool.HikariProxyStatement.executeBatch(HikariProxyStatement.java) at clojure.java.jdbc$execute_batch.invokeStatic(jdbc.clj:508) at clojure.java.jdbc$execute_batch.invoke(jdbc.clj:501) at clojure.java.jdbc$db_do_commands$fn__26659.invoke(jdbc.clj:835) at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:748) at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:684) at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:697) at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:684) at clojure.java.jdbc$db_do_commands.invokeStatic(jdbc.clj:834) at clojure.java.jdbc$db_do_commands.invoke(jdbc.clj:819) at puppetlabs.puppetdb.jdbc$do_commands.invokeStatic(jdbc.clj:38) at puppetlabs.puppetdb.jdbc$do_commands.doInvoke(jdbc.clj:33) at clojure.lang.RestFn.invoke(RestFn.java:421) at puppetlabs.puppetdb.scf.migrate$varchar_columns_to_text.invokeStatic(migrate.clj:1453) at puppetlabs.puppetdb.scf.migrate$varchar_columns_to_text.invoke(migrate.clj:1452) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_$fn__41146$fn__41148$fn__41152.invoke(migrate.clj:1650) at puppetlabs.puppetdb.scf.migrate$sql_or_die.invokeStatic(migrate.clj:1592) at puppetlabs.puppetdb.scf.migrate$sql_or_die.invoke(migrate.clj:1583) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_$fn__41146$fn__41148.invoke(migrate.clj:1650) at clojure.core$map$fn__5851.invoke(core.clj:2755) at clojure.lang.LazySeq.sval(LazySeq.java:42) at clojure.lang.LazySeq.seq(LazySeq.java:51) at clojure.lang.RT.seq(RT.java:531) at clojure.core$seq__5387.invokeStatic(core.clj:137) at clojure.core$filter$fn__5878.invoke(core.clj:2809) at clojure.lang.LazySeq.sval(LazySeq.java:42) at clojure.lang.LazySeq.seq(LazySeq.java:58) at clojure.lang.RT.seq(RT.java:531) at clojure.core$seq__5387.invokeStatic(core.clj:137) at clojure.core$map$fn__5851.invoke(core.clj:2746) at clojure.lang.LazySeq.sval(LazySeq.java:42) at clojure.lang.LazySeq.seq(LazySeq.java:51) at clojure.lang.Cons.next(Cons.java:39) at clojure.lang.RT.boundedLength(RT.java:1788) at clojure.lang.RestFn.applyTo(RestFn.java:130) at clojure.core$apply.invokeStatic(core.clj:667) at clojure.core$apply.invoke(core.clj:660) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_$fn__41146.invoke(migrate.clj:1657) at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:714) at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:684) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_.invokeStatic(migrate.clj:1645) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_.invoke(migrate.clj:1616) at puppetlabs.puppetdb.cli.services$initialize_schema.invokeStatic(services.clj:332) at puppetlabs.puppetdb.cli.services$initialize_schema.invoke(services.clj:317) at puppetlabs.puppetdb.cli.services$init_with_db$fn__41405.invoke(services.clj:356) at puppetlabs.puppetdb.cli.services$init_with_db.invokeStatic(services.clj:352) at puppetlabs.puppetdb.cli.services$init_with_db.invoke(services.clj:336) at puppetlabs.puppetdb.cli.services$start_puppetdb.invokeStatic(services.clj:425) at puppetlabs.puppetdb.cli.services$start_puppetdb.invoke(services.clj:404) at puppetlabs.puppetdb.cli.services$reify__41517$service_fnk__23931__auto___positional$reify__41528.start(services.clj:516) at puppetlabs.trapperkeeper.services$eval23729$fn__23743$G__23719__23746.invoke(services.clj:8) at puppetlabs.trapperkeeper.services$eval23729$fn__23743$G__23718__23750.invoke(services.clj:8) at puppetlabs.trapperkeeper.internal$eval24312$run_lifecycle_fn_BANG___24319$fn__24320.invoke(internal.clj:204) at puppetlabs.trapperkeeper.internal$eval24312$run_lifecycle_fn_BANG___24319.invoke(internal.clj:187) at puppetlabs.trapperkeeper.internal$eval24341$run_lifecycle_fns__24346$fn__24347.invoke(internal.clj:238) at puppetlabs.trapperkeeper.internal$eval24341$run_lifecycle_fns__24346.invoke(internal.clj:215) at puppetlabs.trapperkeeper.internal$eval24899$build_app_STAR___24908$fn$reify__24920.start(internal.clj:591) at puppetlabs.trapperkeeper.internal$eval24947$boot_services_for_app_STAR__STAR___24954$fn__24955$fn__24957.invoke(internal.clj:617) at puppetlabs.trapperkeeper.internal$eval24947$boot_services_for_app_STAR__STAR___24954$fn__24955.invoke(internal.clj:615) at puppetlabs.trapperkeeper.internal$eval24947$boot_services_for_app_STAR__STAR___24954.invoke(internal.clj:609) at clojure.core$partial$fn__5826.invoke(core.clj:2630) at puppetlabs.trapperkeeper.internal$eval24383$initialize_lifecycle_worker__24394$fn__24395$fn__24534$state_machine__12865__auto____24559$fn__24562.invoke(internal.clj:255) at puppetlabs.trapperkeeper.internal$eval24383$initialize_lifecycle_worker__24394$fn__24395$fn__24534$state_machine__12865__auto____24559.invoke(internal.clj:255) at clojure.core.async.impl.ioc_macros$run_state_machine.invokeStatic(ioc_macros.clj:973) at clojure.core.async.impl.ioc_macros$run_state_machine.invoke(ioc_macros.clj:972) at clojure.core.async.impl.ioc_macros$run_state_machine_wrapped.invokeStatic(ioc_macros.clj:977) at clojure.core.async.impl.ioc_macros$run_state_machine_wrapped.invoke(ioc_macros.clj:975) at clojure.core.async$ioc_alts_BANG_$fn__13094.invoke(async.clj:384) at clojure.core.async$do_alts$fn__13026$fn__13029.invoke(async.clj:253) at clojure.core.async.impl.channels.ManyToManyChannel$fn__7046$fn__7047.invoke(channels.clj:95) at clojure.lang.AFn.run(AFn.java:22) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at java.base/java.lang.Thread.run(Thread.java:834) Caused by: org.postgresql.util.PSQLException: ERROR: relation "resource_events_status_for_corrective_change_idx" already exists at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2440) at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2183) ... 77 common frames omitted 2019-07-15T05:11:16.606-04:00 ERROR [p.p.s.migrate] Unravelled exception org.postgresql.util.PSQLException: ERROR: relation "resource_events_status_for_corrective_change_idx" already exists at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2440) at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2183) at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:481) at org.postgresql.jdbc.PgStatement.executeBatch(PgStatement.java:840) at com.zaxxer.hikari.pool.ProxyStatement.executeBatch(ProxyStatement.java:128) at com.zaxxer.hikari.pool.HikariProxyStatement.executeBatch(HikariProxyStatement.java) at clojure.java.jdbc$execute_batch.invokeStatic(jdbc.clj:508) at clojure.java.jdbc$execute_batch.invoke(jdbc.clj:501) at clojure.java.jdbc$db_do_commands$fn__26659.invoke(jdbc.clj:835) at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:748) at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:684) at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:697) at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:684) at clojure.java.jdbc$db_do_commands.invokeStatic(jdbc.clj:834) at clojure.java.jdbc$db_do_commands.invoke(jdbc.clj:819) at puppetlabs.puppetdb.jdbc$do_commands.invokeStatic(jdbc.clj:38) at puppetlabs.puppetdb.jdbc$do_commands.doInvoke(jdbc.clj:33) at clojure.lang.RestFn.invoke(RestFn.java:421) at puppetlabs.puppetdb.scf.migrate$varchar_columns_to_text.invokeStatic(migrate.clj:1453) at puppetlabs.puppetdb.scf.migrate$varchar_columns_to_text.invoke(migrate.clj:1452) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_$fn__41146$fn__41148$fn__41152.invoke(migrate.clj:1650) at puppetlabs.puppetdb.scf.migrate$sql_or_die.invokeStatic(migrate.clj:1592) at puppetlabs.puppetdb.scf.migrate$sql_or_die.invoke(migrate.clj:1583) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_$fn__41146$fn__41148.invoke(migrate.clj:1650) at clojure.core$map$fn__5851.invoke(core.clj:2755) at clojure.lang.LazySeq.sval(LazySeq.java:42) at clojure.lang.LazySeq.seq(LazySeq.java:51) at clojure.lang.Cons.next(Cons.java:39) at clojure.lang.RT.boundedLength(RT.java:1788) at clojure.lang.RestFn.applyTo(RestFn.java:130) at clojure.core$apply.invokeStatic(core.clj:667) at clojure.core$apply.invoke(core.clj:660) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_$fn__41146.invoke(migrate.clj:1657) at clojure.java.jdbc$db_transaction_STAR_.invokeStatic(jdbc.clj:714) at clojure.java.jdbc$db_transaction_STAR_.invoke(jdbc.clj:684) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_.invokeStatic(migrate.clj:1645) at puppetlabs.puppetdb.scf.migrate$migrate_BANG_.invoke(migrate.clj:1616) at puppetlabs.puppetdb.cli.services$initialize_schema.invokeStatic(services.clj:332) at puppetlabs.puppetdb.cli.services$initialize_schema.invoke(services.clj:317) at puppetlabs.puppetdb.cli.services$init_with_db$fn__41405.invoke(services.clj:356) at puppetlabs.puppetdb.cli.services$init_with_db.invokeStatic(services.clj:352) at puppetlabs.puppetdb.cli.services$init_with_db.invoke(services.clj:336) at puppetlabs.puppetdb.cli.services$start_puppetdb.invokeStatic(services.clj:425) at puppetlabs.puppetdb.cli.services$start_puppetdb.invoke(services.clj:404) at puppetlabs.puppetdb.cli.services$reify__41517$service_fnk__23931__auto___positional$reify__41528.start(services.clj:516) at puppetlabs.trapperkeeper.services$eval23729$fn__23743$G__23719__23746.invoke(services.clj:8) at puppetlabs.trapperkeeper.services$eval23729$fn__23743$G__23718__23750.invoke(services.clj:8) at puppetlabs.trapperkeeper.internal$eval24312$run_lifecycle_fn_BANG___24319$fn__24320.invoke(internal.clj:204) at puppetlabs.trapperkeeper.internal$eval24312$run_lifecycle_fn_BANG___24319.invoke(internal.clj:187) at puppetlabs.trapperkeeper.internal$eval24341$run_lifecycle_fns__24346$fn__24347.invoke(internal.clj:238) at puppetlabs.trapperkeeper.internal$eval24341$run_lifecycle_fns__24346.invoke(internal.clj:215) at puppetlabs.trapperkeeper.internal$eval24899$build_app_STAR___24908$fn$reify__24920.start(internal.clj:591) at puppetlabs.trapperkeeper.internal$eval24947$boot_services_for_app_STAR__STAR___24954$fn__24955$fn__24957.invoke(internal.clj:617) at puppetlabs.trapperkeeper.internal$eval24947$boot_services_for_app_STAR__STAR___24954$fn__24955.invoke(internal.clj:615) at puppetlabs.trapperkeeper.internal$eval24947$boot_services_for_app_STAR__STAR___24954.invoke(internal.clj:609) at clojure.core$partial$fn__5826.invoke(core.clj:2630) at puppetlabs.trapperkeeper.internal$eval24383$initialize_lifecycle_worker__24394$fn__24395$fn__24534$state_machine__12865__auto____24559$fn__24562.invoke(internal.clj:255) at puppetlabs.trapperkeeper.internal$eval24383$initialize_lifecycle_worker__24394$fn__24395$fn__24534$state_machine__12865__auto____24559.invoke(internal.clj:255) at clojure.core.async.impl.ioc_macros$run_state_machine.invokeStatic(ioc_macros.clj:973) at clojure.core.async.impl.ioc_macros$run_state_machine.invoke(ioc_macros.clj:972) at clojure.core.async.impl.ioc_macros$run_state_machine_wrapped.invokeStatic(ioc_macros.clj:977) at clojure.core.async.impl.ioc_macros$run_state_machine_wrapped.invoke(ioc_macros.clj:975) at clojure.core.async$ioc_alts_BANG_$fn__13094.invoke(async.clj:384) at clojure.core.async$do_alts$fn__13026$fn__13029.invoke(async.clj:253) at clojure.core.async.impl.channels.ManyToManyChannel$fn__7046$fn__7047.invoke(channels.clj:95) at clojure.lang.AFn.run(AFn.java:22) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) at java.base/java.lang.Thread.run(Thread.java:834) 2019-07-15T05:11:16.624-04:00 INFO [p.t.internal] Shutting down due to JVM shutdown hook. 2019-07-15T05:11:16.625-04:00 INFO [p.t.internal] Beginning shutdown sequence 2019-07-15T05:11:16.767-04:00 INFO [p.p.pdb-routing] HTTP request received while in maintenance mode ---------------------->8---------------------------->8-------------------------- -- System Information: Debian Release: 10.0 APT prefers testing APT policy: (500, 'testing') Architecture: amd64 (x86_64) Kernel: Linux 4.19.0-5-amd64 (SMP w/2 CPU cores) Locale: LANG=en_CA.UTF-8, LC_CTYPE=en_CA.UTF-8 (charmap=UTF-8) (ignored: LC_ALL set to en_CA.UTF-8), LANGUAGE=en_CA.UTF-8 (charmap=UTF-8) (ignored: LC_ALL set to en_CA.UTF-8) Shell: /bin/sh linked to /usr/bin/dash Init: systemd (via /run/systemd/system) LSM: AppArmor: enabled Versions of packages puppetdb depends on: ii adduser 3.118 ii dbconfig-no-thanks 2.0.11 ii debconf [debconf-2.0] 1.5.71 ii default-jre-headless 2:1.11-71 ii libasm-java 7.0-1 ii libat-at-clojure 1.2.0-1 ii libbidi-clojure 2.1.2-2 ii libcheshire-clojure 5.7.1-1 ii libclj-digest-clojure 1.4.5-2 ii libclj-http-clojure 2.3.0-1 ii libclj-stacktrace-clojure 0.2.6-3 ii libclj-time-clojure 0.14.0-2 ii libclojure-java 1.10.0-1 ii libcommons-compress-java 1.18-2 ii libcommons-lang3-java 3.8-2 ii libcommons-logging-java 1.2-2 ii libcompojure-clojure 1.6.0-1 ii libcore-async-clojure 0.3.443-1 ii libcore-match-clojure 0.2.2-1 ii libcore-memoize-clojure 0.5.9-1 ii libdata-priority-map-clojure 0.0.7-1 ii libdujour-version-check-clojure 0.2.2-1 ii libfast-zip-visit-clojure 1.0.2-2 ii libgeronimo-j2ee-management-1.1-spec-java 1.0.1-1 ii libgeronimo-jms-1.1-spec-java 1.1-1.2 ii libhikaricp-java 2.7.1-2 ii libhoneysql-clojure 0.8.2-2 ii libinstaparse-clojure 1.4.7-1 ii libjava-jdbc-clojure 0.7.0-2 ii libjava-jmx-clojure 0.3.4-1 ii libkitchensink-clojure 2.3.0-2 ii libmath-combinatorics-clojure 0.1.4-1 ii libmath-numeric-tower-clojure 0.0.4-1 ii libmetrics-clojure 2.9.0-2 ii libpantomime-clojure 2.1.0+dfsg-1 ii libpostgresql-jdbc-java 42.2.5-2 ii libprismatic-schema-clojure 1.1.6-1 ii libpuppetlabs-http-client-clojure 0.9.0-1 ii libpuppetlabs-i18n-clojure 0.8.0-1 ii libraynes-fs-clojure 1.4.6-1 ii libring-core-clojure 1.6.2-2 ii librobert-hooke-clojure 1.3.0-4 ii libslf4j-java 1.7.25-3 ii libslingshot-clojure 0.12.2-2 ii libspecter-clojure 1.0.2-2 ii libstockpile-clojure 0.0.4-1 ii libtools-logging-clojure 0.2.3-6 ii libtools-macro-clojure 0.1.5-2 ii libtools-namespace-clojure 0.2.11-1 ii libtrapperkeeper-clojure 1.5.2-2 ii libtrapperkeeper-metrics-clojure 0.4.2-1 ii libtrapperkeeper-status-clojure 0.7.1-2 ii libtrapperkeeper-webserver-jetty9-clojure 1.7.0-2 ii libversioneer-clojure 0.1.1-3 ii lsb-base 10.2019051400 ii ucf 3.0038+nmu1 puppetdb recommends no packages. Versions of packages puppetdb suggests: ii postgresql 11+200+deb10u1 pn postgresql-contrib <none> -- Configuration Files: /etc/default/puppetdb changed: JAVA_ARGS="-Xmx328m" /etc/puppetdb/conf.d/config.ini [Errno 13] Permission denied: '/etc/puppetdb/conf.d/config.ini' /etc/puppetdb/conf.d/jetty.ini [Errno 13] Permission denied: '/etc/puppetdb/conf.d/jetty.ini' -- no debconf information