0s autopkgtest [14:55:38]: starting date and time: 2024-11-09 14:55:38+0000 0s autopkgtest [14:55:38]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [14:55:38]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.axlu5g9n/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/265 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-23.secgroup --name adt-plucky-s390x-postgresql-filedump-20241109-145538-juju-7f2275-prod-proposed-migration-environment-15-d582ba38-85ca-49c0-834b-6f9e18558dd6 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --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/ 116s autopkgtest [14:57:34]: testbed dpkg architecture: s390x 116s autopkgtest [14:57:34]: testbed apt version: 2.9.8 116s autopkgtest [14:57:34]: @@@@@@@@@@@@@@@@@@@@ test bed setup 117s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 117s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 117s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1004 kB] 118s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 118s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [104 kB] 118s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [125 kB] 118s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [696 kB] 119s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [16.6 kB] 119s Fetched 2043 kB in 2s (897 kB/s) 119s Reading package lists... 121s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s Calculating upgrade... 121s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 121s Reading package lists... 121s Building dependency tree... 121s Reading state information... 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 122s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 122s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 122s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 122s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 123s Reading package lists... 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s Calculating upgrade... 123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s autopkgtest [14:57:44]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 126s autopkgtest [14:57:44]: @@@@@@@@@@@@@@@@@@@@ apt-source postgresql-filedump 127s Get:1 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (dsc) [2180 B] 127s Get:2 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (tar) [40.5 kB] 127s Get:3 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (diff) [3672 B] 127s gpgv: Signature made Thu Sep 14 14:08:31 2023 UTC 127s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 127s gpgv: Can't check signature: No public key 127s dpkg-source: warning: cannot verify inline signature for ./postgresql-filedump_16.0-1.dsc: no acceptable signature found 127s autopkgtest [14:57:45]: testing package postgresql-filedump version 16.0-1 128s autopkgtest [14:57:46]: build not needed 128s autopkgtest [14:57:46]: test installcheck: preparing testbed 129s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s Starting pkgProblemResolver with broken count: 0 129s Starting 2 pkgProblemResolver with broken count: 0 129s Done 129s The following additional packages will be installed: 129s libio-pty-perl libipc-run-perl libjson-perl libpq5 libxslt1.1 postgresql 129s postgresql-17 postgresql-client-17 postgresql-client-common 129s postgresql-common postgresql-filedump ssl-cert 129s Suggested packages: 129s postgresql-doc postgresql-doc-17 129s Recommended packages: 129s libjson-xs-perl 129s The following NEW packages will be installed: 129s autopkgtest-satdep libio-pty-perl libipc-run-perl libjson-perl libpq5 129s libxslt1.1 postgresql postgresql-17 postgresql-client-17 129s postgresql-client-common postgresql-common postgresql-filedump ssl-cert 129s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 129s Need to get 22.1 MB/22.1 MB of archives. 129s After this operation, 73.6 MB of additional disk space will be used. 129s Get:1 /tmp/autopkgtest.gSyWK3/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 130s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libjson-perl all 4.10000-1 [81.9 kB] 130s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 130s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 130s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x postgresql-client-common all 265 [37.0 kB] 130s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x ssl-cert all 1.1.2ubuntu2 [18.0 kB] 130s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x postgresql-common all 265 [162 kB] 130s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libpq5 s390x 17.0-1 [252 kB] 130s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libxslt1.1 s390x 1.1.39-0exp1ubuntu1 [169 kB] 130s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-client-17 s390x 17.0-1 [2129 kB] 132s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-17 s390x 17.0-1 [19.1 MB] 149s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x postgresql all 17+265 [12.1 kB] 149s Get:13 http://ftpmaster.internal/ubuntu plucky/universe s390x postgresql-filedump s390x 16.0-1 [45.5 kB] 149s Preconfiguring packages ... 149s Fetched 22.1 MB in 19s (1150 kB/s) 149s Selecting previously unselected package libjson-perl. 149s (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 ... 55520 files and directories currently installed.) 149s Preparing to unpack .../00-libjson-perl_4.10000-1_all.deb ... 149s Unpacking libjson-perl (4.10000-1) ... 149s Selecting previously unselected package libio-pty-perl. 149s Preparing to unpack .../01-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 149s Unpacking libio-pty-perl (1:1.20-1build3) ... 149s Selecting previously unselected package libipc-run-perl. 149s Preparing to unpack .../02-libipc-run-perl_20231003.0-2_all.deb ... 149s Unpacking libipc-run-perl (20231003.0-2) ... 149s Selecting previously unselected package postgresql-client-common. 149s Preparing to unpack .../03-postgresql-client-common_265_all.deb ... 149s Unpacking postgresql-client-common (265) ... 149s Selecting previously unselected package ssl-cert. 149s Preparing to unpack .../04-ssl-cert_1.1.2ubuntu2_all.deb ... 149s Unpacking ssl-cert (1.1.2ubuntu2) ... 149s Selecting previously unselected package postgresql-common. 149s Preparing to unpack .../05-postgresql-common_265_all.deb ... 149s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 149s Unpacking postgresql-common (265) ... 149s Selecting previously unselected package libpq5:s390x. 149s Preparing to unpack .../06-libpq5_17.0-1_s390x.deb ... 149s Unpacking libpq5:s390x (17.0-1) ... 149s Selecting previously unselected package libxslt1.1:s390x. 149s Preparing to unpack .../07-libxslt1.1_1.1.39-0exp1ubuntu1_s390x.deb ... 149s Unpacking libxslt1.1:s390x (1.1.39-0exp1ubuntu1) ... 149s Selecting previously unselected package postgresql-client-17. 149s Preparing to unpack .../08-postgresql-client-17_17.0-1_s390x.deb ... 149s Unpacking postgresql-client-17 (17.0-1) ... 149s Selecting previously unselected package postgresql-17. 149s Preparing to unpack .../09-postgresql-17_17.0-1_s390x.deb ... 149s Unpacking postgresql-17 (17.0-1) ... 149s Selecting previously unselected package postgresql. 149s Preparing to unpack .../10-postgresql_17+265_all.deb ... 149s Unpacking postgresql (17+265) ... 149s Selecting previously unselected package postgresql-filedump. 149s Preparing to unpack .../11-postgresql-filedump_16.0-1_s390x.deb ... 149s Unpacking postgresql-filedump (16.0-1) ... 149s Selecting previously unselected package autopkgtest-satdep. 149s Preparing to unpack .../12-1-autopkgtest-satdep.deb ... 149s Unpacking autopkgtest-satdep (0) ... 149s Setting up libio-pty-perl (1:1.20-1build3) ... 149s Setting up libpq5:s390x (17.0-1) ... 149s Setting up ssl-cert (1.1.2ubuntu2) ... 150s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 150s Setting up postgresql-filedump (16.0-1) ... 150s Setting up libipc-run-perl (20231003.0-2) ... 150s Setting up libjson-perl (4.10000-1) ... 150s Setting up libxslt1.1:s390x (1.1.39-0exp1ubuntu1) ... 150s Setting up postgresql-client-common (265) ... 150s Setting up postgresql-client-17 (17.0-1) ... 150s 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 150s Setting up postgresql-common (265) ... 151s 151s Creating config file /etc/postgresql-common/createcluster.conf with new version 151s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 151s Removing obsolete dictionary files: 151s Created symlink '/etc/systemd/system/multi-user.target.wants/postgresql.service' → '/usr/lib/systemd/system/postgresql.service'. 151s Setting up postgresql-17 (17.0-1) ... 152s Creating new PostgreSQL cluster 17/main ... 152s /usr/lib/postgresql/17/bin/initdb -D /var/lib/postgresql/17/main --auth-local peer --auth-host scram-sha-256 --no-instructions 152s The files belonging to this database system will be owned by user "postgres". 152s This user must also own the server process. 152s 152s The database cluster will be initialized with locale "C.UTF-8". 152s The default database encoding has accordingly been set to "UTF8". 152s The default text search configuration will be set to "english". 152s 152s Data page checksums are disabled. 152s 152s fixing permissions on existing directory /var/lib/postgresql/17/main ... ok 152s creating subdirectories ... ok 152s selecting dynamic shared memory implementation ... posix 152s selecting default "max_connections" ... 100 152s selecting default "shared_buffers" ... 128MB 152s selecting default time zone ... Etc/UTC 152s creating configuration files ... ok 152s running bootstrap script ... ok 152s performing post-bootstrap initialization ... ok 152s syncing data to disk ... ok 155s Setting up postgresql (17+265) ... 155s Setting up autopkgtest-satdep (0) ... 155s Processing triggers for man-db (2.12.1-3) ... 156s Processing triggers for libc-bin (2.40-1ubuntu3) ... 158s (Reading database ... 57889 files and directories currently installed.) 158s Removing autopkgtest-satdep (0) ... 158s autopkgtest [14:58:16]: test installcheck: [----------------------- 158s Creating new PostgreSQL cluster 17/regress ... 161s 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 161s # +++ regress install-check in +++ 161s # using postmaster on localhost, port 5433 161s not ok 1 - datatypes 71 ms 161s ok 2 - float 20 ms 161s ok 3 - numeric 17 ms 161s ok 4 - xml 17 ms 161s 1..4 161s # 1 of 4 tests failed. 161s # The differences that caused some tests to fail can be viewed in the file "/tmp/autopkgtest.gSyWK3/build.xUc/src/regression.diffs". 161s # A copy of the test summary that you see above is saved in the file "/tmp/autopkgtest.gSyWK3/build.xUc/src/regression.out". 161s *** /tmp/pg_virtualenv.KNpxwH/log/postgresql-17-regress.log (last 100 lines) *** 161s make: *** [/usr/lib/postgresql/17/lib/pgxs/src/makefiles/pgxs.mk:436: installcheck] Error 1 161s 2024-11-09 14:58:17.276 UTC [4237] 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 161s 2024-11-09 14:58:17.276 UTC [4237] LOG: listening on IPv6 address "::1", port 5433 161s 2024-11-09 14:58:17.276 UTC [4237] LOG: listening on IPv4 address "127.0.0.1", port 5433 161s 2024-11-09 14:58:17.276 UTC [4237] LOG: listening on Unix socket "/tmp/.s.PGSQL.5433" 161s 2024-11-09 14:58:17.277 UTC [4240] LOG: database system was shut down at 2024-11-09 14:58:17 UTC 161s 2024-11-09 14:58:17.279 UTC [4237] LOG: database system is ready to accept connections 161s 2024-11-09 14:58:19.500 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.505 UTC [4238] LOG: checkpoint complete: wrote 934 buffers (5.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.005 s, sync=0.001 s, total=0.005 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 161s 2024-11-09 14:58:19.509 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.509 UTC [4238] 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 161s 2024-11-09 14:58:19.511 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.511 UTC [4238] 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 161s 2024-11-09 14:58:19.513 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.514 UTC [4238] 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 161s 2024-11-09 14:58:19.516 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.516 UTC [4238] 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 161s 2024-11-09 14:58:19.518 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.518 UTC [4238] 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 161s 2024-11-09 14:58:19.520 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.520 UTC [4238] 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 161s 2024-11-09 14:58:19.523 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.523 UTC [4238] 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 161s 2024-11-09 14:58:19.525 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.525 UTC [4238] 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 161s 2024-11-09 14:58:19.527 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.527 UTC [4238] 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 161s 2024-11-09 14:58:19.529 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.530 UTC [4238] 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 161s 2024-11-09 14:58:19.532 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.532 UTC [4238] 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 161s 2024-11-09 14:58:19.534 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.534 UTC [4238] 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 161s 2024-11-09 14:58:19.537 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.537 UTC [4238] 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 161s 2024-11-09 14:58:19.539 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.539 UTC [4238] 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 161s 2024-11-09 14:58:19.541 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.542 UTC [4238] 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 161s 2024-11-09 14:58:19.544 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.544 UTC [4238] 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 161s 2024-11-09 14:58:19.546 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.546 UTC [4238] 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 161s 2024-11-09 14:58:19.548 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.549 UTC [4238] 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 161s 2024-11-09 14:58:19.551 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.551 UTC [4238] 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 161s 2024-11-09 14:58:19.553 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.553 UTC [4238] 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 161s 2024-11-09 14:58:19.572 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.573 UTC [4238] 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 161s 2024-11-09 14:58:19.575 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.576 UTC [4238] 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 161s 2024-11-09 14:58:19.593 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.594 UTC [4238] 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 161s 2024-11-09 14:58:19.612 UTC [4238] LOG: checkpoint starting: immediate force wait 161s 2024-11-09 14:58:19.612 UTC [4238] 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 161s Dropping cluster 17/regress ... 161s diff -U3 /tmp/autopkgtest.gSyWK3/build.xUc/src/expected/datatypes.out /tmp/autopkgtest.gSyWK3/build.xUc/src/results/datatypes.out 161s --- /tmp/autopkgtest.gSyWK3/build.xUc/src/expected/datatypes.out 2023-09-14 13:37:45.000000000 +0000 161s +++ /tmp/autopkgtest.gSyWK3/build.xUc/src/results/datatypes.out 2024-11-09 14:58:19.545549026 +0000 161s @@ -746,7 +746,7 @@ 161s Item 1 -- Length: 32 Offset: 8160 (0x1fe0) Flags: NORMAL 161s COPY: 2000-01-01 08:00:00.000000+00 161s Item 2 -- Length: 32 Offset: 8128 (0x1fc0) Flags: NORMAL 161s -COPY: 0100-01-01 10:22:00.000000+00 BC 161s +COPY: 0100-01-01 10:14:58.000000+00 BC 161s Item 3 -- Length: 32 Offset: 8096 (0x1fa0) Flags: NORMAL 161s COPY: infinity 161s Item 4 -- Length: 32 Offset: 8064 (0x1f80) Flags: NORMAL 162s autopkgtest [14:58:20]: test installcheck: -----------------------] 162s autopkgtest [14:58:20]: test installcheck: - - - - - - - - - - results - - - - - - - - - - 162s installcheck FAIL non-zero exit status 1 162s autopkgtest [14:58:20]: @@@@@@@@@@@@@@@@@@@@ summary 162s installcheck FAIL non-zero exit status 1 173s nova [W] Using flock in prodstack6-s390x 173s Creating nova instance adt-plucky-s390x-postgresql-filedump-20241109-145538-juju-7f2275-prod-proposed-migration-environment-15-d582ba38-85ca-49c0-834b-6f9e18558dd6 from image adt/ubuntu-plucky-s390x-server-20241109.img (UUID 3dcdd533-c66f-4d57-9732-52a74843bc00)...