• Bug#1091504: puppetdb: will FTBFS during trixie support period (2/2)

    From Santiago Vila@21:1/5 to All on Fri Dec 27 20:00:01 2024
    [continued from previous message]

    2028-06-10 12:06:58.628 UTC [29565] STATEMENT: INSERT INTO reports_20110101Z ( status_id, environment_id, catalog_uuid, receive_time, hash, transaction_uuid, puppet_version, noop, logs, report_format, start_time, producer_timestamp, cached_catalog_
    status, end_time, producer_id, report_type, configuration_version, code_id, noop_pending, certname, metrics, job_id ) VALUES ( $1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14, $15, $16, $17, $18, $19, $20, $21, $22 )
    RETURNING *
    2028-06-10 12:06:58.769 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:06:58.788 UTC [11002] LOG: checkpoint complete: wrote 12 buffers (0.0%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.003 s, sync=0.005 s, total=0.020 s; sync files=7, longest=0.002 s, average=0.001 s; distance=5476 kB, estimate=
    6978 kB; lsn=0/FD4BE4E0, redo lsn=0/FD4BE488
    2028-06-10 12:07:04.743 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:04.768 UTC [11002] LOG: checkpoint complete: wrote 202 buffers (0.2%); 0 WAL file(s) added, 0 removed, 3 recycled; write=0.007 s, sync=0.003 s, total=0.025 s; sync files=13, longest=0.002 s, average=0.001 s; distance=51723 kB, estimate=
    51723 kB; lsn=1/741438, redo lsn=1/7413E0
    2028-06-10 12:07:05.241 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:05.249 UTC [11002] LOG: checkpoint complete: wrote 15 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.009 s; sync files=9, longest=0.001 s, average=0.001 s; distance=5299 kB, estimate=
    47081 kB; lsn=1/C6E198, redo lsn=1/C6E140
    2028-06-10 12:07:05.438 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:05.452 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.003 s, sync=0.003 s, total=0.015 s; sync files=10, longest=0.002 s, average=0.001 s; distance=5323 kB, estimate=
    42905 kB; lsn=1/11A1040, redo lsn=1/11A0FE8
    2028-06-10 12:07:05.634 UTC [29659] ERROR: index row requires 22920 bytes, maximum size is 8191
    2028-06-10 12:07:05.634 UTC [29659] STATEMENT: INSERT INTO catalog_resources ( certname_id, resource, type, title, tags, exported, file, line ) VALUES ( $1, $2, $3, $4, $5, $6, $7, $8 )
    RETURNING *
    2028-06-10 12:07:05.654 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:05.662 UTC [11002] LOG: checkpoint complete: wrote 15 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.008 s; sync files=9, longest=0.001 s, average=0.001 s; distance=5346 kB, estimate=
    39149 kB; lsn=1/16D9898, redo lsn=1/16D9840
    2028-06-10 12:07:05.815 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:05.824 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.010 s; sync files=10, longest=0.002 s, average=0.001 s; distance=5334 kB, estimate=
    35768 kB; lsn=1/1C0F378, redo lsn=1/1C0F320
    2028-06-10 12:07:05.984 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:06.003 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.003 s, sync=0.003 s, total=0.019 s; sync files=10, longest=0.002 s, average=0.001 s; distance=5337 kB, estimate=
    32725 kB; lsn=1/2145998, redo lsn=1/2145940
    2028-06-10 12:07:06.160 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:06.169 UTC [11002] LOG: checkpoint complete: wrote 15 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.009 s; sync files=9, longest=0.001 s, average=0.001 s; distance=5319 kB, estimate=
    29984 kB; lsn=1/2677660, redo lsn=1/2677608
    2028-06-10 12:07:06.331 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:06.340 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.009 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5333 kB, estimate=
    27519 kB; lsn=1/2BACD38, redo lsn=1/2BACCE0
    2028-06-10 12:07:06.476 UTC [29719] ERROR: relation "reports_20110101z" does not exist at character 13
    2028-06-10 12:07:06.476 UTC [29719] STATEMENT: INSERT INTO reports_20110101Z ( status_id, environment_id, catalog_uuid, receive_time, hash, transaction_uuid, puppet_version, noop, logs, report_format, start_time, producer_timestamp, cached_catalog_
    status, end_time, producer_id, report_type, configuration_version, code_id, noop_pending, certname, metrics, job_id ) VALUES ( $1, $2, $3, $4, $5, $6, $7, $8, $9, $10, $11, $12, $13, $14, $15, $16, $17, $18, $19, $20, $21, $22 )
    RETURNING *
    2028-06-10 12:07:06.544 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:06.552 UTC [11002] LOG: checkpoint complete: wrote 16 buffers (0.0%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.002 s, sync=0.002 s, total=0.009 s; sync files=9, longest=0.001 s, average=0.001 s; distance=5399 kB, estimate=
    25307 kB; lsn=1/30F2B80, redo lsn=1/30F2B28
    2028-06-10 12:07:06.709 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:06.717 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.008 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5329 kB, estimate=
    23309 kB; lsn=1/3627268, redo lsn=1/3627210
    2028-06-10 12:07:06.914 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:06.922 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.008 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5330 kB, estimate=
    21511 kB; lsn=1/3B5BB88, redo lsn=1/3B5BB30
    2028-06-10 12:07:07.078 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:07.087 UTC [11002] LOG: checkpoint complete: wrote 15 buffers (0.0%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.002 s, sync=0.002 s, total=0.010 s; sync files=9, longest=0.001 s, average=0.001 s; distance=5330 kB, estimate=
    19893 kB; lsn=1/40906C0, redo lsn=1/4090668
    2028-06-10 12:07:07.228 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:07.236 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.009 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5323 kB, estimate=
    18436 kB; lsn=1/45C35E0, redo lsn=1/45C3588
    2028-06-10 12:07:07.395 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:07.407 UTC [11002] LOG: checkpoint complete: wrote 15 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.003 s, sync=0.003 s, total=0.012 s; sync files=9, longest=0.002 s, average=0.001 s; distance=5334 kB, estimate=
    17126 kB; lsn=1/4AF90F8, redo lsn=1/4AF90A0
    2028-06-10 12:07:07.567 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:07.577 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.002 s, sync=0.002 s, total=0.011 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5338 kB, estimate=
    15947 kB; lsn=1/502FBA8, redo lsn=1/502FB50
    2028-06-10 12:07:07.740 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:07.750 UTC [11002] LOG: checkpoint complete: wrote 22 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.010 s; sync files=11, longest=0.001 s, average=0.001 s; distance=5345 kB, estimate=
    14887 kB; lsn=1/5568230, redo lsn=1/55681D8
    2028-06-10 12:07:07.903 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:07.912 UTC [11002] LOG: checkpoint complete: wrote 22 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.009 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5332 kB, estimate=
    13932 kB; lsn=1/5A9D518, redo lsn=1/5A9D4C0
    2028-06-10 12:07:08.053 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:08.061 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.009 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5318 kB, estimate=
    13070 kB; lsn=1/5FCEFE8, redo lsn=1/5FCEF90

    lein test puppetlabs.puppetdb.scf.storage-utils-test
    2028-06-10 12:07:08.199 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:08.208 UTC [11002] LOG: checkpoint complete: wrote 15 buffers (0.0%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.002 s, sync=0.002 s, total=0.010 s; sync files=9, longest=0.001 s, average=0.001 s; distance=5315 kB, estimate=
    12295 kB; lsn=1/64FFE68, redo lsn=1/64FFE10
    2028-06-10 12:07:08.356 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:08.365 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.009 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5329 kB, estimate=
    11598 kB; lsn=1/6A34350, redo lsn=1/6A342F8

    lein test puppetlabs.puppetdb.schema-test

    lein test puppetlabs.puppetdb.status-test
    The [database] classname config option has been retired and will be ignored. The [database] subprotocol config option has been retired and will be ignored. The [read-database] classname config option has been retired and will be ignored.
    The [read-database] subprotocol config option has been retired and will be ignored.
    2028-06-10 12:07:09.052 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:09.059 UTC [11002] LOG: checkpoint complete: wrote 18 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.002 s, total=0.007 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5322 kB, estimate=
    10970 kB; lsn=1/6F66C78, redo lsn=1/6F66C20
    The [database] classname config option has been retired and will be ignored. The [database] subprotocol config option has been retired and will be ignored. The [read-database] classname config option has been retired and will be ignored.
    The [read-database] subprotocol config option has been retired and will be ignored.
    2028-06-10 12:07:09.727 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:09.737 UTC [11002] LOG: checkpoint complete: wrote 145 buffers (0.1%); 0 WAL file(s) added, 0 removed, 1 recycled; write=0.004 s, sync=0.002 s, total=0.010 s; sync files=11, longest=0.001 s, average=0.001 s; distance=5527 kB, estimate=
    10426 kB; lsn=1/74CC878, redo lsn=1/74CC820
    The [database] classname config option has been retired and will be ignored. The [database] subprotocol config option has been retired and will be ignored. The [read-database] classname config option has been retired and will be ignored.
    The [read-database] subprotocol config option has been retired and will be ignored.
    2028-06-10 12:07:10.405 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:10.413 UTC [11002] LOG: checkpoint complete: wrote 19 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.003 s, sync=0.002 s, total=0.008 s; sync files=10, longest=0.001 s, average=0.001 s; distance=5314 kB, estimate=
    9915 kB; lsn=1/79FD278, redo lsn=1/79FD220

    lein test puppetlabs.puppetdb.test-import

    lein test puppetlabs.puppetdb.threadpool-test
    Reporting unexpected error from thread testpool-1 to stderr and log
    #error {
    :cause Broken!
    :via
    [{:type java.lang.RuntimeException
    :message Broken!
    :at [puppetlabs.puppetdb.threadpool_test$fn__119713$fn__119740$fn__119741$fn__119744$fn__119750 invoke threadpool_test.clj 198]}]
    :trace
    [[puppetlabs.puppetdb.threadpool_test$fn__119713$fn__119740$fn__119741$fn__119744$fn__119750 invoke threadpool_test.clj 198]
    [puppetlabs.puppetdb.threadpool$gated_execute$fn__39003 invoke threadpool.clj 68]
    [clojure.lang.AFn run AFn.java 22]
    [java.util.concurrent.ThreadPoolExecutor runWorker ThreadPoolExecutor.java 1144]
    [java.util.concurrent.ThreadPoolExecutor$Worker run ThreadPoolExecutor.java 642]
    [java.lang.Thread run Thread.java 1583]]}
    376153 [testpool-1] ERROR puppetlabs.puppetdb.threadpool - Reporting unexpected error from thread testpool-1 to stderr and log
    java.lang.RuntimeException: Broken!
    at puppetlabs.puppetdb.threadpool_test$fn__119713$fn__119740$fn__119741$fn__119744$fn__119750.invoke(threadpool_test.clj:198)
    at puppetlabs.puppetdb.threadpool$gated_execute$fn__39003.invoke(threadpool.clj:68)
    at clojure.lang.AFn.run(AFn.java:22)
    at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1144)
    at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:642)
    at java.base/java.lang.Thread.run(Thread.java:1583)
    From error log: /tmp/tk-suppressed1844251631036-353310836.log

    lein test puppetlabs.puppetdb.time-test

    lein test puppetlabs.puppetdb.utils-test

    lein test puppetlabs.puppetdb.utils.metrics-test

    lein test puppetlabs.puppetdb.utils.string_formatter-test
    {:result true, :num-tests 50, :seed 1844251631052, :time-elapsed-ms 250, :test-var "test-dash-conversions"}
    {:result true, :num-tests 50, :seed 1844251631303, :time-elapsed-ms 306, :test-var "test-underscore-conversions"}

    lein test puppetlabs.puppetdb.zip-test
    {:result true, :num-tests 50, :seed 1844251631621, :time-elapsed-ms 744, :test-var "post-order-collect"}
    {:result true, :num-tests 50, :seed 1844251632366, :time-elapsed-ms 810, :test-var "no-op-zipper"}
    {:result true, :num-tests 50, :seed 1844251633178, :time-elapsed-ms 1629, :test-var "flip-sign-zipper"}

    Ran 674 tests containing 13547 assertions.
    0 failures, 1 errors.
    2028-06-10 12:07:14.822 UTC [11002] LOG: checkpoint starting: immediate force wait
    2028-06-10 12:07:14.836 UTC [11002] LOG: checkpoint complete: wrote 4 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.002 s, sync=0.003 s, total=0.015 s; sync files=3, longest=0.002 s, average=0.001 s; distance=22 kB, estimate=8926
    kB; lsn=1/7A02E08, redo lsn=1/7A02DB0
    Tests failed.
    ++ /<<PKGBUILDDIR>>/ext/bin/pdbbox-env pg_ctl stop
    waiting for server to shut down...2028-06-10 12:07:14.943 UTC [11001] LOG: received fast shutdown request
    .2028-06-10 12:07:14.947 UTC [11001] LOG: aborting any active transactions 2028-06-10 12:07:14.950 UTC [11001] LOG: background worker "logical replication launcher" (PID 11007) exited with exit code 1
    2028-06-10 12:07:14.951 UTC [11002] LOG: shutting down
    2028-06-10 12:07:14.953 UTC [11002] LOG: checkpoint starting: shutdown immediate
    2028-06-10 12:07:14.965 UTC [11002] LOG: checkpoint complete: wrote 1 buffers (0.0%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.003 s, sync=0.001 s, total=0.015 s; sync files=1, longest=0.001 s, average=0.001 s; distance=0 kB, estimate=8033 kB;
    lsn=1/7A02F58, redo lsn=1/7A02F58
    2028-06-10 12:07:14.984 UTC [11001] LOG: database system is shut down
    done
    server stopped
    + rm -rf core-test-scJdyk/box
    make[1]: *** [debian/rules:26: override_dh_auto_test] Error 1
    make[1]: Leaving directory '/<<PKGBUILDDIR>>'
    make: *** [debian/rules:14: binary] Error 2
    dpkg-buildpackage: error: debian/rules binary subprocess returned exit status 2 --------------------------------------------------------------------------------

    The above is just how the build ends and not necessarily the most relevant part.
    If required, the full build log is available here:

    https://people.debian.org/~sanvila/build-logs/ftbfs-future/

    About the archive rebuild: This is an effort to keep trixie free from FTBFS bugs
    during its lifetime as a supported distribution, as explained here:

    https://lists.debian.org/debian-devel/2024/05/msg00414.html

    (While they are not yet, please note that the bugs will be RC for trixie)

    The build was made using sbuild and a reduced chroot with only
    build-essential packages.

    If you could not reproduce the bug please contact me privately, as I
    am willing to provide ssh access to a virtual machine where the bug is
    fully reproducible.

    If this is really a bug in one of the build-depends, please use
    reassign and affects, so that this is still visible in the BTS web
    page for this package.

    Thanks.

    --- SoupGate-Win32 v1.05
    * Origin: fsxNet Usenet Gateway (21:1/5)