0s autopkgtest [13:43:46]: starting date and time: 2024-06-03 13:43:46+0000 0s autopkgtest [13:43:46]: git checkout: d60bd36 autopkgtest-build-lxd: Add missing remote prefix in get_boot_id(). 0s autopkgtest [13:43:46]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.955b3mdi/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_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\n" >> /etc/environment' --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' -- lxd -r lxd-armhf-10.145.243.70 lxd-armhf-10.145.243.70:autopkgtest/ubuntu/oracular/armhf 30s autopkgtest [13:44:16]: testbed dpkg architecture: armhf 32s autopkgtest [13:44:18]: testbed apt version: 2.9.3 32s autopkgtest [13:44:18]: @@@@@@@@@@@@@@@@@@@@ test bed setup 40s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 40s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 40s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [384 kB] 40s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [43.4 kB] 40s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [4792 B] 40s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf Packages [46.4 kB] 40s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf Packages [273 kB] 40s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse armhf Packages [1392 B] 40s Fetched 865 kB in 1s (1054 kB/s) 41s Reading package lists... 57s tee: /proc/self/fd/2: Permission denied 78s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 78s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 78s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 78s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 79s Reading package lists... 79s Reading package lists... 80s Building dependency tree... 80s Reading state information... 80s Calculating upgrade... 81s The following packages will be upgraded: 81s libxml2 81s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 81s Need to get 560 kB of archives. 81s After this operation, 60.4 kB disk space will be freed. 81s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libxml2 armhf 2.12.7+dfsg-3 [560 kB] 81s Fetched 560 kB in 0s (1173 kB/s) 81s (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 ... 58332 files and directories currently installed.) 81s Preparing to unpack .../libxml2_2.12.7+dfsg-3_armhf.deb ... 81s Unpacking libxml2:armhf (2.12.7+dfsg-3) over (2.9.14+dfsg-1.3ubuntu3) ... 82s Setting up libxml2:armhf (2.12.7+dfsg-3) ... 82s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 85s autopkgtest [13:45:11]: rebooting testbed after setup commands that affected boot 123s autopkgtest [13:45:49]: testbed running kernel: Linux 6.5.0-35-generic #35~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue May 7 11:19:33 UTC 2 150s autopkgtest [13:46:16]: @@@@@@@@@@@@@@@@@@@@ apt-source pgbackrest 164s Get:1 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (dsc) [2311 B] 164s Get:2 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (tar) [6120 kB] 164s Get:3 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (diff) [21.2 kB] 164s gpgv: Signature made Tue Mar 26 14:14:21 2024 UTC 164s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 164s gpgv: Can't check signature: No public key 164s dpkg-source: warning: cannot verify inline signature for ./pgbackrest_2.51-1.dsc: no acceptable signature found 165s autopkgtest [13:46:31]: testing package pgbackrest version 2.51-1 167s autopkgtest [13:46:33]: build not needed 171s autopkgtest [13:46:37]: test version: preparing testbed 181s Reading package lists... 181s Building dependency tree... 181s Reading state information... 182s Starting pkgProblemResolver with broken count: 0 182s Starting 2 pkgProblemResolver with broken count: 0 182s Done 182s The following additional packages will be installed: 182s libjson-perl libllvm18 libpq5 libssh2-1t64 libxslt1.1 pgbackrest 182s pgbackrest-doc postgresql postgresql-16 postgresql-client-16 182s postgresql-client-common postgresql-common ssl-cert 182s Suggested packages: 182s check-pgbackrest postgresql-doc postgresql-doc-16 182s Recommended packages: 182s libjson-xs-perl 182s The following NEW packages will be installed: 182s autopkgtest-satdep libjson-perl libllvm18 libpq5 libssh2-1t64 libxslt1.1 182s pgbackrest pgbackrest-doc postgresql postgresql-16 postgresql-client-16 182s postgresql-client-common postgresql-common ssl-cert 182s 0 upgraded, 14 newly installed, 0 to remove and 0 not upgraded. 182s Need to get 43.8 MB/43.8 MB of archives. 182s After this operation, 160 MB of additional disk space will be used. 182s Get:1 /tmp/autopkgtest.BM9FOl/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [716 B] 182s Get:2 http://ftpmaster.internal/ubuntu oracular/main armhf libjson-perl all 4.10000-1 [81.9 kB] 183s Get:3 http://ftpmaster.internal/ubuntu oracular/main armhf postgresql-client-common all 260 [36.4 kB] 183s Get:4 http://ftpmaster.internal/ubuntu oracular/main armhf ssl-cert all 1.1.2ubuntu1 [17.8 kB] 183s Get:5 http://ftpmaster.internal/ubuntu oracular/main armhf postgresql-common all 260 [162 kB] 183s Get:6 http://ftpmaster.internal/ubuntu oracular/main armhf libllvm18 armhf 1:18.1.6-1 [26.7 MB] 184s Get:7 http://ftpmaster.internal/ubuntu oracular/main armhf libpq5 armhf 16.3-1 [122 kB] 184s Get:8 http://ftpmaster.internal/ubuntu oracular/main armhf libssh2-1t64 armhf 1.11.0-5 [102 kB] 184s Get:9 http://ftpmaster.internal/ubuntu oracular/main armhf libxslt1.1 armhf 1.1.39-0exp1build1 [150 kB] 184s Get:10 http://ftpmaster.internal/ubuntu oracular/universe armhf pgbackrest armhf 2.51-1 [481 kB] 184s Get:11 http://ftpmaster.internal/ubuntu oracular/universe armhf pgbackrest-doc all 2.51-1 [125 kB] 184s Get:12 http://ftpmaster.internal/ubuntu oracular/main armhf postgresql-client-16 armhf 16.3-1 [1228 kB] 184s Get:13 http://ftpmaster.internal/ubuntu oracular/main armhf postgresql-16 armhf 16.3-1 [14.6 MB] 184s Get:14 http://ftpmaster.internal/ubuntu oracular/main armhf postgresql all 16+260 [11.6 kB] 184s Preconfiguring packages ... 184s Fetched 43.8 MB in 2s (23.9 MB/s) 184s Selecting previously unselected package libjson-perl. 184s (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 ... 58332 files and directories currently installed.) 184s Preparing to unpack .../00-libjson-perl_4.10000-1_all.deb ... 184s Unpacking libjson-perl (4.10000-1) ... 184s Selecting previously unselected package postgresql-client-common. 184s Preparing to unpack .../01-postgresql-client-common_260_all.deb ... 184s Unpacking postgresql-client-common (260) ... 184s Selecting previously unselected package ssl-cert. 185s Preparing to unpack .../02-ssl-cert_1.1.2ubuntu1_all.deb ... 185s Unpacking ssl-cert (1.1.2ubuntu1) ... 185s Selecting previously unselected package postgresql-common. 185s Preparing to unpack .../03-postgresql-common_260_all.deb ... 185s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 185s Unpacking postgresql-common (260) ... 185s Selecting previously unselected package libllvm18:armhf. 185s Preparing to unpack .../04-libllvm18_1%3a18.1.6-1_armhf.deb ... 185s Unpacking libllvm18:armhf (1:18.1.6-1) ... 185s Selecting previously unselected package libpq5:armhf. 185s Preparing to unpack .../05-libpq5_16.3-1_armhf.deb ... 185s Unpacking libpq5:armhf (16.3-1) ... 185s Selecting previously unselected package libssh2-1t64:armhf. 185s Preparing to unpack .../06-libssh2-1t64_1.11.0-5_armhf.deb ... 185s Unpacking libssh2-1t64:armhf (1.11.0-5) ... 185s Selecting previously unselected package libxslt1.1:armhf. 185s Preparing to unpack .../07-libxslt1.1_1.1.39-0exp1build1_armhf.deb ... 185s Unpacking libxslt1.1:armhf (1.1.39-0exp1build1) ... 185s Selecting previously unselected package pgbackrest. 185s Preparing to unpack .../08-pgbackrest_2.51-1_armhf.deb ... 185s Unpacking pgbackrest (2.51-1) ... 185s Selecting previously unselected package pgbackrest-doc. 185s Preparing to unpack .../09-pgbackrest-doc_2.51-1_all.deb ... 185s Unpacking pgbackrest-doc (2.51-1) ... 186s Selecting previously unselected package postgresql-client-16. 186s Preparing to unpack .../10-postgresql-client-16_16.3-1_armhf.deb ... 186s Unpacking postgresql-client-16 (16.3-1) ... 186s Selecting previously unselected package postgresql-16. 186s Preparing to unpack .../11-postgresql-16_16.3-1_armhf.deb ... 186s Unpacking postgresql-16 (16.3-1) ... 186s Selecting previously unselected package postgresql. 186s Preparing to unpack .../12-postgresql_16+260_all.deb ... 186s Unpacking postgresql (16+260) ... 186s Selecting previously unselected package autopkgtest-satdep. 186s Preparing to unpack .../13-1-autopkgtest-satdep.deb ... 186s Unpacking autopkgtest-satdep (0) ... 186s Setting up postgresql-client-common (260) ... 186s Setting up libpq5:armhf (16.3-1) ... 186s Setting up ssl-cert (1.1.2ubuntu1) ... 187s Created symlink /etc/systemd/system/multi-user.target.wants/ssl-cert.service → /usr/lib/systemd/system/ssl-cert.service. 188s Setting up pgbackrest-doc (2.51-1) ... 188s Setting up libjson-perl (4.10000-1) ... 188s Setting up libxslt1.1:armhf (1.1.39-0exp1build1) ... 188s Setting up libssh2-1t64:armhf (1.11.0-5) ... 188s Setting up libllvm18:armhf (1:18.1.6-1) ... 188s Setting up postgresql-client-16 (16.3-1) ... 188s 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 188s Setting up postgresql-common (260) ... 189s 189s Creating config file /etc/postgresql-common/createcluster.conf with new version 189s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 189s Removing obsolete dictionary files: 189s Created symlink /etc/systemd/system/multi-user.target.wants/postgresql.service → /usr/lib/systemd/system/postgresql.service. 190s Setting up postgresql-16 (16.3-1) ... 190s Creating new PostgreSQL cluster 16/main ... 190s /usr/lib/postgresql/16/bin/initdb -D /var/lib/postgresql/16/main --auth-local peer --auth-host scram-sha-256 --no-instructions 190s The files belonging to this database system will be owned by user "postgres". 190s This user must also own the server process. 190s 190s The database cluster will be initialized with locale "C.UTF-8". 190s The default database encoding has accordingly been set to "UTF8". 190s The default text search configuration will be set to "english". 190s 190s Data page checksums are disabled. 190s 190s fixing permissions on existing directory /var/lib/postgresql/16/main ... ok 190s creating subdirectories ... ok 190s selecting dynamic shared memory implementation ... posix 190s selecting default max_connections ... 100 190s selecting default shared_buffers ... 128MB 190s selecting default time zone ... Etc/UTC 190s creating configuration files ... ok 190s running bootstrap script ... ok 191s performing post-bootstrap initialization ... ok 191s syncing data to disk ... ok 194s Setting up pgbackrest (2.51-1) ... 195s Setting up postgresql (16+260) ... 195s Setting up autopkgtest-satdep (0) ... 195s Processing triggers for man-db (2.12.1-1) ... 196s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 208s (Reading database ... 60320 files and directories currently installed.) 208s Removing autopkgtest-satdep (0) ... 214s autopkgtest [13:47:20]: test version: [----------------------- 216s pgBackRest 2.51 216s autopkgtest [13:47:22]: test version: -----------------------] 220s autopkgtest [13:47:26]: test version: - - - - - - - - - - results - - - - - - - - - - 220s version PASS 224s autopkgtest [13:47:30]: test suite: preparing testbed 234s Reading package lists... 234s Building dependency tree... 234s Reading state information... 234s Starting pkgProblemResolver with broken count: 0 234s Starting 2 pkgProblemResolver with broken count: 0 234s Done 235s The following NEW packages will be installed: 235s autopkgtest-satdep 235s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 235s Need to get 0 B/716 B of archives. 235s After this operation, 0 B of additional disk space will be used. 235s Get:1 /tmp/autopkgtest.BM9FOl/2-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [716 B] 235s Selecting previously unselected package autopkgtest-satdep. 235s (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 ... 60320 files and directories currently installed.) 235s Preparing to unpack .../2-autopkgtest-satdep.deb ... 235s Unpacking autopkgtest-satdep (0) ... 235s Setting up autopkgtest-satdep (0) ... 246s (Reading database ... 60320 files and directories currently installed.) 246s Removing autopkgtest-satdep (0) ... 254s autopkgtest [13:48:00]: test suite: [----------------------- 256s Creating new PostgreSQL cluster 16/regress ... 259s 2024-06-03 13:48:05.476 P00 INFO: stanza-create command begin 2.51: --config=/tmp/tmp.9JBALWsGhV --exec-id=3402-05081462 --lock-path=/tmp/tmp.diV8PcSbjM --log-level-console=info --log-path=/tmp/tmp.LCIR7CrGOR --pg1-path=/tmp/pg_virtualenv.VSn7vK/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.ZY5dHd0aQo --stanza=demo 260s 2024-06-03 13:48:06.080 P00 INFO: stanza-create for stanza 'demo' on repo1 260s 2024-06-03 13:48:06.089 P00 INFO: stanza-create command end: completed successfully (616ms) 260s 2024-06-03 13:48:06.100 P00 INFO: check command begin 2.51: --config=/tmp/tmp.9JBALWsGhV --exec-id=3404-7679650d --log-level-console=info --log-path=/tmp/tmp.LCIR7CrGOR --pg1-path=/tmp/pg_virtualenv.VSn7vK/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.ZY5dHd0aQo --stanza=demo 260s 2024-06-03 13:48:06.704 P00 INFO: check repo1 configuration (primary) 260s 2024-06-03 13:48:06.905 P00 INFO: check repo1 archive for WAL (primary) 261s 2024-06-03 13:48:07.207 P00 INFO: WAL segment 000000010000000000000001 successfully archived to '/tmp/tmp.ZY5dHd0aQo/archive/demo/16-1/0000000100000000/000000010000000000000001-83285782015dd007f9669d9d21d468635fdf2ed3.gz' on repo1 261s 2024-06-03 13:48:07.207 P00 INFO: check command end: completed successfully (1109ms) 261s 2024-06-03 13:48:07.218 P00 INFO: backup command begin 2.51: --config=/tmp/tmp.9JBALWsGhV --exec-id=3408-3680364a --lock-path=/tmp/tmp.diV8PcSbjM --log-level-console=info --log-path=/tmp/tmp.LCIR7CrGOR --pg1-path=/tmp/pg_virtualenv.VSn7vK/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.ZY5dHd0aQo --repo1-retention-full=2 --stanza=demo 261s WARN: no prior backup exists, incr backup has been changed to full 261s 2024-06-03 13:48:07.922 P00 INFO: execute non-exclusive backup start: backup begins after the next regular checkpoint completes 262s 2024-06-03 13:48:08.624 P00 INFO: backup start archive = 000000010000000000000002, lsn = 0/2000028 262s 2024-06-03 13:48:08.624 P00 INFO: check archive for segment 000000010000000000000002 265s 2024-06-03 13:48:11.293 P00 INFO: execute non-exclusive backup stop and wait for all WAL segments to archive 265s 2024-06-03 13:48:11.494 P00 INFO: backup stop archive = 000000010000000000000003, lsn = 0/3000050 265s 2024-06-03 13:48:11.497 P00 INFO: check archive for segment(s) 000000010000000000000002:000000010000000000000003 265s 2024-06-03 13:48:11.506 P00 INFO: new backup label = 20240603-134807F 265s 2024-06-03 13:48:11.540 P00 INFO: full backup size = 22MB, file total = 963 265s 2024-06-03 13:48:11.540 P00 INFO: backup command end: completed successfully (4325ms) 265s 2024-06-03 13:48:11.541 P00 INFO: expire command begin 2.51: --config=/tmp/tmp.9JBALWsGhV --exec-id=3408-3680364a --lock-path=/tmp/tmp.diV8PcSbjM --log-level-console=info --log-path=/tmp/tmp.LCIR7CrGOR --repo1-path=/tmp/tmp.ZY5dHd0aQo --repo1-retention-full=2 --stanza=demo 265s 2024-06-03 13:48:11.545 P00 INFO: expire command end: completed successfully (5ms) 265s stanza: demo 265s status: ok 265s cipher: none 265s 265s db (current) 265s wal archive min/max (16): 000000010000000000000001/000000010000000000000003 265s 265s full backup: 20240603-134807F 265s timestamp start/stop: 2024-06-03 13:48:07+00 / 2024-06-03 13:48:11+00 265s wal start/stop: 000000010000000000000002 / 000000010000000000000003 265s database size: 22MB, database backup size: 22MB 265s repo1: backup set size: 2.9MB, backup size: 2.9MB 265s Cleaning files... 265s Dropping cluster 16/regress ... 266s autopkgtest [13:48:12]: test suite: -----------------------] 270s autopkgtest [13:48:16]: test suite: - - - - - - - - - - results - - - - - - - - - - 270s suite PASS 273s autopkgtest [13:48:19]: @@@@@@@@@@@@@@@@@@@@ summary 273s version PASS 273s suite PASS