0s autopkgtest [13:30:42]: starting date and time: 2024-06-03 13:30:42+0000 0s autopkgtest [13:30:42]: git checkout: 930f72e5 setup-testbed: don't install 'Recommends' for 'linux-generic' 0s autopkgtest [13:30:42]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.qt3gm67q/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:libxml2,src:libxml-libxml-perl --apt-upgrade pgbackrest --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=libxml2/2.12.7+dfsg-3 libxml-libxml-perl/2.0207+dfsg+really+2.0134-3' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-ppc64el-13.secgroup --name adt-oracular-ppc64el-pgbackrest-20240603-133042-juju-7f2275-prod-proposed-migration-environment-3-e4ebe723-0476-4c4e-aa80-9aeb5b64287d --image adt/ubuntu-oracular-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 137s autopkgtest [13:32:59]: testbed dpkg architecture: ppc64el 137s autopkgtest [13:32:59]: testbed apt version: 2.9.3 137s autopkgtest [13:32:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup 138s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 138s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [384 kB] 139s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [43.4 kB] 139s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [4792 B] 139s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 139s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [69.7 kB] 139s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [348 kB] 139s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [1392 B] 139s Fetched 964 kB in 1s (1233 kB/s) 139s Reading package lists... 142s Reading package lists... 142s Building dependency tree... 142s Reading state information... 142s Calculating upgrade... 142s The following packages will be upgraded: 142s libxml2 142s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 142s Need to get 728 kB of archives. 142s After this operation, 516 kB disk space will be freed. 142s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libxml2 ppc64el 2.12.7+dfsg-3 [728 kB] 143s Fetched 728 kB in 0s (1466 kB/s) 143s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 72596 files and directories currently installed.) 143s Preparing to unpack .../libxml2_2.12.7+dfsg-3_ppc64el.deb ... 143s Unpacking libxml2:ppc64el (2.12.7+dfsg-3) over (2.9.14+dfsg-1.3ubuntu3) ... 143s Setting up libxml2:ppc64el (2.12.7+dfsg-3) ... 143s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 143s Reading package lists... 143s Building dependency tree... 143s Reading state information... 143s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 144s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 144s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 144s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 144s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 145s Reading package lists... 145s Reading package lists... 145s Building dependency tree... 145s Reading state information... 145s Calculating upgrade... 146s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 146s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 150s autopkgtest [13:33:12]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 150s autopkgtest [13:33:12]: @@@@@@@@@@@@@@@@@@@@ apt-source pgbackrest 154s Get:1 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (dsc) [2311 B] 154s Get:2 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (tar) [6120 kB] 154s Get:3 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (diff) [21.2 kB] 154s gpgv: Signature made Tue Mar 26 14:14:21 2024 UTC 155s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 155s gpgv: Can't check signature: No public key 155s dpkg-source: warning: cannot verify inline signature for ./pgbackrest_2.51-1.dsc: no acceptable signature found 155s autopkgtest [13:33:17]: testing package pgbackrest version 2.51-1 155s autopkgtest [13:33:17]: build not needed 167s autopkgtest [13:33:29]: test version: preparing testbed 169s Reading package lists... 169s Building dependency tree... 169s Reading state information... 169s Starting pkgProblemResolver with broken count: 0 169s Starting 2 pkgProblemResolver with broken count: 0 169s Done 169s The following additional packages will be installed: 169s libjson-perl libpq5 libssh2-1t64 libxslt1.1 pgbackrest pgbackrest-doc 169s postgresql postgresql-16 postgresql-client-16 postgresql-client-common 169s postgresql-common ssl-cert 169s Suggested packages: 169s check-pgbackrest postgresql-doc postgresql-doc-16 169s Recommended packages: 169s libjson-xs-perl 169s The following NEW packages will be installed: 169s autopkgtest-satdep libjson-perl libpq5 libssh2-1t64 libxslt1.1 pgbackrest 169s pgbackrest-doc postgresql postgresql-16 postgresql-client-16 169s postgresql-client-common postgresql-common ssl-cert 169s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 169s Need to get 19.9 MB/19.9 MB of archives. 169s After this operation, 67.5 MB of additional disk space will be used. 169s Get:1 /tmp/autopkgtest.kRTOxU/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [724 B] 169s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libjson-perl all 4.10000-1 [81.9 kB] 170s Get:3 http://ftpmaster.internal/ubuntu oracular/main ppc64el postgresql-client-common all 260 [36.4 kB] 170s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el ssl-cert all 1.1.2ubuntu1 [17.8 kB] 170s Get:5 http://ftpmaster.internal/ubuntu oracular/main ppc64el postgresql-common all 260 [162 kB] 170s Get:6 http://ftpmaster.internal/ubuntu oracular/main ppc64el libpq5 ppc64el 16.3-1 [171 kB] 170s Get:7 http://ftpmaster.internal/ubuntu oracular/main ppc64el libssh2-1t64 ppc64el 1.11.0-5 [154 kB] 170s Get:8 http://ftpmaster.internal/ubuntu oracular/main ppc64el libxslt1.1 ppc64el 1.1.39-0exp1build1 [192 kB] 170s Get:9 http://ftpmaster.internal/ubuntu oracular/universe ppc64el pgbackrest ppc64el 2.51-1 [619 kB] 170s Get:10 http://ftpmaster.internal/ubuntu oracular/universe ppc64el pgbackrest-doc all 2.51-1 [125 kB] 170s Get:11 http://ftpmaster.internal/ubuntu oracular/main ppc64el postgresql-client-16 ppc64el 16.3-1 [1395 kB] 170s Get:12 http://ftpmaster.internal/ubuntu oracular/main ppc64el postgresql-16 ppc64el 16.3-1 [16.9 MB] 172s Get:13 http://ftpmaster.internal/ubuntu oracular/main ppc64el postgresql all 16+260 [11.6 kB] 172s Preconfiguring packages ... 172s Fetched 19.9 MB in 2s (9007 kB/s) 172s Selecting previously unselected package libjson-perl. 172s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 72596 files and directories currently installed.) 172s Preparing to unpack .../00-libjson-perl_4.10000-1_all.deb ... 172s Unpacking libjson-perl (4.10000-1) ... 172s Selecting previously unselected package postgresql-client-common. 172s Preparing to unpack .../01-postgresql-client-common_260_all.deb ... 172s Unpacking postgresql-client-common (260) ... 172s Selecting previously unselected package ssl-cert. 172s Preparing to unpack .../02-ssl-cert_1.1.2ubuntu1_all.deb ... 172s Unpacking ssl-cert (1.1.2ubuntu1) ... 172s Selecting previously unselected package postgresql-common. 172s Preparing to unpack .../03-postgresql-common_260_all.deb ... 172s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 172s Unpacking postgresql-common (260) ... 172s Selecting previously unselected package libpq5:ppc64el. 172s Preparing to unpack .../04-libpq5_16.3-1_ppc64el.deb ... 172s Unpacking libpq5:ppc64el (16.3-1) ... 172s Selecting previously unselected package libssh2-1t64:ppc64el. 172s Preparing to unpack .../05-libssh2-1t64_1.11.0-5_ppc64el.deb ... 172s Unpacking libssh2-1t64:ppc64el (1.11.0-5) ... 172s Selecting previously unselected package libxslt1.1:ppc64el. 172s Preparing to unpack .../06-libxslt1.1_1.1.39-0exp1build1_ppc64el.deb ... 172s Unpacking libxslt1.1:ppc64el (1.1.39-0exp1build1) ... 172s Selecting previously unselected package pgbackrest. 172s Preparing to unpack .../07-pgbackrest_2.51-1_ppc64el.deb ... 172s Unpacking pgbackrest (2.51-1) ... 172s Selecting previously unselected package pgbackrest-doc. 172s Preparing to unpack .../08-pgbackrest-doc_2.51-1_all.deb ... 172s Unpacking pgbackrest-doc (2.51-1) ... 172s Selecting previously unselected package postgresql-client-16. 172s Preparing to unpack .../09-postgresql-client-16_16.3-1_ppc64el.deb ... 172s Unpacking postgresql-client-16 (16.3-1) ... 172s Selecting previously unselected package postgresql-16. 172s Preparing to unpack .../10-postgresql-16_16.3-1_ppc64el.deb ... 172s Unpacking postgresql-16 (16.3-1) ... 173s Selecting previously unselected package postgresql. 173s Preparing to unpack .../11-postgresql_16+260_all.deb ... 173s Unpacking postgresql (16+260) ... 173s Selecting previously unselected package autopkgtest-satdep. 173s Preparing to unpack .../12-1-autopkgtest-satdep.deb ... 173s Unpacking autopkgtest-satdep (0) ... 173s Setting up postgresql-client-common (260) ... 173s Setting up libpq5:ppc64el (16.3-1) ... 173s Setting up ssl-cert (1.1.2ubuntu1) ... 173s Created symlink /etc/systemd/system/multi-user.target.wants/ssl-cert.service → /usr/lib/systemd/system/ssl-cert.service. 174s Setting up pgbackrest-doc (2.51-1) ... 174s Setting up libjson-perl (4.10000-1) ... 174s Setting up libxslt1.1:ppc64el (1.1.39-0exp1build1) ... 174s Setting up libssh2-1t64:ppc64el (1.11.0-5) ... 174s Setting up postgresql-client-16 (16.3-1) ... 174s update-alternatives: using /usr/share/postgresql/16/man/man1/psql.1.gz to provide /usr/share/man/man1/psql.1.gz (psql.1.gz) in auto mode 174s Setting up postgresql-common (260) ... 174s 174s Creating config file /etc/postgresql-common/createcluster.conf with new version 175s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 175s Removing obsolete dictionary files: 175s Created symlink /etc/systemd/system/multi-user.target.wants/postgresql.service → /usr/lib/systemd/system/postgresql.service. 176s Setting up postgresql-16 (16.3-1) ... 176s Creating new PostgreSQL cluster 16/main ... 176s /usr/lib/postgresql/16/bin/initdb -D /var/lib/postgresql/16/main --auth-local peer --auth-host scram-sha-256 --no-instructions 176s The files belonging to this database system will be owned by user "postgres". 176s This user must also own the server process. 176s 176s The database cluster will be initialized with locale "C.UTF-8". 176s The default database encoding has accordingly been set to "UTF8". 176s The default text search configuration will be set to "english". 176s 176s Data page checksums are disabled. 176s 176s fixing permissions on existing directory /var/lib/postgresql/16/main ... ok 176s creating subdirectories ... ok 176s selecting dynamic shared memory implementation ... posix 176s selecting default max_connections ... 100 176s selecting default shared_buffers ... 128MB 176s selecting default time zone ... Etc/UTC 176s creating configuration files ... ok 176s running bootstrap script ... ok 177s performing post-bootstrap initialization ... ok 177s syncing data to disk ... ok 180s Setting up pgbackrest (2.51-1) ... 181s Setting up postgresql (16+260) ... 181s Setting up autopkgtest-satdep (0) ... 181s Processing triggers for man-db (2.12.1-1) ... 182s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 184s (Reading database ... 74573 files and directories currently installed.) 184s Removing autopkgtest-satdep (0) ... 185s autopkgtest [13:33:47]: test version: [----------------------- 186s pgBackRest 2.51 186s autopkgtest [13:33:48]: test version: -----------------------] 187s autopkgtest [13:33:49]: test version: - - - - - - - - - - results - - - - - - - - - - 187s version PASS 187s autopkgtest [13:33:49]: test suite: preparing testbed 189s Reading package lists... 190s Building dependency tree... 190s Reading state information... 190s Starting pkgProblemResolver with broken count: 0 190s Starting 2 pkgProblemResolver with broken count: 0 190s Done 190s The following NEW packages will be installed: 190s autopkgtest-satdep 190s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 190s Need to get 0 B/720 B of archives. 190s After this operation, 0 B of additional disk space will be used. 190s Get:1 /tmp/autopkgtest.kRTOxU/2-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [720 B] 190s Selecting previously unselected package autopkgtest-satdep. 190s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 74573 files and directories currently installed.) 190s Preparing to unpack .../2-autopkgtest-satdep.deb ... 190s Unpacking autopkgtest-satdep (0) ... 190s Setting up autopkgtest-satdep (0) ... 192s (Reading database ... 74573 files and directories currently installed.) 192s Removing autopkgtest-satdep (0) ... 193s autopkgtest [13:33:55]: test suite: [----------------------- 194s Creating new PostgreSQL cluster 16/regress ... 197s 2024-06-03 13:33:59.099 P00 INFO: stanza-create command begin 2.51: --config=/tmp/tmp.xqnIjYvNyD --exec-id=4575-ae7b32d7 --lock-path=/tmp/tmp.lVqi2hJuZD --log-level-console=info --log-path=/tmp/tmp.MqxpLJI81U --pg1-path=/tmp/pg_virtualenv.lZv8wK/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.BttNb65VtQ --stanza=demo 197s 2024-06-03 13:33:59.702 P00 INFO: stanza-create for stanza 'demo' on repo1 197s 2024-06-03 13:33:59.712 P00 INFO: stanza-create command end: completed successfully (616ms) 197s 2024-06-03 13:33:59.720 P00 INFO: check command begin 2.51: --config=/tmp/tmp.xqnIjYvNyD --exec-id=4577-8cf43a54 --log-level-console=info --log-path=/tmp/tmp.MqxpLJI81U --pg1-path=/tmp/pg_virtualenv.lZv8wK/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.BttNb65VtQ --stanza=demo 198s 2024-06-03 13:34:00.324 P00 INFO: check repo1 configuration (primary) 198s 2024-06-03 13:34:00.425 P00 INFO: check repo1 archive for WAL (primary) 198s 2024-06-03 13:34:00.727 P00 INFO: WAL segment 000000010000000000000001 successfully archived to '/tmp/tmp.BttNb65VtQ/archive/demo/16-1/0000000100000000/000000010000000000000001-653a39590722bb0bbea359135d3dd08487845dc5.gz' on repo1 198s 2024-06-03 13:34:00.727 P00 INFO: check command end: completed successfully (1009ms) 198s 2024-06-03 13:34:00.737 P00 INFO: backup command begin 2.51: --config=/tmp/tmp.xqnIjYvNyD --exec-id=4581-cb7114f4 --lock-path=/tmp/tmp.lVqi2hJuZD --log-level-console=info --log-path=/tmp/tmp.MqxpLJI81U --pg1-path=/tmp/pg_virtualenv.lZv8wK/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.BttNb65VtQ --repo1-retention-full=2 --stanza=demo 199s 2024-06-03 13:34:01.442 P00 INFO: execute non-exclusive backup start: backup begins after the next regular checkpoint completes 199s WARN: no prior backup exists, incr backup has been changed to full 200s 2024-06-03 13:34:02.047 P00 INFO: backup start archive = 000000010000000000000002, lsn = 0/2000028 200s 2024-06-03 13:34:02.048 P00 INFO: check archive for segment 000000010000000000000002 202s 2024-06-03 13:34:04.761 P00 INFO: execute non-exclusive backup stop and wait for all WAL segments to archive 203s 2024-06-03 13:34:04.963 P00 INFO: backup stop archive = 000000010000000000000003, lsn = 0/3000050 203s 2024-06-03 13:34:04.967 P00 INFO: check archive for segment(s) 000000010000000000000002:000000010000000000000003 203s 2024-06-03 13:34:04.974 P00 INFO: new backup label = 20240603-133401F 203s 2024-06-03 13:34:05.005 P00 INFO: full backup size = 22MB, file total = 963 203s 2024-06-03 13:34:05.005 P00 INFO: backup command end: completed successfully (4270ms) 203s 2024-06-03 13:34:05.005 P00 INFO: expire command begin 2.51: --config=/tmp/tmp.xqnIjYvNyD --exec-id=4581-cb7114f4 --lock-path=/tmp/tmp.lVqi2hJuZD --log-level-console=info --log-path=/tmp/tmp.MqxpLJI81U --repo1-path=/tmp/tmp.BttNb65VtQ --repo1-retention-full=2 --stanza=demo 203s 2024-06-03 13:34:05.009 P00 INFO: expire command end: completed successfully (4ms) 203s stanza: demo 203s status: ok 203s cipher: none 203s 203s db (current) 203s wal archive min/max (16): 000000010000000000000001/000000010000000000000003 203s 203s full backup: 20240603-133401F 203s timestamp start/stop: 2024-06-03 13:34:01+00 / 2024-06-03 13:34:04+00 203s wal start/stop: 000000010000000000000002 / 000000010000000000000003 203s database size: 22MB, database backup size: 22MB 203s repo1: backup set size: 2.9MB, backup size: 2.9MB 203s Cleaning files... 203s Dropping cluster 16/regress ... 203s autopkgtest [13:34:05]: test suite: -----------------------] 204s autopkgtest [13:34:06]: test suite: - - - - - - - - - - results - - - - - - - - - - 204s suite PASS 204s autopkgtest [13:34:06]: @@@@@@@@@@@@@@@@@@@@ summary 204s version PASS 204s suite PASS 212s Creating nova instance adt-oracular-ppc64el-pgbackrest-20240603-133042-juju-7f2275-prod-proposed-migration-environment-3-e4ebe723-0476-4c4e-aa80-9aeb5b64287d from image adt/ubuntu-oracular-ppc64el-server-20240603.img (UUID 062f8fe3-b9b8-44c5-9911-f4a93486fbaf)...