0s autopkgtest [13:31:39]: starting date and time: 2024-06-03 13:31:39+0000 0s autopkgtest [13:31:39]: git checkout: 930f72e5 setup-testbed: don't install 'Recommends' for 'linux-generic' 0s autopkgtest [13:31:39]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.u2298qce/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@bos01-s390x-11.secgroup --name adt-oracular-s390x-pgbackrest-20240603-133139-juju-7f2275-prod-proposed-migration-environment-3-063b0529-c99f-488e-9370-f3db0837bd79 --image adt/ubuntu-oracular-s390x-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://us.ports.ubuntu.com/ubuntu-ports/ 82s autopkgtest [13:33:01]: testbed dpkg architecture: s390x 82s autopkgtest [13:33:01]: testbed apt version: 2.9.3 82s autopkgtest [13:33:01]: @@@@@@@@@@@@@@@@@@@@ test bed setup 83s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 84s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 84s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [43.4 kB] 84s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [4792 B] 84s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [384 kB] 84s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x Packages [62.1 kB] 84s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x Packages [305 kB] 84s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x Packages [1392 B] 84s Fetched 913 kB in 1s (814 kB/s) 85s Reading package lists... 88s Reading package lists... 88s Building dependency tree... 89s Reading state information... 89s Calculating upgrade... 89s The following packages will be upgraded: 89s libxml2 90s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s Need to get 663 kB of archives. 90s After this operation, 438 kB disk space will be freed. 90s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libxml2 s390x 2.12.7+dfsg-3 [663 kB] 90s Fetched 663 kB in 1s (1314 kB/s) 90s (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 ... 54601 files and directories currently installed.) 90s Preparing to unpack .../libxml2_2.12.7+dfsg-3_s390x.deb ... 90s Unpacking libxml2:s390x (2.12.7+dfsg-3) over (2.9.14+dfsg-1.3ubuntu3) ... 91s Setting up libxml2:s390x (2.12.7+dfsg-3) ... 91s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 92s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 93s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 93s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 93s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 93s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 94s Reading package lists... 94s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Calculating upgrade... 95s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 95s Reading package lists... 95s Building dependency tree... 95s Reading state information... 96s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 100s autopkgtest [13:33:19]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:14:26 UTC 2024 100s autopkgtest [13:33:19]: @@@@@@@@@@@@@@@@@@@@ apt-source pgbackrest 104s Get:1 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (dsc) [2311 B] 104s Get:2 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (tar) [6120 kB] 104s Get:3 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (diff) [21.2 kB] 104s gpgv: Signature made Tue Mar 26 14:14:21 2024 UTC 104s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 104s gpgv: Can't check signature: No public key 104s dpkg-source: warning: cannot verify inline signature for ./pgbackrest_2.51-1.dsc: no acceptable signature found 105s autopkgtest [13:33:24]: testing package pgbackrest version 2.51-1 106s autopkgtest [13:33:25]: build not needed 109s autopkgtest [13:33:28]: test version: preparing testbed 111s Reading package lists... 111s Building dependency tree... 111s Reading state information... 111s Starting pkgProblemResolver with broken count: 0 111s Starting 2 pkgProblemResolver with broken count: 0 111s Done 112s The following additional packages will be installed: 112s libjson-perl libpq5 libssh2-1t64 libxslt1.1 pgbackrest pgbackrest-doc 112s postgresql postgresql-16 postgresql-client-16 postgresql-client-common 112s postgresql-common ssl-cert 112s Suggested packages: 112s check-pgbackrest postgresql-doc postgresql-doc-16 112s Recommended packages: 112s libjson-xs-perl 112s The following NEW packages will be installed: 112s autopkgtest-satdep libjson-perl libpq5 libssh2-1t64 libxslt1.1 pgbackrest 112s pgbackrest-doc postgresql postgresql-16 postgresql-client-16 112s postgresql-client-common postgresql-common ssl-cert 112s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 112s Need to get 19.5 MB/19.5 MB of archives. 112s After this operation, 56.9 MB of additional disk space will be used. 112s Get:1 /tmp/autopkgtest.SsZ1lp/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [720 B] 112s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x libjson-perl all 4.10000-1 [81.9 kB] 113s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x postgresql-client-common all 260 [36.4 kB] 113s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x ssl-cert all 1.1.2ubuntu1 [17.8 kB] 113s Get:5 http://ftpmaster.internal/ubuntu oracular/main s390x postgresql-common all 260 [162 kB] 113s Get:6 http://ftpmaster.internal/ubuntu oracular/main s390x libpq5 s390x 16.3-1 [144 kB] 113s Get:7 http://ftpmaster.internal/ubuntu oracular/main s390x libssh2-1t64 s390x 1.11.0-5 [126 kB] 113s Get:8 http://ftpmaster.internal/ubuntu oracular/main s390x libxslt1.1 s390x 1.1.39-0exp1build1 [170 kB] 113s Get:9 http://ftpmaster.internal/ubuntu oracular/universe s390x pgbackrest s390x 2.51-1 [618 kB] 113s Get:10 http://ftpmaster.internal/ubuntu oracular/universe s390x pgbackrest-doc all 2.51-1 [125 kB] 113s Get:11 http://ftpmaster.internal/ubuntu oracular/main s390x postgresql-client-16 s390x 16.3-1 [1290 kB] 113s Get:12 http://ftpmaster.internal/ubuntu oracular/main s390x postgresql-16 s390x 16.3-1 [16.7 MB] 115s Get:13 http://ftpmaster.internal/ubuntu oracular/main s390x postgresql all 16+260 [11.6 kB] 115s Preconfiguring packages ... 115s Fetched 19.5 MB in 3s (6705 kB/s) 115s Selecting previously unselected package libjson-perl. 115s (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 ... 54601 files and directories currently installed.) 115s Preparing to unpack .../00-libjson-perl_4.10000-1_all.deb ... 115s Unpacking libjson-perl (4.10000-1) ... 115s Selecting previously unselected package postgresql-client-common. 115s Preparing to unpack .../01-postgresql-client-common_260_all.deb ... 115s Unpacking postgresql-client-common (260) ... 115s Selecting previously unselected package ssl-cert. 115s Preparing to unpack .../02-ssl-cert_1.1.2ubuntu1_all.deb ... 115s Unpacking ssl-cert (1.1.2ubuntu1) ... 115s Selecting previously unselected package postgresql-common. 115s Preparing to unpack .../03-postgresql-common_260_all.deb ... 116s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 116s Unpacking postgresql-common (260) ... 116s Selecting previously unselected package libpq5:s390x. 116s Preparing to unpack .../04-libpq5_16.3-1_s390x.deb ... 116s Unpacking libpq5:s390x (16.3-1) ... 116s Selecting previously unselected package libssh2-1t64:s390x. 116s Preparing to unpack .../05-libssh2-1t64_1.11.0-5_s390x.deb ... 116s Unpacking libssh2-1t64:s390x (1.11.0-5) ... 116s Selecting previously unselected package libxslt1.1:s390x. 116s Preparing to unpack .../06-libxslt1.1_1.1.39-0exp1build1_s390x.deb ... 116s Unpacking libxslt1.1:s390x (1.1.39-0exp1build1) ... 116s Selecting previously unselected package pgbackrest. 116s Preparing to unpack .../07-pgbackrest_2.51-1_s390x.deb ... 116s Unpacking pgbackrest (2.51-1) ... 116s Selecting previously unselected package pgbackrest-doc. 116s Preparing to unpack .../08-pgbackrest-doc_2.51-1_all.deb ... 116s Unpacking pgbackrest-doc (2.51-1) ... 116s Selecting previously unselected package postgresql-client-16. 116s Preparing to unpack .../09-postgresql-client-16_16.3-1_s390x.deb ... 116s Unpacking postgresql-client-16 (16.3-1) ... 116s Selecting previously unselected package postgresql-16. 116s Preparing to unpack .../10-postgresql-16_16.3-1_s390x.deb ... 116s Unpacking postgresql-16 (16.3-1) ... 117s Selecting previously unselected package postgresql. 117s Preparing to unpack .../11-postgresql_16+260_all.deb ... 117s Unpacking postgresql (16+260) ... 117s Selecting previously unselected package autopkgtest-satdep. 117s Preparing to unpack .../12-1-autopkgtest-satdep.deb ... 117s Unpacking autopkgtest-satdep (0) ... 117s Setting up postgresql-client-common (260) ... 117s Setting up libpq5:s390x (16.3-1) ... 117s Setting up ssl-cert (1.1.2ubuntu1) ... 118s Created symlink /etc/systemd/system/multi-user.target.wants/ssl-cert.service → /usr/lib/systemd/system/ssl-cert.service. 118s Setting up pgbackrest-doc (2.51-1) ... 118s Setting up libjson-perl (4.10000-1) ... 118s Setting up libxslt1.1:s390x (1.1.39-0exp1build1) ... 118s Setting up libssh2-1t64:s390x (1.11.0-5) ... 118s Setting up postgresql-client-16 (16.3-1) ... 119s 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 119s Setting up postgresql-common (260) ... 119s 119s Creating config file /etc/postgresql-common/createcluster.conf with new version 120s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 120s Removing obsolete dictionary files: 121s Created symlink /etc/systemd/system/multi-user.target.wants/postgresql.service → /usr/lib/systemd/system/postgresql.service. 122s Setting up postgresql-16 (16.3-1) ... 122s Creating new PostgreSQL cluster 16/main ... 122s /usr/lib/postgresql/16/bin/initdb -D /var/lib/postgresql/16/main --auth-local peer --auth-host scram-sha-256 --no-instructions 122s The files belonging to this database system will be owned by user "postgres". 122s This user must also own the server process. 122s 122s The database cluster will be initialized with locale "C.UTF-8". 122s The default database encoding has accordingly been set to "UTF8". 122s The default text search configuration will be set to "english". 122s 122s Data page checksums are disabled. 122s 122s fixing permissions on existing directory /var/lib/postgresql/16/main ... ok 122s creating subdirectories ... ok 122s selecting dynamic shared memory implementation ... posix 122s selecting default max_connections ... 100 122s selecting default shared_buffers ... 128MB 122s selecting default time zone ... Etc/UTC 122s creating configuration files ... ok 122s running bootstrap script ... ok 123s performing post-bootstrap initialization ... ok 123s syncing data to disk ... ok 128s Setting up pgbackrest (2.51-1) ... 128s Setting up postgresql (16+260) ... 128s Setting up autopkgtest-satdep (0) ... 128s Processing triggers for man-db (2.12.1-1) ... 130s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 134s (Reading database ... 56578 files and directories currently installed.) 134s Removing autopkgtest-satdep (0) ... 135s autopkgtest [13:33:54]: test version: [----------------------- 135s pgBackRest 2.51 136s autopkgtest [13:33:55]: test version: -----------------------] 136s autopkgtest [13:33:55]: test version: - - - - - - - - - - results - - - - - - - - - - 136s version PASS 137s autopkgtest [13:33:56]: test suite: preparing testbed 139s Reading package lists... 139s Building dependency tree... 139s Reading state information... 140s Starting pkgProblemResolver with broken count: 0 140s Starting 2 pkgProblemResolver with broken count: 0 140s Done 141s The following NEW packages will be installed: 141s autopkgtest-satdep 141s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 141s Need to get 0 B/724 B of archives. 141s After this operation, 0 B of additional disk space will be used. 141s Get:1 /tmp/autopkgtest.SsZ1lp/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 141s Selecting previously unselected package autopkgtest-satdep. 141s (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 ... 56578 files and directories currently installed.) 141s Preparing to unpack .../2-autopkgtest-satdep.deb ... 141s Unpacking autopkgtest-satdep (0) ... 141s Setting up autopkgtest-satdep (0) ... 145s (Reading database ... 56578 files and directories currently installed.) 145s Removing autopkgtest-satdep (0) ... 146s autopkgtest [13:34:05]: test suite: [----------------------- 146s Creating new PostgreSQL cluster 16/regress ... 149s 2024-06-03 13:34:08.602 P00 INFO: stanza-create command begin 2.51: --config=/tmp/tmp.8jcWsxt5A4 --exec-id=4547-becd7087 --lock-path=/tmp/tmp.Dlollv8Ggw --log-level-console=info --log-path=/tmp/tmp.Jgr1xopEUb --pg1-path=/tmp/pg_virtualenv.BYAT7S/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.DVHO606hXe --stanza=demo 150s 2024-06-03 13:34:09.208 P00 INFO: stanza-create for stanza 'demo' on repo1 150s 2024-06-03 13:34:09.224 P00 INFO: stanza-create command end: completed successfully (626ms) 150s 2024-06-03 13:34:09.238 P00 INFO: check command begin 2.51: --config=/tmp/tmp.8jcWsxt5A4 --exec-id=4549-19fa8a97 --log-level-console=info --log-path=/tmp/tmp.Jgr1xopEUb --pg1-path=/tmp/pg_virtualenv.BYAT7S/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.DVHO606hXe --stanza=demo 150s 2024-06-03 13:34:09.844 P00 INFO: check repo1 configuration (primary) 150s 2024-06-03 13:34:09.947 P00 INFO: check repo1 archive for WAL (primary) 151s 2024-06-03 13:34:10.258 P00 INFO: WAL segment 000000010000000000000001 successfully archived to '/tmp/tmp.DVHO606hXe/archive/demo/16-1/0000000100000000/000000010000000000000001-c187820e2adeed74f1072949c290fb54a323ad53.gz' on repo1 151s 2024-06-03 13:34:10.263 P00 INFO: check command end: completed successfully (1030ms) 151s 2024-06-03 13:34:10.276 P00 INFO: backup command begin 2.51: --config=/tmp/tmp.8jcWsxt5A4 --exec-id=4553-0bcfb1fd --lock-path=/tmp/tmp.Dlollv8Ggw --log-level-console=info --log-path=/tmp/tmp.Jgr1xopEUb --pg1-path=/tmp/pg_virtualenv.BYAT7S/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.DVHO606hXe --repo1-retention-full=2 --stanza=demo 152s 2024-06-03 13:34:10.990 P00 INFO: execute non-exclusive backup start: backup begins after the next regular checkpoint completes 152s WARN: no prior backup exists, incr backup has been changed to full 152s 2024-06-03 13:34:11.592 P00 INFO: backup start archive = 000000010000000000000002, lsn = 0/2000028 152s 2024-06-03 13:34:11.592 P00 INFO: check archive for segment 000000010000000000000002 155s 2024-06-03 13:34:14.436 P00 INFO: execute non-exclusive backup stop and wait for all WAL segments to archive 155s 2024-06-03 13:34:14.648 P00 INFO: backup stop archive = 000000010000000000000003, lsn = 0/3000050 155s 2024-06-03 13:34:14.658 P00 INFO: check archive for segment(s) 000000010000000000000002:000000010000000000000003 155s 2024-06-03 13:34:14.671 P00 INFO: new backup label = 20240603-133410F 155s 2024-06-03 13:34:14.701 P00 INFO: full backup size = 22MB, file total = 963 155s 2024-06-03 13:34:14.701 P00 INFO: backup command end: completed successfully (4427ms) 155s 2024-06-03 13:34:14.701 P00 INFO: expire command begin 2.51: --config=/tmp/tmp.8jcWsxt5A4 --exec-id=4553-0bcfb1fd --lock-path=/tmp/tmp.Dlollv8Ggw --log-level-console=info --log-path=/tmp/tmp.Jgr1xopEUb --repo1-path=/tmp/tmp.DVHO606hXe --repo1-retention-full=2 --stanza=demo 155s 2024-06-03 13:34:14.703 P00 INFO: expire command end: completed successfully (2ms) 155s stanza: demo 155s status: ok 155s cipher: none 155s 155s db (current) 155s wal archive min/max (16): 000000010000000000000001/000000010000000000000003 155s 155s full backup: 20240603-133410F 155s timestamp start/stop: 2024-06-03 13:34:10+00 / 2024-06-03 13:34:14+00 155s wal start/stop: 000000010000000000000002 / 000000010000000000000003 155s database size: 22MB, database backup size: 22MB 155s repo1: backup set size: 3.0MB, backup size: 3.0MB 155s Cleaning files... 155s Dropping cluster 16/regress ... 156s autopkgtest [13:34:15]: test suite: -----------------------] 157s autopkgtest [13:34:16]: test suite: - - - - - - - - - - results - - - - - - - - - - 157s suite PASS 157s autopkgtest [13:34:16]: @@@@@@@@@@@@@@@@@@@@ summary 157s version PASS 157s suite PASS 172s Creating nova instance adt-oracular-s390x-pgbackrest-20240603-133139-juju-7f2275-prod-proposed-migration-environment-3-063b0529-c99f-488e-9370-f3db0837bd79 from image adt/ubuntu-oracular-s390x-server-20240602.img (UUID 23a7d092-2c85-4f7d-bba6-f3d2afddd108)...