0s autopkgtest [02:49:29]: starting date and time: 2024-11-15 02:49:29+0000 0s autopkgtest [02:49:29]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [02:49:29]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.fma4dyb1/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-arm64-16.secgroup --name adt-plucky-arm64-postgresql-filedump-20241115-024929-juju-7f2275-prod-proposed-migration-environment-20-975f03a4-0e1f-41a6-8401-56f23856c14b --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --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/ 65s autopkgtest [02:50:34]: testbed dpkg architecture: arm64 65s autopkgtest [02:50:34]: testbed apt version: 2.9.8 65s autopkgtest [02:50:34]: @@@@@@@@@@@@@@@@@@@@ test bed setup 66s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 66s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 66s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 66s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1030 kB] 66s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [101 kB] 66s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [164 kB] 66s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [50.3 kB] 66s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [773 kB] 66s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [6116 B] 67s Fetched 2220 kB in 1s (2490 kB/s) 67s Reading package lists... 69s Reading package lists... 69s Building dependency tree... 69s Reading state information... 70s Calculating upgrade... 70s The following packages will be upgraded: 70s firmware-sof-signed libcap-ng0 libexpat1 lxd-installer man-db pastebinit 70s python3-systemd xfsprogs 71s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 71s Need to get 9481 kB of archives. 71s After this operation, 549 kB of additional disk space will be used. 71s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 firmware-sof-signed all 2024.06-1ubuntu2 [7083 kB] 72s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 libcap-ng0 arm64 0.8.5-3build1 [15.0 kB] 72s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 libexpat1 arm64 2.6.4-1 [76.6 kB] 72s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 man-db arm64 2.13.0-1 [1320 kB] 72s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 lxd-installer all 9 [5084 B] 72s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 pastebinit all 1.7.1-1 [14.9 kB] 72s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-systemd arm64 235-1build5 [46.7 kB] 72s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 xfsprogs arm64 6.8.0-2.2ubuntu2 [919 kB] 72s Preconfiguring packages ... 73s Fetched 9481 kB in 2s (5181 kB/s) 73s (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 ... 79925 files and directories currently installed.) 73s Preparing to unpack .../firmware-sof-signed_2024.06-1ubuntu2_all.deb ... 73s Unpacking firmware-sof-signed (2024.06-1ubuntu2) over (2024.06-1ubuntu1) ... 73s Preparing to unpack .../libcap-ng0_0.8.5-3build1_arm64.deb ... 73s Unpacking libcap-ng0:arm64 (0.8.5-3build1) over (0.8.5-1) ... 73s Setting up libcap-ng0:arm64 (0.8.5-3build1) ... 73s (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 ... 79951 files and directories currently installed.) 73s Preparing to unpack .../0-libexpat1_2.6.4-1_arm64.deb ... 73s Unpacking libexpat1:arm64 (2.6.4-1) over (2.6.2-2) ... 73s Preparing to unpack .../1-man-db_2.13.0-1_arm64.deb ... 73s Unpacking man-db (2.13.0-1) over (2.12.1-3) ... 74s Preparing to unpack .../2-lxd-installer_9_all.deb ... 74s Unpacking lxd-installer (9) over (8) ... 74s Preparing to unpack .../3-pastebinit_1.7.1-1_all.deb ... 74s Unpacking pastebinit (1.7.1-1) over (1.7.0-1) ... 74s Preparing to unpack .../4-python3-systemd_235-1build5_arm64.deb ... 74s Unpacking python3-systemd (235-1build5) over (235-1build4) ... 74s Preparing to unpack .../5-xfsprogs_6.8.0-2.2ubuntu2_arm64.deb ... 74s Unpacking xfsprogs (6.8.0-2.2ubuntu2) over (6.8.0-2.2ubuntu1) ... 74s Setting up libexpat1:arm64 (2.6.4-1) ... 74s Setting up firmware-sof-signed (2024.06-1ubuntu2) ... 74s Setting up pastebinit (1.7.1-1) ... 74s Setting up man-db (2.13.0-1) ... 74s Updating database of manual pages ... 76s man-db.service is a disabled or a static unit not running, not starting it. 76s Setting up xfsprogs (6.8.0-2.2ubuntu2) ... 76s update-initramfs: deferring update (trigger activated) 77s Setting up lxd-installer (9) ... 77s Setting up python3-systemd (235-1build5) ... 77s Processing triggers for libc-bin (2.40-1ubuntu3) ... 78s Processing triggers for initramfs-tools (0.142ubuntu34) ... 78s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 78s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 95s System running in EFI mode, skipping. 95s Reading package lists... 96s Building dependency tree... 96s Reading state information... 96s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 97s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 97s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 97s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 97s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 98s Reading package lists... 98s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s Calculating upgrade... 100s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 100s Reading package lists... 100s Building dependency tree... 100s Reading state information... 101s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 101s autopkgtest [02:51:10]: rebooting testbed after setup commands that affected boot 125s autopkgtest [02:51:34]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 128s autopkgtest [02:51:37]: @@@@@@@@@@@@@@@@@@@@ apt-source postgresql-filedump 130s Get:1 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (dsc) [2180 B] 130s Get:2 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (tar) [40.5 kB] 130s Get:3 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 16.0-1 (diff) [3672 B] 130s gpgv: Signature made Thu Sep 14 14:08:31 2023 UTC 130s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 130s gpgv: Can't check signature: No public key 130s dpkg-source: warning: cannot verify inline signature for ./postgresql-filedump_16.0-1.dsc: no acceptable signature found 130s autopkgtest [02:51:39]: testing package postgresql-filedump version 16.0-1 131s autopkgtest [02:51:40]: build not needed 131s autopkgtest [02:51:40]: test installcheck: preparing testbed 137s Reading package lists... 138s Building dependency tree... 138s Reading state information... 138s Starting pkgProblemResolver with broken count: 0 138s Starting 2 pkgProblemResolver with broken count: 0 138s Done 139s The following additional packages will be installed: 139s libio-pty-perl libipc-run-perl libjson-perl libpq5 libxslt1.1 postgresql 139s postgresql-17 postgresql-client-17 postgresql-client-common 139s postgresql-common postgresql-filedump ssl-cert 139s Suggested packages: 139s postgresql-doc postgresql-doc-17 139s Recommended packages: 139s libjson-xs-perl 139s The following NEW packages will be installed: 139s autopkgtest-satdep libio-pty-perl libipc-run-perl libjson-perl libpq5 139s libxslt1.1 postgresql postgresql-17 postgresql-client-17 139s postgresql-client-common postgresql-common postgresql-filedump ssl-cert 139s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 139s Need to get 20.7 MB/20.7 MB of archives. 139s After this operation, 75.9 MB of additional disk space will be used. 139s Get:1 /tmp/autopkgtest.5mN8iZ/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [720 B] 139s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 libjson-perl all 4.10000-1 [81.9 kB] 139s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 libio-pty-perl arm64 1:1.20-1build3 [31.3 kB] 139s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 libipc-run-perl all 20231003.0-2 [91.5 kB] 139s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 postgresql-client-common all 266 [37.0 kB] 139s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 ssl-cert all 1.1.2ubuntu2 [18.0 kB] 139s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 postgresql-common all 266 [162 kB] 139s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 libpq5 arm64 17.0-1 [248 kB] 139s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 libxslt1.1 arm64 1.1.39-0exp1ubuntu1 [167 kB] 140s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 postgresql-client-17 arm64 17.0-1 [2102 kB] 140s Get:11 http://ftpmaster.internal/ubuntu plucky/main arm64 postgresql-17 arm64 17.0-1 [17.7 MB] 141s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 postgresql all 17+266 [12.1 kB] 141s Get:13 http://ftpmaster.internal/ubuntu plucky/universe arm64 postgresql-filedump arm64 16.0-1 [43.5 kB] 142s Preconfiguring packages ... 143s Fetched 20.7 MB in 2s (9101 kB/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 ... 79957 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 libio-pty-perl. 143s Preparing to unpack .../01-libio-pty-perl_1%3a1.20-1build3_arm64.deb ... 143s Unpacking libio-pty-perl (1:1.20-1build3) ... 143s Selecting previously unselected package libipc-run-perl. 143s Preparing to unpack .../02-libipc-run-perl_20231003.0-2_all.deb ... 143s Unpacking libipc-run-perl (20231003.0-2) ... 143s Selecting previously unselected package postgresql-client-common. 143s Preparing to unpack .../03-postgresql-client-common_266_all.deb ... 143s Unpacking postgresql-client-common (266) ... 143s Selecting previously unselected package ssl-cert. 143s Preparing to unpack .../04-ssl-cert_1.1.2ubuntu2_all.deb ... 143s Unpacking ssl-cert (1.1.2ubuntu2) ... 143s Selecting previously unselected package postgresql-common. 143s Preparing to unpack .../05-postgresql-common_266_all.deb ... 143s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 143s Unpacking postgresql-common (266) ... 143s Selecting previously unselected package libpq5:arm64. 143s Preparing to unpack .../06-libpq5_17.0-1_arm64.deb ... 143s Unpacking libpq5:arm64 (17.0-1) ... 143s Selecting previously unselected package libxslt1.1:arm64. 143s Preparing to unpack .../07-libxslt1.1_1.1.39-0exp1ubuntu1_arm64.deb ... 143s Unpacking libxslt1.1:arm64 (1.1.39-0exp1ubuntu1) ... 143s Selecting previously unselected package postgresql-client-17. 143s Preparing to unpack .../08-postgresql-client-17_17.0-1_arm64.deb ... 143s Unpacking postgresql-client-17 (17.0-1) ... 143s Selecting previously unselected package postgresql-17. 143s Preparing to unpack .../09-postgresql-17_17.0-1_arm64.deb ... 143s Unpacking postgresql-17 (17.0-1) ... 143s Selecting previously unselected package postgresql. 143s Preparing to unpack .../10-postgresql_17+266_all.deb ... 143s Unpacking postgresql (17+266) ... 143s Selecting previously unselected package postgresql-filedump. 143s Preparing to unpack .../11-postgresql-filedump_16.0-1_arm64.deb ... 143s Unpacking postgresql-filedump (16.0-1) ... 143s Selecting previously unselected package autopkgtest-satdep. 143s Preparing to unpack .../12-1-autopkgtest-satdep.deb ... 143s Unpacking autopkgtest-satdep (0) ... 143s Setting up libio-pty-perl (1:1.20-1build3) ... 143s Setting up libpq5:arm64 (17.0-1) ... 143s Setting up ssl-cert (1.1.2ubuntu2) ... 144s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 144s Setting up postgresql-filedump (16.0-1) ... 144s Setting up libipc-run-perl (20231003.0-2) ... 144s Setting up libjson-perl (4.10000-1) ... 144s Setting up libxslt1.1:arm64 (1.1.39-0exp1ubuntu1) ... 144s Setting up postgresql-client-common (266) ... 144s Setting up postgresql-client-17 (17.0-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 (266) ... 145s 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.0-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 151s Setting up postgresql (17+266) ... 151s Setting up autopkgtest-satdep (0) ... 151s Processing triggers for man-db (2.13.0-1) ... 152s Processing triggers for libc-bin (2.40-1ubuntu3) ... 156s (Reading database ... 82326 files and directories currently installed.) 156s Removing autopkgtest-satdep (0) ... 157s autopkgtest [02:52:06]: test installcheck: [----------------------- 157s Creating new PostgreSQL cluster 17/regress ... 160s 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 160s # +++ regress install-check in +++ 160s # using postmaster on localhost, port 5433 161s not ok 1 - datatypes 177 ms 161s ok 2 - float 34 ms 161s ok 3 - numeric 30 ms 161s ok 4 - xml 34 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.5mN8iZ/build.DVv/src/regression.diffs". 161s # A copy of the test summary that you see above is saved in the file "/tmp/autopkgtest.5mN8iZ/build.DVv/src/regression.out". 161s make: *** [/usr/lib/postgresql/17/lib/pgxs/src/makefiles/pgxs.mk:436: installcheck] Error 1 161s *** /tmp/pg_virtualenv.IeAcfP/log/postgresql-17-regress.log (last 100 lines) *** 161s 2024-11-15 02:52:07.583 UTC [3498] LOG: starting PostgreSQL 17.0 (Ubuntu 17.0-1) on aarch64-unknown-linux-gnu, compiled by gcc (Ubuntu 14.2.0-7ubuntu1) 14.2.0, 64-bit 161s 2024-11-15 02:52:07.583 UTC [3498] LOG: listening on IPv6 address "::1", port 5433 161s 2024-11-15 02:52:07.583 UTC [3498] LOG: listening on IPv4 address "127.0.0.1", port 5433 161s 2024-11-15 02:52:07.583 UTC [3498] LOG: listening on Unix socket "/tmp/.s.PGSQL.5433" 161s 2024-11-15 02:52:07.586 UTC [3501] LOG: database system was shut down at 2024-11-15 02:52:07 UTC 161s 2024-11-15 02:52:07.590 UTC [3498] LOG: database system is ready to accept connections 161s 2024-11-15 02:52:09.903 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.912 UTC [3499] LOG: checkpoint complete: wrote 934 buffers (5.7%); 0 WAL file(s) added, 0 removed, 0 recycled; write=0.009 s, sync=0.001 s, total=0.009 s; sync files=0, longest=0.000 s, average=0.000 s; distance=4276 kB, estimate=4276 kB; lsn=0/19183A8, redo lsn=0/1918350 161s 2024-11-15 02:52:09.921 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.922 UTC [3499] 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=3857 kB; lsn=0/192D850, redo lsn=0/192D7F8 161s 2024-11-15 02:52:09.928 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.929 UTC [3499] 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=3480 kB; lsn=0/1943810, redo lsn=0/19437B8 161s 2024-11-15 02:52:09.934 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.935 UTC [3499] 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=3141 kB; lsn=0/195A2F8, redo lsn=0/195A2A0 161s 2024-11-15 02:52:09.940 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.941 UTC [3499] 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=2836 kB; lsn=0/1971928, redo lsn=0/19718D0 161s 2024-11-15 02:52:09.946 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.947 UTC [3499] 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=2562 kB; lsn=0/1989BC0, redo lsn=0/1989B68 161s 2024-11-15 02:52:09.953 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.954 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=105 kB, estimate=2316 kB; lsn=0/19A40F8, redo lsn=0/19A40A0 161s 2024-11-15 02:52:09.960 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.961 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=117 kB, estimate=2097 kB; lsn=0/19C1608, redo lsn=0/19C15B0 161s 2024-11-15 02:52:09.967 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.968 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=97 kB, estimate=1897 kB; lsn=0/19D9D10, redo lsn=0/19D9CB8 161s 2024-11-15 02:52:09.974 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.975 UTC [3499] 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=1717 kB; lsn=0/19F3020, redo lsn=0/19F2FC8 161s 2024-11-15 02:52:09.981 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.982 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=103 kB, estimate=1556 kB; lsn=0/1A0CE40, redo lsn=0/1A0CDE8 161s 2024-11-15 02:52:09.988 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.989 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=107 kB, estimate=1411 kB; lsn=0/1A27B28, redo lsn=0/1A27AD0 161s 2024-11-15 02:52:09.995 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:09.996 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=130 kB, estimate=1283 kB; lsn=0/1A484A8, redo lsn=0/1A48450 161s 2024-11-15 02:52:10.002 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.003 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=107 kB, estimate=1165 kB; lsn=0/1A63490, redo lsn=0/1A63438 161s 2024-11-15 02:52:10.009 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.010 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=102 kB, estimate=1059 kB; lsn=0/1A7D030, redo lsn=0/1A7CFD8 161s 2024-11-15 02:52:10.015 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.016 UTC [3499] 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/1A97780, redo lsn=0/1A97728 161s 2024-11-15 02:52:10.022 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.023 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=108 kB, estimate=878 kB; lsn=0/1AB2A60, redo lsn=0/1AB2A08 161s 2024-11-15 02:52:10.028 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.030 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=103 kB, estimate=800 kB; lsn=0/1ACC830, redo lsn=0/1ACC7D8 161s 2024-11-15 02:52:10.036 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.037 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=126 kB, estimate=733 kB; lsn=0/1AEC3F8, redo lsn=0/1AEC3A0 161s 2024-11-15 02:52:10.042 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.043 UTC [3499] 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/1B064B8, redo lsn=0/1B06460 161s 2024-11-15 02:52:10.049 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.050 UTC [3499] 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/1B210F8, redo lsn=0/1B210A0 161s 2024-11-15 02:52:10.085 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.086 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=107 kB, estimate=563 kB; lsn=0/1B3BD28, redo lsn=0/1B3BCD0 161s 2024-11-15 02:52:10.094 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.095 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=101 kB, estimate=517 kB; lsn=0/1B55508, redo lsn=0/1B554B0 161s 2024-11-15 02:52:10.125 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.126 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=133 kB, estimate=478 kB; lsn=0/1B769C0, redo lsn=0/1B76968 161s 2024-11-15 02:52:10.162 UTC [3499] LOG: checkpoint starting: immediate force wait 161s 2024-11-15 02:52:10.163 UTC [3499] 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.002 s; sync files=0, longest=0.000 s, average=0.000 s; distance=126 kB, estimate=443 kB; lsn=0/1B965B8, redo lsn=0/1B96560 161s Dropping cluster 17/regress ... 161s diff -U3 /tmp/autopkgtest.5mN8iZ/build.DVv/src/expected/datatypes.out /tmp/autopkgtest.5mN8iZ/build.DVv/src/results/datatypes.out 161s --- /tmp/autopkgtest.5mN8iZ/build.DVv/src/expected/datatypes.out 2023-09-14 13:37:45.000000000 +0000 161s +++ /tmp/autopkgtest.5mN8iZ/build.DVv/src/results/datatypes.out 2024-11-15 02:52:10.053235545 +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 161s autopkgtest [02:52:10]: test installcheck: -----------------------] 162s installcheck FAIL non-zero exit status 1 162s autopkgtest [02:52:11]: test installcheck: - - - - - - - - - - results - - - - - - - - - - 162s autopkgtest [02:52:11]: @@@@@@@@@@@@@@@@@@@@ summary 162s installcheck FAIL non-zero exit status 1 175s nova [W] Skipping flock in bos03-arm64 175s Creating nova instance adt-plucky-arm64-postgresql-filedump-20241115-024929-juju-7f2275-prod-proposed-migration-environment-20-975f03a4-0e1f-41a6-8401-56f23856c14b from image adt/ubuntu-plucky-arm64-server-20241114.img (UUID 4472f5f7-859f-4441-9e8e-9550fb35f210)...