0s autopkgtest [10:27:48]: starting date and time: 2024-06-04 10:27:48+0000 0s autopkgtest [10:27:48]: git checkout: 930f72e5 setup-testbed: don't install 'Recommends' for 'linux-generic' 0s autopkgtest [10:27:48]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.fpa0n8hl/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@bos03-arm64-24.secgroup --name adt-oracular-arm64-pgbackrest-20240604-102748-juju-7f2275-prod-proposed-migration-environment-3-2197d3de-be2c-4ad2-81a6-fe58b4a39422 --image adt/ubuntu-oracular-arm64-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/ 748s autopkgtest [10:40:16]: testbed dpkg architecture: arm64 748s autopkgtest [10:40:16]: testbed apt version: 2.9.3 748s autopkgtest [10:40:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 750s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 755s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [4792 B] 756s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 756s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [43.3 kB] 756s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [414 kB] 756s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 Packages [85.0 kB] 756s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/restricted arm64 Packages [12.3 kB] 757s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/universe arm64 Packages [371 kB] 757s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse arm64 Packages [1392 B] 758s Fetched 1043 kB in 8s (132 kB/s) 759s Reading package lists... 778s Reading package lists... 779s Building dependency tree... 779s Reading state information... 780s Calculating upgrade... 780s The following packages will be upgraded: 780s libxml2 784s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 784s Need to get 627 kB of archives. 784s After this operation, 385 kB disk space will be freed. 784s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 libxml2 arm64 2.12.7+dfsg-3 [627 kB] 798s Fetched 627 kB in 4s (160 kB/s) 798s (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 ... 78194 files and directories currently installed.) 798s Preparing to unpack .../libxml2_2.12.7+dfsg-3_arm64.deb ... 798s Unpacking libxml2:arm64 (2.12.7+dfsg-3) over (2.9.14+dfsg-1.3ubuntu3) ... 798s Setting up libxml2:arm64 (2.12.7+dfsg-3) ... 798s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 798s Reading package lists... 798s Building dependency tree... 798s Reading state information... 798s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 799s Hit:1 http://ftpmaster.internal/ubuntu oracular InRelease 799s Hit:2 http://ftpmaster.internal/ubuntu oracular-updates InRelease 799s Hit:3 http://ftpmaster.internal/ubuntu oracular-security InRelease 799s Hit:4 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 806s Reading package lists... 806s Reading package lists... 806s Building dependency tree... 806s Reading state information... 807s Calculating upgrade... 807s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 808s Reading package lists... 808s Building dependency tree... 808s Reading state information... 809s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 819s autopkgtest [10:41:27]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 20 02:32:42 UTC 2024 820s autopkgtest [10:41:28]: @@@@@@@@@@@@@@@@@@@@ apt-source pgbackrest 825s Get:1 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (dsc) [2311 B] 825s Get:2 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (tar) [6120 kB] 825s Get:3 http://ftpmaster.internal/ubuntu oracular/universe pgbackrest 2.51-1 (diff) [21.2 kB] 825s gpgv: Signature made Tue Mar 26 14:14:21 2024 UTC 825s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 825s gpgv: Can't check signature: No public key 825s dpkg-source: warning: cannot verify inline signature for ./pgbackrest_2.51-1.dsc: no acceptable signature found 826s autopkgtest [10:41:34]: testing package pgbackrest version 2.51-1 827s autopkgtest [10:41:35]: build not needed 835s autopkgtest [10:41:43]: test version: preparing testbed 839s Reading package lists... 839s Building dependency tree... 839s Reading state information... 839s Starting pkgProblemResolver with broken count: 0 840s Starting 2 pkgProblemResolver with broken count: 0 840s Done 840s The following additional packages will be installed: 840s libjson-perl libpq5 libssh2-1t64 libxslt1.1 pgbackrest pgbackrest-doc 840s postgresql postgresql-16 postgresql-client-16 postgresql-client-common 840s postgresql-common ssl-cert 840s Suggested packages: 840s check-pgbackrest postgresql-doc postgresql-doc-16 840s Recommended packages: 840s libjson-xs-perl 840s The following NEW packages will be installed: 840s autopkgtest-satdep libjson-perl libpq5 libssh2-1t64 libxslt1.1 pgbackrest 840s pgbackrest-doc postgresql postgresql-16 postgresql-client-16 840s postgresql-client-common postgresql-common ssl-cert 841s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 841s Need to get 18.0 MB/18.0 MB of archives. 841s After this operation, 59.0 MB of additional disk space will be used. 841s Get:1 /tmp/autopkgtest.TZP6wa/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [716 B] 842s Get:2 http://ftpmaster.internal/ubuntu oracular/main arm64 libjson-perl all 4.10000-1 [81.9 kB] 842s Get:3 http://ftpmaster.internal/ubuntu oracular/main arm64 postgresql-client-common all 260 [36.4 kB] 842s Get:4 http://ftpmaster.internal/ubuntu oracular/main arm64 ssl-cert all 1.1.2ubuntu1 [17.8 kB] 842s Get:5 http://ftpmaster.internal/ubuntu oracular/main arm64 postgresql-common all 260 [162 kB] 842s Get:6 http://ftpmaster.internal/ubuntu oracular/main arm64 libpq5 arm64 16.3-1 [138 kB] 842s Get:7 http://ftpmaster.internal/ubuntu oracular/main arm64 libssh2-1t64 arm64 1.11.0-5 [119 kB] 842s Get:8 http://ftpmaster.internal/ubuntu oracular/main arm64 libxslt1.1 arm64 1.1.39-0exp1build1 [166 kB] 842s Get:9 http://ftpmaster.internal/ubuntu oracular/universe arm64 pgbackrest arm64 2.51-1 [551 kB] 842s Get:10 http://ftpmaster.internal/ubuntu oracular/universe arm64 pgbackrest-doc all 2.51-1 [125 kB] 842s Get:11 http://ftpmaster.internal/ubuntu oracular/main arm64 postgresql-client-16 arm64 16.3-1 [1261 kB] 842s Get:12 http://ftpmaster.internal/ubuntu oracular/main arm64 postgresql-16 arm64 16.3-1 [15.3 MB] 843s Get:13 http://ftpmaster.internal/ubuntu oracular/main arm64 postgresql all 16+260 [11.6 kB] 844s Preconfiguring packages ... 845s Fetched 18.0 MB in 3s (7108 kB/s) 845s Selecting previously unselected package libjson-perl. 845s (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 ... 78194 files and directories currently installed.) 845s Preparing to unpack .../00-libjson-perl_4.10000-1_all.deb ... 845s Unpacking libjson-perl (4.10000-1) ... 845s Selecting previously unselected package postgresql-client-common. 845s Preparing to unpack .../01-postgresql-client-common_260_all.deb ... 845s Unpacking postgresql-client-common (260) ... 845s Selecting previously unselected package ssl-cert. 845s Preparing to unpack .../02-ssl-cert_1.1.2ubuntu1_all.deb ... 845s Unpacking ssl-cert (1.1.2ubuntu1) ... 845s Selecting previously unselected package postgresql-common. 845s Preparing to unpack .../03-postgresql-common_260_all.deb ... 845s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 846s Unpacking postgresql-common (260) ... 846s Selecting previously unselected package libpq5:arm64. 846s Preparing to unpack .../04-libpq5_16.3-1_arm64.deb ... 846s Unpacking libpq5:arm64 (16.3-1) ... 846s Selecting previously unselected package libssh2-1t64:arm64. 847s Preparing to unpack .../05-libssh2-1t64_1.11.0-5_arm64.deb ... 847s Unpacking libssh2-1t64:arm64 (1.11.0-5) ... 848s Selecting previously unselected package libxslt1.1:arm64. 849s Preparing to unpack .../06-libxslt1.1_1.1.39-0exp1build1_arm64.deb ... 849s Unpacking libxslt1.1:arm64 (1.1.39-0exp1build1) ... 849s Selecting previously unselected package pgbackrest. 850s Preparing to unpack .../07-pgbackrest_2.51-1_arm64.deb ... 850s Unpacking pgbackrest (2.51-1) ... 850s Selecting previously unselected package pgbackrest-doc. 850s Preparing to unpack .../08-pgbackrest-doc_2.51-1_all.deb ... 850s Unpacking pgbackrest-doc (2.51-1) ... 850s Selecting previously unselected package postgresql-client-16. 850s Preparing to unpack .../09-postgresql-client-16_16.3-1_arm64.deb ... 850s Unpacking postgresql-client-16 (16.3-1) ... 850s Selecting previously unselected package postgresql-16. 851s Preparing to unpack .../10-postgresql-16_16.3-1_arm64.deb ... 851s Unpacking postgresql-16 (16.3-1) ... 851s Selecting previously unselected package postgresql. 851s Preparing to unpack .../11-postgresql_16+260_all.deb ... 851s Unpacking postgresql (16+260) ... 851s Selecting previously unselected package autopkgtest-satdep. 851s Preparing to unpack .../12-1-autopkgtest-satdep.deb ... 851s Unpacking autopkgtest-satdep (0) ... 851s Setting up postgresql-client-common (260) ... 851s Setting up libpq5:arm64 (16.3-1) ... 851s Setting up ssl-cert (1.1.2ubuntu1) ... 853s Created symlink /etc/systemd/system/multi-user.target.wants/ssl-cert.service → /usr/lib/systemd/system/ssl-cert.service. 854s Setting up pgbackrest-doc (2.51-1) ... 854s Setting up libjson-perl (4.10000-1) ... 854s Setting up libxslt1.1:arm64 (1.1.39-0exp1build1) ... 854s Setting up libssh2-1t64:arm64 (1.11.0-5) ... 854s Setting up postgresql-client-16 (16.3-1) ... 856s 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 856s Setting up postgresql-common (260) ... 857s 857s Creating config file /etc/postgresql-common/createcluster.conf with new version 857s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 857s Removing obsolete dictionary files: 859s Created symlink /etc/systemd/system/multi-user.target.wants/postgresql.service → /usr/lib/systemd/system/postgresql.service. 862s Setting up postgresql-16 (16.3-1) ... 863s Creating new PostgreSQL cluster 16/main ... 863s /usr/lib/postgresql/16/bin/initdb -D /var/lib/postgresql/16/main --auth-local peer --auth-host scram-sha-256 --no-instructions 863s The files belonging to this database system will be owned by user "postgres". 863s This user must also own the server process. 863s 863s The database cluster will be initialized with locale "C.UTF-8". 863s The default database encoding has accordingly been set to "UTF8". 863s The default text search configuration will be set to "english". 863s 863s Data page checksums are disabled. 863s 863s fixing permissions on existing directory /var/lib/postgresql/16/main ... ok 863s creating subdirectories ... ok 863s selecting dynamic shared memory implementation ... posix 863s selecting default max_connections ... 100 863s selecting default shared_buffers ... 128MB 864s selecting default time zone ... Etc/UTC 864s creating configuration files ... ok 864s running bootstrap script ... ok 865s performing post-bootstrap initialization ... ok 865s syncing data to disk ... ok 875s Setting up pgbackrest (2.51-1) ... 876s Setting up postgresql (16+260) ... 876s Setting up autopkgtest-satdep (0) ... 876s Processing triggers for man-db (2.12.1-1) ... 878s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 885s (Reading database ... 80171 files and directories currently installed.) 885s Removing autopkgtest-satdep (0) ... 887s autopkgtest [10:42:35]: test version: [----------------------- 887s pgBackRest 2.51 887s autopkgtest [10:42:35]: test version: -----------------------] 888s autopkgtest [10:42:36]: test version: - - - - - - - - - - results - - - - - - - - - - 888s version PASS 888s autopkgtest [10:42:36]: test suite: preparing testbed 890s Reading package lists... 890s Building dependency tree... 890s Reading state information... 890s Starting pkgProblemResolver with broken count: 0 891s Starting 2 pkgProblemResolver with broken count: 0 891s Done 891s The following NEW packages will be installed: 891s autopkgtest-satdep 891s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 891s Need to get 0 B/720 B of archives. 891s After this operation, 0 B of additional disk space will be used. 891s Get:1 /tmp/autopkgtest.TZP6wa/2-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [720 B] 893s Selecting previously unselected package autopkgtest-satdep. 893s (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 ... 80171 files and directories currently installed.) 893s Preparing to unpack .../2-autopkgtest-satdep.deb ... 893s Unpacking autopkgtest-satdep (0) ... 893s Setting up autopkgtest-satdep (0) ... 898s (Reading database ... 80171 files and directories currently installed.) 898s Removing autopkgtest-satdep (0) ... 899s autopkgtest [10:42:47]: test suite: [----------------------- 900s Creating new PostgreSQL cluster 16/regress ... 903s 2024-06-04 10:42:51.941 P00 INFO: stanza-create command begin 2.51: --config=/tmp/tmp.KK2lAv8Zsf --exec-id=4732-058f51db --lock-path=/tmp/tmp.u8x7t6oDNe --log-level-console=info --log-path=/tmp/tmp.l3lHE9dXeD --pg1-path=/tmp/pg_virtualenv.G9KW0n/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.b5yKe6fakl --stanza=demo 904s 2024-06-04 10:42:52.560 P00 INFO: stanza-create for stanza 'demo' on repo1 904s 2024-06-04 10:42:52.579 P00 INFO: stanza-create command end: completed successfully (642ms) 904s 2024-06-04 10:42:52.603 P00 INFO: check command begin 2.51: --config=/tmp/tmp.KK2lAv8Zsf --exec-id=4734-03d9d9ac --log-level-console=info --log-path=/tmp/tmp.l3lHE9dXeD --pg1-path=/tmp/pg_virtualenv.G9KW0n/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.b5yKe6fakl --stanza=demo 905s 2024-06-04 10:42:53.220 P00 INFO: check repo1 configuration (primary) 905s 2024-06-04 10:42:53.422 P00 INFO: check repo1 archive for WAL (primary) 905s 2024-06-04 10:42:53.730 P00 INFO: WAL segment 000000010000000000000001 successfully archived to '/tmp/tmp.b5yKe6fakl/archive/demo/16-1/0000000100000000/000000010000000000000001-5957507923311f2e89ab1d81eb939253c345010f.gz' on repo1 905s 2024-06-04 10:42:53.730 P00 INFO: check command end: completed successfully (1130ms) 905s 2024-06-04 10:42:53.804 P00 INFO: backup command begin 2.51: --config=/tmp/tmp.KK2lAv8Zsf --exec-id=4738-86780a09 --lock-path=/tmp/tmp.u8x7t6oDNe --log-level-console=info --log-path=/tmp/tmp.l3lHE9dXeD --pg1-path=/tmp/pg_virtualenv.G9KW0n/data/16/regress --pg1-port=5433 --repo1-path=/tmp/tmp.b5yKe6fakl --repo1-retention-full=2 --stanza=demo 906s 2024-06-04 10:42:54.429 P00 INFO: execute non-exclusive backup start: backup begins after the next regular checkpoint completes 906s WARN: no prior backup exists, incr backup has been changed to full 907s 2024-06-04 10:42:55.131 P00 INFO: backup start archive = 000000010000000000000002, lsn = 0/2000028 907s 2024-06-04 10:42:55.131 P00 INFO: check archive for segment 000000010000000000000002 911s 2024-06-04 10:42:59.884 P00 INFO: execute non-exclusive backup stop and wait for all WAL segments to archive 912s 2024-06-04 10:43:00.085 P00 INFO: backup stop archive = 000000010000000000000003, lsn = 0/3000050 912s 2024-06-04 10:43:00.092 P00 INFO: check archive for segment(s) 000000010000000000000002:000000010000000000000003 912s 2024-06-04 10:43:00.117 P00 INFO: new backup label = 20240604-104254F 912s 2024-06-04 10:43:00.160 P00 INFO: full backup size = 22MB, file total = 963 912s 2024-06-04 10:43:00.160 P00 INFO: backup command end: completed successfully (6358ms) 912s 2024-06-04 10:43:00.162 P00 INFO: expire command begin 2.51: --config=/tmp/tmp.KK2lAv8Zsf --exec-id=4738-86780a09 --lock-path=/tmp/tmp.u8x7t6oDNe --log-level-console=info --log-path=/tmp/tmp.l3lHE9dXeD --repo1-path=/tmp/tmp.b5yKe6fakl --repo1-retention-full=2 --stanza=demo 912s 2024-06-04 10:43:00.170 P00 INFO: expire command end: completed successfully (8ms) 912s stanza: demo 912s status: ok 912s cipher: none 912s 912s db (current) 912s wal archive min/max (16): 000000010000000000000001/000000010000000000000003 912s 912s full backup: 20240604-104254F 912s timestamp start/stop: 2024-06-04 10:42:54+00 / 2024-06-04 10:42:59+00 912s wal start/stop: 000000010000000000000002 / 000000010000000000000003 912s database size: 22MB, database backup size: 22MB 912s repo1: backup set size: 2.9MB, backup size: 2.9MB 912s Cleaning files... 912s Dropping cluster 16/regress ... 914s autopkgtest [10:43:02]: test suite: -----------------------] 915s suite PASS 915s autopkgtest [10:43:03]: test suite: - - - - - - - - - - results - - - - - - - - - - 915s autopkgtest [10:43:03]: @@@@@@@@@@@@@@@@@@@@ summary 915s version PASS 915s suite PASS 986s Creating nova instance adt-oracular-arm64-pgbackrest-20240604-102748-juju-7f2275-prod-proposed-migration-environment-3-2197d3de-be2c-4ad2-81a6-fe58b4a39422 from image adt/ubuntu-oracular-arm64-server-20240604.img (UUID 817125ed-cc9e-45ae-b3a8-f2fa61f3db59)...