0s autopkgtest [06:06:50]: starting date and time: 2025-05-02 06:06:50+0000 0s autopkgtest [06:06:50]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [06:06:50]: host juju-7f2275-prod-proposed-migration-environment-23; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.v9ezvgqk/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:postgresql-common --apt-upgrade pgbackrest --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=postgresql-common/277 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-23@bos03-s390x-4.secgroup --name adt-questing-s390x-pgbackrest-20250502-060650-juju-7f2275-prod-proposed-migration-environment-23-f4630713-2d1c-4a2c-9b6f-ad53c6f59ee7 --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-23 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 98s autopkgtest [06:08:28]: testbed dpkg architecture: s390x 98s autopkgtest [06:08:28]: testbed apt version: 3.0.0 99s autopkgtest [06:08:29]: @@@@@@@@@@@@@@@@@@@@ test bed setup 99s autopkgtest [06:08:29]: testbed release detected to be: None 99s autopkgtest [06:08:29]: updating testbed package index (apt update) 100s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 100s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 100s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 100s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 100s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [184 kB] 100s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [57.4 kB] 100s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2202 kB] 101s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [216 kB] 101s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [1083 kB] 101s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [15.9 kB] 102s Fetched 3868 kB in 2s (2047 kB/s) 102s Reading package lists... 103s autopkgtest [06:08:33]: upgrading testbed (apt dist-upgrade and autopurge) 103s Reading package lists... 103s Building dependency tree... 103s Reading state information... 103s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 103s Starting 2 pkgProblemResolver with broken count: 0 103s Done 103s Entering ResolveByKeep 104s 104s Calculating upgrade... 104s The following packages will be upgraded: 104s dpkg dpkg-dev libdpkg-perl 104s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 104s Need to get 2667 kB of archives. 104s After this operation, 1024 B of additional disk space will be used. 104s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x dpkg s390x 1.22.18ubuntu3 [1297 kB] 105s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x dpkg-dev all 1.22.18ubuntu3 [1089 kB] 105s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x libdpkg-perl all 1.22.18ubuntu3 [281 kB] 105s Fetched 2667 kB in 1s (2310 kB/s) 105s (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 ... 81472 files and directories currently installed.) 105s Preparing to unpack .../dpkg_1.22.18ubuntu3_s390x.deb ... 105s Unpacking dpkg (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 105s Setting up dpkg (1.22.18ubuntu3) ... 106s (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 ... 81472 files and directories currently installed.) 106s Preparing to unpack .../dpkg-dev_1.22.18ubuntu3_all.deb ... 106s Unpacking dpkg-dev (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 106s Preparing to unpack .../libdpkg-perl_1.22.18ubuntu3_all.deb ... 106s Unpacking libdpkg-perl (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 106s Setting up libdpkg-perl (1.22.18ubuntu3) ... 106s Setting up dpkg-dev (1.22.18ubuntu3) ... 106s Processing triggers for man-db (2.13.0-1) ... 108s Reading package lists... 108s Building dependency tree... 108s Reading state information... 108s Starting pkgProblemResolver with broken count: 0 108s Starting 2 pkgProblemResolver with broken count: 0 108s Done 108s Solving dependencies... 108s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 108s autopkgtest [06:08:38]: rebooting testbed after setup commands that affected boot 112s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 130s autopkgtest [06:09:00]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 132s autopkgtest [06:09:02]: @@@@@@@@@@@@@@@@@@@@ apt-source pgbackrest 135s Get:1 http://ftpmaster.internal/ubuntu questing/universe pgbackrest 2.54.2-1 (dsc) [2361 B] 135s Get:2 http://ftpmaster.internal/ubuntu questing/universe pgbackrest 2.54.2-1 (tar) [2732 kB] 135s Get:3 http://ftpmaster.internal/ubuntu questing/universe pgbackrest 2.54.2-1 (diff) [21.7 kB] 136s gpgv: Signature made Tue Jan 21 10:48:00 2025 UTC 136s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 136s gpgv: Can't check signature: No public key 136s dpkg-source: warning: cannot verify inline signature for ./pgbackrest_2.54.2-1.dsc: no acceptable signature found 136s autopkgtest [06:09:06]: testing package pgbackrest version 2.54.2-1 136s autopkgtest [06:09:06]: build not needed 137s autopkgtest [06:09:07]: test version: preparing testbed 137s Reading package lists... 137s Building dependency tree... 137s Reading state information... 138s Starting pkgProblemResolver with broken count: 0 138s Starting 2 pkgProblemResolver with broken count: 0 138s Done 138s The following NEW packages will be installed: 138s libio-pty-perl libipc-run-perl libjson-perl libllvm20 libpq5 libxslt1.1 138s pgbackrest pgbackrest-doc postgresql postgresql-17 postgresql-client-17 138s postgresql-client-common postgresql-common postgresql-common-dev ssl-cert 138s 0 upgraded, 15 newly installed, 0 to remove and 0 not upgraded. 138s Need to get 50.6 MB of archives. 138s After this operation, 216 MB of additional disk space will be used. 138s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x libjson-perl all 4.10000-1 [81.9 kB] 138s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x postgresql-client-common all 277 [48.0 kB] 138s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 138s Get:4 http://ftpmaster.internal/ubuntu questing/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 138s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main s390x postgresql-common-dev all 277 [73.4 kB] 138s Get:6 http://ftpmaster.internal/ubuntu questing/main s390x ssl-cert all 1.1.3ubuntu1 [18.7 kB] 138s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main s390x postgresql-common all 277 [101 kB] 138s Get:8 http://ftpmaster.internal/ubuntu questing/main s390x libllvm20 s390x 1:20.1.2-0ubuntu1 [31.4 MB] 142s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x libpq5 s390x 17.4-1 [147 kB] 142s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x libxslt1.1 s390x 1.1.39-0exp1ubuntu4 [170 kB] 142s Get:11 http://ftpmaster.internal/ubuntu questing/universe s390x pgbackrest s390x 2.54.2-1 [639 kB] 142s Get:12 http://ftpmaster.internal/ubuntu questing/universe s390x pgbackrest-doc all 2.54.2-1 [129 kB] 142s Get:13 http://ftpmaster.internal/ubuntu questing/main s390x postgresql-client-17 s390x 17.4-1 [1367 kB] 142s Get:14 http://ftpmaster.internal/ubuntu questing/main s390x postgresql-17 s390x 17.4-1 [16.3 MB] 142s Get:15 http://ftpmaster.internal/ubuntu questing-proposed/main s390x postgresql all 17+277 [16.5 kB] 143s Preconfiguring packages ... 143s /var/cache/debconf/tmp.ci/postgresql.config.NXvKtf: 12: pg_lsclusters: not found 143s Fetched 50.6 MB in 5s (11.0 MB/s) 143s Selecting previously unselected package libjson-perl. 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 ... 81472 files and directories currently installed.) 143s Preparing to unpack .../00-libjson-perl_4.10000-1_all.deb ... 143s Unpacking libjson-perl (4.10000-1) ... 143s Selecting previously unselected package postgresql-client-common. 143s Preparing to unpack .../01-postgresql-client-common_277_all.deb ... 143s Unpacking postgresql-client-common (277) ... 143s Selecting previously unselected package libio-pty-perl. 143s Preparing to unpack .../02-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 143s Unpacking libio-pty-perl (1:1.20-1build3) ... 143s Selecting previously unselected package libipc-run-perl. 143s Preparing to unpack .../03-libipc-run-perl_20231003.0-2_all.deb ... 143s Unpacking libipc-run-perl (20231003.0-2) ... 143s Selecting previously unselected package postgresql-common-dev. 143s Preparing to unpack .../04-postgresql-common-dev_277_all.deb ... 143s Unpacking postgresql-common-dev (277) ... 143s Selecting previously unselected package ssl-cert. 143s Preparing to unpack .../05-ssl-cert_1.1.3ubuntu1_all.deb ... 143s Unpacking ssl-cert (1.1.3ubuntu1) ... 143s Selecting previously unselected package postgresql-common. 143s Preparing to unpack .../06-postgresql-common_277_all.deb ... 143s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 143s Unpacking postgresql-common (277) ... 143s Selecting previously unselected package libllvm20:s390x. 143s Preparing to unpack .../07-libllvm20_1%3a20.1.2-0ubuntu1_s390x.deb ... 143s Unpacking libllvm20:s390x (1:20.1.2-0ubuntu1) ... 144s Selecting previously unselected package libpq5:s390x. 144s Preparing to unpack .../08-libpq5_17.4-1_s390x.deb ... 144s Unpacking libpq5:s390x (17.4-1) ... 144s Selecting previously unselected package libxslt1.1:s390x. 144s Preparing to unpack .../09-libxslt1.1_1.1.39-0exp1ubuntu4_s390x.deb ... 144s Unpacking libxslt1.1:s390x (1.1.39-0exp1ubuntu4) ... 144s Selecting previously unselected package pgbackrest. 144s Preparing to unpack .../10-pgbackrest_2.54.2-1_s390x.deb ... 144s Unpacking pgbackrest (2.54.2-1) ... 144s Selecting previously unselected package pgbackrest-doc. 144s Preparing to unpack .../11-pgbackrest-doc_2.54.2-1_all.deb ... 144s Unpacking pgbackrest-doc (2.54.2-1) ... 144s Selecting previously unselected package postgresql-client-17. 144s Preparing to unpack .../12-postgresql-client-17_17.4-1_s390x.deb ... 144s Unpacking postgresql-client-17 (17.4-1) ... 144s Selecting previously unselected package postgresql-17. 144s Preparing to unpack .../13-postgresql-17_17.4-1_s390x.deb ... 144s Unpacking postgresql-17 (17.4-1) ... 144s Selecting previously unselected package postgresql. 144s Preparing to unpack .../14-postgresql_17+277_all.deb ... 144s Unpacking postgresql (17+277) ... 144s Setting up postgresql-client-common (277) ... 144s Setting up libio-pty-perl (1:1.20-1build3) ... 144s Setting up libpq5:s390x (17.4-1) ... 144s Setting up ssl-cert (1.1.3ubuntu1) ... 144s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 145s Setting up libllvm20:s390x (1:20.1.2-0ubuntu1) ... 145s Setting up libipc-run-perl (20231003.0-2) ... 145s Setting up pgbackrest-doc (2.54.2-1) ... 145s Setting up libjson-perl (4.10000-1) ... 145s Setting up libxslt1.1:s390x (1.1.39-0exp1ubuntu4) ... 145s Setting up postgresql-common-dev (277) ... 145s Setting up postgresql-client-17 (17.4-1) ... 145s update-alternatives: using /usr/share/postgresql/17/man/man1/psql.1.gz to provide /usr/share/man/man1/psql.1.gz (psql.1.gz) in auto mode 145s Setting up postgresql-common (277) ... 145s Creating config file /etc/postgresql-common/createcluster.conf with new version 145s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 145s Removing obsolete dictionary files: 146s Created symlink '/etc/systemd/system/multi-user.target.wants/postgresql.service' → '/usr/lib/systemd/system/postgresql.service'. 146s Setting up postgresql-17 (17.4-1) ... 147s Creating new PostgreSQL cluster 17/main ... 147s /usr/lib/postgresql/17/bin/initdb -D /var/lib/postgresql/17/main --auth-local peer --auth-host scram-sha-256 --no-instructions 147s The files belonging to this database system will be owned by user "postgres". 147s This user must also own the server process. 147s 147s The database cluster will be initialized with locale "C.UTF-8". 147s The default database encoding has accordingly been set to "UTF8". 147s The default text search configuration will be set to "english". 147s 147s Data page checksums are disabled. 147s 147s fixing permissions on existing directory /var/lib/postgresql/17/main ... ok 147s creating subdirectories ... ok 147s selecting dynamic shared memory implementation ... posix 147s selecting default "max_connections" ... 100 147s selecting default "shared_buffers" ... 128MB 147s selecting default time zone ... Etc/UTC 147s creating configuration files ... ok 147s running bootstrap script ... ok 147s performing post-bootstrap initialization ... ok 147s syncing data to disk ... ok 150s Setting up pgbackrest (2.54.2-1) ... 150s Setting up postgresql (17+277) ... 150s Processing triggers for man-db (2.13.0-1) ... 151s Processing triggers for libc-bin (2.41-6ubuntu1) ... 152s autopkgtest [06:09:22]: test version: [----------------------- 153s pgBackRest 2.54.2 153s autopkgtest [06:09:23]: test version: -----------------------] 153s autopkgtest [06:09:23]: test version: - - - - - - - - - - results - - - - - - - - - - 153s version PASS 154s autopkgtest [06:09:24]: test suite: preparing testbed 154s Reading package lists... 154s Building dependency tree... 154s Reading state information... 154s Starting pkgProblemResolver with broken count: 0 154s Starting 2 pkgProblemResolver with broken count: 0 154s Done 154s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 155s autopkgtest [06:09:25]: test suite: [----------------------- 155s Creating new PostgreSQL cluster 17/regress ... 158s 2025-05-02 06:09:28.629 P00 INFO: stanza-create command begin 2.54.2: --config=/tmp/tmp.ZvxGag0RLQ --exec-id=3801-0f5f2a61 --lock-path=/tmp/tmp.wi9Csn9VEy --log-level-console=info --log-path=/tmp/tmp.JBRuEeKkpd --pg1-path=/tmp/pg_virtualenv.nP5JyY/data/17/regress --pg1-port=5433 --repo1-path=/tmp/tmp.GhB960Hvtn --stanza=demo 158s 2025-05-02 06:09:28.635 P00 INFO: stanza-create for stanza 'demo' on repo1 158s 2025-05-02 06:09:28.645 P00 INFO: stanza-create command end: completed successfully (18ms) 158s 2025-05-02 06:09:28.649 P00 INFO: check command begin 2.54.2: --config=/tmp/tmp.ZvxGag0RLQ --exec-id=3803-30283b40 --log-level-console=info --log-path=/tmp/tmp.JBRuEeKkpd --pg1-path=/tmp/pg_virtualenv.nP5JyY/data/17/regress --pg1-port=5433 --repo1-path=/tmp/tmp.GhB960Hvtn --stanza=demo 158s 2025-05-02 06:09:28.655 P00 INFO: check repo1 configuration (primary) 158s 2025-05-02 06:09:28.659 P00 INFO: check repo1 archive for WAL (primary) 158s 2025-05-02 06:09:28.960 P00 INFO: WAL segment 000000010000000000000001 successfully archived to '/tmp/tmp.GhB960Hvtn/archive/demo/17-1/0000000100000000/000000010000000000000001-fbbe72c9189cca6f017258f32d9b41bc485e1426.gz' on repo1 158s 2025-05-02 06:09:28.960 P00 INFO: check command end: completed successfully (312ms) 158s 2025-05-02 06:09:28.965 P00 INFO: backup command begin 2.54.2: --config=/tmp/tmp.ZvxGag0RLQ --exec-id=3807-f9fcd617 --lock-path=/tmp/tmp.wi9Csn9VEy --log-level-console=info --log-path=/tmp/tmp.JBRuEeKkpd --pg1-path=/tmp/pg_virtualenv.nP5JyY/data/17/regress --pg1-port=5433 --repo1-path=/tmp/tmp.GhB960Hvtn --repo1-retention-full=2 --stanza=demo 158s 2025-05-02 06:09:28.972 P00 WARN: no prior backup exists, incr backup has been changed to full 158s 2025-05-02 06:09:28.972 P00 INFO: execute non-exclusive backup start: backup begins after the next regular checkpoint completes 159s 2025-05-02 06:09:28.982 P00 INFO: backup start archive = 000000010000000000000002, lsn = 0/2000028 159s 2025-05-02 06:09:28.982 P00 INFO: check archive for segment 000000010000000000000002 161s 2025-05-02 06:09:31.424 P00 INFO: execute non-exclusive backup stop and wait for all WAL segments to archive 161s 2025-05-02 06:09:31.430 P00 INFO: backup stop archive = 000000010000000000000003, lsn = 0/3000050 161s 2025-05-02 06:09:31.444 P00 INFO: check archive for segment(s) 000000010000000000000002:000000010000000000000003 161s 2025-05-02 06:09:31.553 P00 INFO: new backup label = 20250502-060928F 161s 2025-05-02 06:09:31.579 P00 INFO: full backup size = 22MB, file total = 963 161s 2025-05-02 06:09:31.579 P00 INFO: backup command end: completed successfully (2616ms) 161s 2025-05-02 06:09:31.579 P00 INFO: expire command begin 2.54.2: --config=/tmp/tmp.ZvxGag0RLQ --exec-id=3807-f9fcd617 --lock-path=/tmp/tmp.wi9Csn9VEy --log-level-console=info --log-path=/tmp/tmp.JBRuEeKkpd --repo1-path=/tmp/tmp.GhB960Hvtn --repo1-retention-full=2 --stanza=demo 161s 2025-05-02 06:09:31.579 P00 INFO: expire command end: completed successfully (0ms) 161s stanza: demo 161s status: ok 161s cipher: none 161s 161s db (current) 161s wal archive min/max (17): 000000010000000000000001/000000010000000000000003 161s 161s full backup: 20250502-060928F 161s timestamp start/stop: 2025-05-02 06:09:28+00 / 2025-05-02 06:09:31+00 161s wal start/stop: 000000010000000000000002 / 000000010000000000000003 161s database size: 22MB, database backup size: 22MB 161s repo1: backup set size: 2.9MB, backup size: 2.9MB 161s Cleaning files... 161s Dropping cluster 17/regress ... 162s autopkgtest [06:09:32]: test suite: -----------------------] 162s suite PASS 162s autopkgtest [06:09:32]: test suite: - - - - - - - - - - results - - - - - - - - - - 162s autopkgtest [06:09:32]: @@@@@@@@@@@@@@@@@@@@ summary 162s version PASS 162s suite PASS 180s nova [W] Using flock in prodstack6-s390x 180s Creating nova instance adt-questing-s390x-pgbackrest-20250502-060650-juju-7f2275-prod-proposed-migration-environment-23-f4630713-2d1c-4a2c-9b6f-ad53c6f59ee7 from image adt/ubuntu-questing-s390x-server-20250501.img (UUID 06a11845-48a4-4c28-9288-51dd7b757c52)... 180s nova [W] Timed out waiting for 2b0ead7e-c61d-42b8-af20-ae528f7cd045 to get deleted.