0s autopkgtest [03:01:19]: starting date and time: 2024-11-15 03:01:19+0000 0s autopkgtest [03:01:19]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [03:01:19]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.lri9f_ko/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:postgresql-common --apt-upgrade postgresql-filedump --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=postgresql-common/266 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-s390x-12.secgroup --name adt-plucky-s390x-postgresql-filedump-20241115-030119-juju-7f2275-prod-proposed-migration-environment-20-93cdaf80-79a5-4892-87db-cdcf29cb7a17 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-s390x -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/ 113s autopkgtest [03:03:12]: testbed dpkg architecture: s390x 113s autopkgtest [03:03:12]: testbed apt version: 2.9.8 113s autopkgtest [03:03:12]: @@@@@@@@@@@@@@@@@@@@ test bed setup 114s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 114s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [101 kB] 114s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 114s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 114s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1030 kB] 114s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [145 kB] 114s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [734 kB] 114s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4648 B] 114s Fetched 2111 kB in 1s (2637 kB/s) 114s Reading package lists... 116s Reading package lists... 116s Building dependency tree... 116s Reading state information... 117s Calculating upgrade... 117s The following packages will be upgraded: 117s libcap-ng0 libexpat1 lxd-installer man-db pastebinit python3-systemd 117s xfsprogs 117s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s Need to get 2461 kB of archives. 117s After this operation, 166 kB of additional disk space will be used. 117s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libcap-ng0 s390x 0.8.5-3build1 [15.9 kB] 117s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libexpat1 s390x 2.6.4-1 [93.3 kB] 117s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x man-db s390x 2.13.0-1 [1337 kB] 117s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x lxd-installer all 9 [5084 B] 117s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x pastebinit all 1.7.1-1 [14.9 kB] 117s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x python3-systemd s390x 235-1build5 [46.9 kB] 117s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x xfsprogs s390x 6.8.0-2.2ubuntu2 [948 kB] 118s Preconfiguring packages ... 118s Fetched 2461 kB in 1s (3411 kB/s) 118s (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 ... 55511 files and directories currently installed.) 118s Preparing to unpack .../libcap-ng0_0.8.5-3build1_s390x.deb ... 118s Unpacking libcap-ng0:s390x (0.8.5-3build1) over (0.8.5-1) ... 118s Setting up libcap-ng0:s390x (0.8.5-3build1) ... 118s (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 ... 55511 files and directories currently installed.) 118s Preparing to unpack .../0-libexpat1_2.6.4-1_s390x.deb ... 118s Unpacking libexpat1:s390x (2.6.4-1) over (2.6.2-2) ... 118s Preparing to unpack .../1-man-db_2.13.0-1_s390x.deb ... 118s Unpacking man-db (2.13.0-1) over (2.12.1-3) ... 118s Preparing to unpack .../2-lxd-installer_9_all.deb ... 118s Unpacking lxd-installer (9) over (8) ... 118s Preparing to unpack .../3-pastebinit_1.7.1-1_all.deb ... 118s Unpacking pastebinit (1.7.1-1) over (1.7.0-1) ... 118s Preparing to unpack .../4-python3-systemd_235-1build5_s390x.deb ... 118s Unpacking python3-systemd (235-1build5) over (235-1build4) ... 118s Preparing to unpack .../5-xfsprogs_6.8.0-2.2ubuntu2_s390x.deb ... 118s Unpacking xfsprogs (6.8.0-2.2ubuntu2) over (6.8.0-2.2ubuntu1) ... 118s Setting up libexpat1:s390x (2.6.4-1) ... 118s Setting up pastebinit (1.7.1-1) ... 118s Setting up man-db (2.13.0-1) ... 118s Updating database of manual pages ... 119s man-db.service is a disabled or a static unit not running, not starting it. 119s Setting up xfsprogs (6.8.0-2.2ubuntu2) ... 119s update-initramfs: deferring update (trigger activated) 120s Setting up lxd-installer (9) ... 120s Setting up python3-systemd (235-1build5) ... 120s Processing triggers for libc-bin (2.40-1ubuntu3) ... 120s Processing triggers for initramfs-tools (0.142ubuntu34) ... 120s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 120s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 122s Using config file '/etc/zipl.conf' 122s Building bootmap in '/boot' 122s Adding IPL section 'ubuntu' (default) 122s Preparing boot device for LD-IPL: vda (0000). 122s Done. 122s Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 123s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 123s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 123s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 124s Reading package lists... 124s Reading package lists... 124s Building dependency tree... 124s Reading state information... 124s Calculating upgrade... 124s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s Reading package lists... 124s Building dependency tree... 124s Reading state information... 124s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s autopkgtest [03:03:23]: rebooting testbed after setup commands that affected boot 128s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 143s autopkgtest [03:03:42]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 146s autopkgtest [03:03:45]: @@@@@@@@@@@@@@@@@@@@ apt-source postgresql-filedump 147s Get:1 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (dsc) [2180 B] 147s Get:2 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (tar) [40.5 kB] 147s Get:3 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (diff) [3672 B] 147s gpgv: Signature made Thu Sep 14 14:08:31 2023 UTC 147s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 147s gpgv: Can't check signature: No public key 147s dpkg-source: warning: cannot verify inline signature for ./postgresql-filedump_16.0-1.dsc: no acceptable signature found 147s autopkgtest [03:03:46]: testing package postgresql-filedump version 16.0-1 147s autopkgtest [03:03:46]: build not needed 148s autopkgtest [03:03:47]: test installcheck: preparing testbed 149s Reading package lists... 149s Building dependency tree... 149s Reading state information... 149s Starting pkgProblemResolver with broken count: 0 149s Starting 2 pkgProblemResolver with broken count: 0 149s Done 149s The following additional packages will be installed: 149s libio-pty-perl libipc-run-perl libjson-perl libpq5 libxslt1.1 postgresql 149s postgresql-17 postgresql-client-17 postgresql-client-common 149s postgresql-common postgresql-filedump ssl-cert 149s Suggested packages: 149s postgresql-doc postgresql-doc-17 149s Recommended packages: 149s libjson-xs-perl 149s The following NEW packages will be installed: 149s autopkgtest-satdep libio-pty-perl libipc-run-perl libjson-perl libpq5 149s libxslt1.1 postgresql postgresql-17 postgresql-client-17 149s postgresql-client-common postgresql-common postgresql-filedump ssl-cert 149s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 149s Need to get 22.1 MB/22.1 MB of archives. 149s After this operation, 73.6 MB of additional disk space will be used. 149s Get:1 /tmp/autopkgtest.x58d2v/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [720 B] 149s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libjson-perl all 4.10000-1 [81.9 kB] 150s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 150s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 150s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x postgresql-client-common all 266 [37.0 kB] 150s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x ssl-cert all 1.1.2ubuntu2 [18.0 kB] 150s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x postgresql-common all 266 [162 kB] 150s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libpq5 s390x 17.0-1 [252 kB] 150s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libxslt1.1 s390x 1.1.39-0exp1ubuntu1 [169 kB] 150s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-client-17 s390x 17.0-1 [2129 kB] 150s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-17 s390x 17.0-1 [19.1 MB] 151s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x postgresql all 17+266 [12.1 kB] 151s Get:13 http://ftpmaster.internal/ubuntu plucky/universe s390x postgresql-filedump s390x 16.0-1 [45.5 kB] 151s Preconfiguring packages ... 151s Fetched 22.1 MB in 1s (17.1 MB/s) 151s Selecting previously unselected package libjson-perl. 151s (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 ... 55517 files and directories currently installed.) 151s Preparing to unpack .../00-libjson-perl_4.10000-1_all.deb ... 151s Unpacking libjson-perl (4.10000-1) ... 151s Selecting previously unselected package libio-pty-perl. 151s Preparing to unpack .../01-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 151s Unpacking libio-pty-perl (1:1.20-1build3) ... 151s Selecting previously unselected package libipc-run-perl. 151s Preparing to unpack .../02-libipc-run-perl_20231003.0-2_all.deb ... 151s Unpacking libipc-run-perl (20231003.0-2) ... 151s Selecting previously unselected package postgresql-client-common. 151s Preparing to unpack .../03-postgresql-client-common_266_all.deb ... 151s Unpacking postgresql-client-common (266) ... 151s Selecting previously unselected package ssl-cert. 151s Preparing to unpack .../04-ssl-cert_1.1.2ubuntu2_all.deb ... 151s Unpacking ssl-cert (1.1.2ubuntu2) ... 151s Selecting previously unselected package postgresql-common. 151s Preparing to unpack .../05-postgresql-common_266_all.deb ... 151s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 151s Unpacking postgresql-common (266) ... 151s Selecting previously unselected package libpq5:s390x. 151s Preparing to unpack .../06-libpq5_17.0-1_s390x.deb ... 151s Unpacking libpq5:s390x (17.0-1) ... 151s Selecting previously unselected package libxslt1.1:s390x. 151s Preparing to unpack .../07-libxslt1.1_1.1.39-0exp1ubuntu1_s390x.deb ... 151s Unpacking libxslt1.1:s390x (1.1.39-0exp1ubuntu1) ... 151s Selecting previously unselected package postgresql-client-17. 151s Preparing to unpack .../08-postgresql-client-17_17.0-1_s390x.deb ... 151s Unpacking postgresql-client-17 (17.0-1) ... 151s Selecting previously unselected package postgresql-17. 151s Preparing to unpack .../09-postgresql-17_17.0-1_s390x.deb ... 151s Unpacking postgresql-17 (17.0-1) ... 151s Selecting previously unselected package postgresql. 151s Preparing to unpack .../10-postgresql_17+266_all.deb ... 151s Unpacking postgresql (17+266) ... 151s Selecting previously unselected package postgresql-filedump. 151s Preparing to unpack .../11-postgresql-filedump_16.0-1_s390x.deb ... 151s Unpacking postgresql-filedump (16.0-1) ... 151s Selecting previously unselected package autopkgtest-satdep. 151s Preparing to unpack .../12-1-autopkgtest-satdep.deb ... 151s Unpacking autopkgtest-satdep (0) ... 151s Setting up libio-pty-perl (1:1.20-1build3) ... 151s Setting up libpq5:s390x (17.0-1) ... 151s Setting up ssl-cert (1.1.2ubuntu2) ... 152s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 152s Setting up postgresql-filedump (16.0-1) ... 152s Setting up libipc-run-perl (20231003.0-2) ... 152s Setting up libjson-perl (4.10000-1) ... 152s Setting up libxslt1.1:s390x (1.1.39-0exp1ubuntu1) ... 152s Setting up postgresql-client-common (266) ... 152s Setting up postgresql-client-17 (17.0-1) ... 152s 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 152s Setting up postgresql-common (266) ... 152s 152s Creating config file /etc/postgresql-common/createcluster.conf with new version 153s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 153s Removing obsolete dictionary files: 153s Created symlink '/etc/systemd/system/multi-user.target.wants/postgresql.service' → '/usr/lib/systemd/system/postgresql.service'. 153s Setting up postgresql-17 (17.0-1) ... 153s Creating new PostgreSQL cluster 17/main ... 153s /usr/lib/postgresql/17/bin/initdb -D /var/lib/postgresql/17/main --auth-local peer --auth-host scram-sha-256 --no-instructions 153s The files belonging to this database system will be owned by user "postgres". 153s This user must also own the server process. 153s 153s The database cluster will be initialized with locale "C.UTF-8". 153s The default database encoding has accordingly been set to "UTF8". 153s The default text search configuration will be set to "english". 153s 153s Data page checksums are disabled. 153s 153s fixing permissions on existing directory /var/lib/postgresql/17/main ... ok 153s creating subdirectories ... ok 154s selecting dynamic shared memory implementation ... posix 154s selecting default "max_connections" ... 100 154s selecting default "shared_buffers" ... 128MB 154s selecting default time zone ... Etc/UTC 154s creating configuration files ... ok 154s running bootstrap script ... ok 154s performing post-bootstrap initialization ... ok 154s syncing data to disk ... ok 157s Setting up postgresql (17+266) ... 157s Setting up autopkgtest-satdep (0) ... 157s Processing triggers for man-db (2.13.0-1) ... 158s Processing triggers for libc-bin (2.40-1ubuntu3) ... 159s (Reading database ... 57886 files and directories currently installed.) 159s Removing autopkgtest-satdep (0) ... 160s autopkgtest [03:03:59]: test installcheck: [----------------------- 160s Creating new PostgreSQL cluster 17/regress ... 163s echo "# +++ regress install-check in +++" && /usr/lib/postgresql/17/lib/pgxs/src/makefiles/../../src/test/regress/pg_regress --inputdir=./ --bindir='/usr/lib/postgresql/17/bin' --dbname=contrib_regression datatypes float numeric xml 163s # +++ regress install-check in +++ 163s # using postmaster on localhost, port 5433 163s not ok 1 - datatypes 64 ms 163s # 1 of 4 tests failed. 163s # The differences that caused some tests to fail can be viewed in the file "/tmp/autopkgtest.x58d2v/build.eaB/src/regression.diffs". 163s # A copy of the test summary that you see above is saved in the file "/tmp/autopkgtest.x58d2v/build.eaB/src/regression.out". 163s make: *** [/usr/lib/postgresql/17/lib/pgxs/src/makefiles/pgxs.mk:436: installcheck] Error 1 163s ok 2 - float 19 ms 163s ok 3 - numeric 16 ms 163s ok 4 - xml 17 ms 163s 1..4 163s *** /tmp/pg_virtualenv.JfAg7R/log/postgresql-17-regress.log (last 100 lines) *** 163s 2024-11-15 03:05:26.373 UTC [3524] LOG: starting PostgreSQL 17.0 (Ubuntu 17.0-1) on s390x-ibm-linux-gnu, compiled by gcc (Ubuntu 14.2.0-7ubuntu1) 14.2.0, 64-bit 163s 2024-11-15 03:05:26.373 UTC [3524] LOG: listening on IPv6 address "::1", port 5433 163s 2024-11-15 03:05:26.373 UTC [3524] LOG: listening on IPv4 address "127.0.0.1", port 5433 163s 2024-11-15 03:05:26.373 UTC [3524] LOG: listening on Unix socket "/tmp/.s.PGSQL.5433" 163s 2024-11-15 03:05:26.374 UTC [3527] LOG: database system was shut down at 2024-11-15 03:05:26 UTC 163s 2024-11-15 03:05:26.376 UTC [3524] LOG: database system is ready to accept connections 163s 2024-11-15 03:05:28.603 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.606 UTC [3525] LOG: checkpoint complete: wrote 934 buffers (5.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.004 s, sync=0.001 s, total=0.004 s; sync files=0, longest=0.000 s, average=0.000 s; distance=4275 kB, estimate=4275 kB; lsn=0/19176F0, redo lsn=0/1917698 163s 2024-11-15 03:05:28.610 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.610 UTC [3525] LOG: checkpoint complete: wrote 28 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=85 kB, estimate=3856 kB; lsn=0/192CBA8, redo lsn=0/192CB50 163s 2024-11-15 03:05:28.612 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.612 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=87 kB, estimate=3479 kB; lsn=0/1942B60, redo lsn=0/1942B08 163s 2024-11-15 03:05:28.614 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.614 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=90 kB, estimate=3140 kB; lsn=0/1959628, redo lsn=0/19595D0 163s 2024-11-15 03:05:28.616 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.616 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=93 kB, estimate=2835 kB; lsn=0/1970C68, redo lsn=0/1970C10 163s 2024-11-15 03:05:28.618 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.618 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=96 kB, estimate=2561 kB; lsn=0/1988EF8, redo lsn=0/1988EA0 163s 2024-11-15 03:05:28.620 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.621 UTC [3525] LOG: checkpoint complete: wrote 33 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=105 kB, estimate=2316 kB; lsn=0/19A3410, redo lsn=0/19A33B8 163s 2024-11-15 03:05:28.623 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.623 UTC [3525] LOG: checkpoint complete: wrote 30 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=117 kB, estimate=2096 kB; lsn=0/19C0930, redo lsn=0/19C08D8 163s 2024-11-15 03:05:28.625 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.625 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=97 kB, estimate=1896 kB; lsn=0/19D9030, redo lsn=0/19D8FD8 163s 2024-11-15 03:05:28.627 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.627 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=100 kB, estimate=1716 kB; lsn=0/19F2330, redo lsn=0/19F22D8 163s 2024-11-15 03:05:28.629 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.629 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=103 kB, estimate=1555 kB; lsn=0/1A0C148, redo lsn=0/1A0C0F0 163s 2024-11-15 03:05:28.631 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.631 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=107 kB, estimate=1410 kB; lsn=0/1A26E28, redo lsn=0/1A26DD0 163s 2024-11-15 03:05:28.633 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.633 UTC [3525] LOG: checkpoint complete: wrote 33 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=130 kB, estimate=1282 kB; lsn=0/1A47788, redo lsn=0/1A47730 163s 2024-11-15 03:05:28.635 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.635 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=107 kB, estimate=1165 kB; lsn=0/1A62778, redo lsn=0/1A62720 163s 2024-11-15 03:05:28.637 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.637 UTC [3525] LOG: checkpoint complete: wrote 29 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=102 kB, estimate=1058 kB; lsn=0/1A7C308, redo lsn=0/1A7C2B0 163s 2024-11-15 03:05:28.639 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.639 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=105 kB, estimate=963 kB; lsn=0/1A96A40, redo lsn=0/1A969E8 163s 2024-11-15 03:05:28.641 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.642 UTC [3525] LOG: checkpoint complete: wrote 30 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=108 kB, estimate=878 kB; lsn=0/1AB1CF0, redo lsn=0/1AB1C98 163s 2024-11-15 03:05:28.643 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.644 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=103 kB, estimate=800 kB; lsn=0/1ACBAA0, redo lsn=0/1ACBA48 163s 2024-11-15 03:05:28.646 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.646 UTC [3525] LOG: checkpoint complete: wrote 31 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=126 kB, estimate=733 kB; lsn=0/1AEB640, redo lsn=0/1AEB5E8 163s 2024-11-15 03:05:28.648 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.648 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=104 kB, estimate=670 kB; lsn=0/1B056E0, redo lsn=0/1B05688 163s 2024-11-15 03:05:28.650 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.650 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=107 kB, estimate=614 kB; lsn=0/1B20310, redo lsn=0/1B202B8 163s 2024-11-15 03:05:28.668 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.669 UTC [3525] LOG: checkpoint complete: wrote 29 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=107 kB, estimate=563 kB; lsn=0/1B3AF10, redo lsn=0/1B3AEB8 163s 2024-11-15 03:05:28.671 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.672 UTC [3525] LOG: checkpoint complete: wrote 26 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=101 kB, estimate=517 kB; lsn=0/1B546E8, redo lsn=0/1B54690 163s 2024-11-15 03:05:28.689 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.689 UTC [3525] LOG: checkpoint complete: wrote 34 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=133 kB, estimate=478 kB; lsn=0/1B75B80, redo lsn=0/1B75B28 163s 2024-11-15 03:05:28.706 UTC [3525] LOG: checkpoint starting: immediate force wait 163s 2024-11-15 03:05:28.707 UTC [3525] LOG: checkpoint complete: wrote 36 buffers (0.2%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.001 s, sync=0.001 s, total=0.001 s; sync files=0, longest=0.000 s, average=0.000 s; distance=126 kB, estimate=443 kB; lsn=0/1B95770, redo lsn=0/1B95718 163s Dropping cluster 17/regress ... 163s diff -U3 /tmp/autopkgtest.x58d2v/build.eaB/src/expected/datatypes.out /tmp/autopkgtest.x58d2v/build.eaB/src/results/datatypes.out 163s --- /tmp/autopkgtest.x58d2v/build.eaB/src/expected/datatypes.out 2023-09-14 13:37:45.000000000 +0000 163s +++ /tmp/autopkgtest.x58d2v/build.eaB/src/results/datatypes.out 2024-11-15 03:05:28.637511052 +0000 163s @@ -746,7 +746,7 @@ 163s Item 1 -- Length: 32 Offset: 8160 (0x1fe0) Flags: NORMAL 163s COPY: 2000-01-01 08:00:00.000000+00 163s Item 2 -- Length: 32 Offset: 8128 (0x1fc0) Flags: NORMAL 163s -COPY: 0100-01-01 10:22:00.000000+00 BC 163s +COPY: 0100-01-01 10:14:58.000000+00 BC 163s Item 3 -- Length: 32 Offset: 8096 (0x1fa0) Flags: NORMAL 163s COPY: infinity 163s Item 4 -- Length: 32 Offset: 8064 (0x1f80) Flags: NORMAL 164s autopkgtest [03:04:03]: test installcheck: -----------------------] 164s autopkgtest [03:04:03]: test installcheck: - - - - - - - - - - results - - - - - - - - - - 164s installcheck FAIL non-zero exit status 1 164s autopkgtest [03:04:03]: @@@@@@@@@@@@@@@@@@@@ summary 164s installcheck FAIL non-zero exit status 1 176s nova [W] Using flock in prodstack6-s390x 176s Creating nova instance adt-plucky-s390x-postgresql-filedump-20241115-030119-juju-7f2275-prod-proposed-migration-environment-20-93cdaf80-79a5-4892-87db-cdcf29cb7a17 from image adt/ubuntu-plucky-s390x-server-20241114.img (UUID 41a907ef-1f3c-4685-a0eb-228b0d61c6b5)...