0s autopkgtest [16:47:32]: starting date and time: 2025-03-15 16:47:32+0000 0s autopkgtest [16:47:32]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [16:47:32]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ku0jjniv/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade postgresql-filedump --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-5.secgroup --name adt-plucky-s390x-postgresql-filedump-20250315-164732-juju-7f2275-prod-proposed-migration-environment-15-49f541ee-6d03-47c2-a9d4-4207e75ddf9a --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,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 149s autopkgtest [16:50:01]: testbed dpkg architecture: s390x 149s autopkgtest [16:50:01]: testbed apt version: 2.9.33 149s autopkgtest [16:50:01]: @@@@@@@@@@@@@@@@@@@@ test bed setup 150s autopkgtest [16:50:02]: testbed release detected to be: None 151s autopkgtest [16:50:03]: updating testbed package index (apt update) 152s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 152s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 152s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 152s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 152s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 152s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 153s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 153s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [113 kB] 153s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B] 153s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 153s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [320 kB] 154s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.4 kB] 154s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3776 B] 154s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B] 154s Fetched 1073 kB in 2s (601 kB/s) 154s Reading package lists... 155s + lsb_release --codename --short 155s + RELEASE=plucky 155s + cat 155s + [ plucky != trusty ] 155s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 155s Reading package lists... 155s Building dependency tree... 155s Reading state information... 155s Calculating upgrade... 155s Calculating upgrade... 156s The following packages were automatically installed and are no longer required: 156s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 156s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 156s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 156s linux-tools-6.11.0-8-generic 156s Use 'sudo apt autoremove' to remove them. 156s The following packages will be upgraded: 156s pinentry-curses python3-jinja2 strace 156s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 156s Need to get 652 kB of archives. 156s After this operation, 27.6 kB of additional disk space will be used. 156s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 157s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB] 157s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 157s Fetched 652 kB in 1s (557 kB/s) 157s (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 ... 81428 files and directories currently installed.) 157s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 157s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 157s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ... 157s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 157s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 157s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 157s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 157s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 157s Setting up strace (6.13+ds-1ubuntu1) ... 157s Processing triggers for man-db (2.13.0-1) ... 158s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 158s + /usr/lib/apt/apt-helper analyze-pattern ?true 158s + uname -r 158s + sed s/\./\\./g 158s + running_kernel_pattern=^linux-.*6\.14\.0-10-generic.* 158s + apt list ?obsolete 158s + tail -n+2 158s + cut -d/ -f1 158s + grep -v ^linux-.*6\.14\.0-10-generic.* 158s + obsolete_pkgs=linux-headers-6.11.0-8-generic 158s linux-headers-6.11.0-8 158s linux-modules-6.11.0-8-generic 158s linux-tools-6.11.0-8-generic 158s linux-tools-6.11.0-8 158s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove linux-headers-6.11.0-8-generic linux-headers-6.11.0-8 linux-modules-6.11.0-8-generic linux-tools-6.11.0-8-generic linux-tools-6.11.0-8 158s Reading package lists... 158s Building dependency tree... 158s Reading state information... 158s Solving dependencies... 158s The following packages will be REMOVED: 158s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 158s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 158s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 158s linux-tools-6.11.0-8-generic* 159s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 159s After this operation, 167 MB disk space will be freed. 159s (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 ... 81428 files and directories currently installed.) 159s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 159s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 159s Removing libpython3.12t64:s390x (3.12.9-1) ... 159s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 159s Removing libnsl2:s390x (1.3.0-3build3) ... 159s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 159s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 159s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 159s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 160s Processing triggers for libc-bin (2.41-1ubuntu1) ... 160s (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 ... 56328 files and directories currently installed.) 160s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 160s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 160s + grep -q trusty /etc/lsb-release 160s + [ ! -d /usr/share/doc/unattended-upgrades ] 160s + [ ! -d /usr/share/doc/lxd ] 160s + [ ! -d /usr/share/doc/lxd-client ] 160s + [ ! -d /usr/share/doc/snapd ] 160s + type iptables 160s + cat 160s + chmod 755 /etc/rc.local 160s + . /etc/rc.local 160s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 160s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 160s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 160s + uname -m 160s + [ s390x = ppc64le ] 160s + [ -d /run/systemd/system ] 160s + systemd-detect-virt --quiet --vm 160s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 160s + cat 160s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 160s + echo COMPRESS=lz4 160s autopkgtest [16:50:12]: upgrading testbed (apt dist-upgrade and autopurge) 160s Reading package lists... 160s Building dependency tree... 160s Reading state information... 161s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 161s Starting 2 pkgProblemResolver with broken count: 0 161s Done 161s Entering ResolveByKeep 161s 161s Calculating upgrade... 161s The following packages will be upgraded: 161s libc-bin libc-dev-bin libc6 libc6-dev locales 161s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 161s Need to get 9512 kB of archives. 161s After this operation, 8192 B of additional disk space will be used. 161s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 163s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 163s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 166s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 167s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 171s Preconfiguring packages ... 171s Fetched 9512 kB in 10s (934 kB/s) 172s (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 ... 56326 files and directories currently installed.) 172s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 172s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 172s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 172s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 172s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 172s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 172s Setting up libc6:s390x (2.41-1ubuntu2) ... 172s (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 ... 56326 files and directories currently installed.) 172s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 172s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 172s Setting up libc-bin (2.41-1ubuntu2) ... 172s (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 ... 56326 files and directories currently installed.) 172s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 172s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 172s Setting up locales (2.41-1ubuntu2) ... 173s Generating locales (this might take a while)... 173s en_US.UTF-8... done 173s Generation complete. 173s Setting up libc-dev-bin (2.41-1ubuntu2) ... 173s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 173s Processing triggers for man-db (2.13.0-1) ... 174s Processing triggers for systemd (257.3-1ubuntu3) ... 175s Reading package lists... 175s Building dependency tree... 175s Reading state information... 175s Starting pkgProblemResolver with broken count: 0 175s Starting 2 pkgProblemResolver with broken count: 0 175s Done 175s Solving dependencies... 175s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 176s autopkgtest [16:50:28]: rebooting testbed after setup commands that affected boot 201s autopkgtest [16:50:53]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP Wed Mar 12 14:53:49 UTC 2025 205s autopkgtest [16:50:57]: @@@@@@@@@@@@@@@@@@@@ apt-source postgresql-filedump 207s Get:1 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 17.1-1ubuntu2 (dsc) [2332 B] 207s Get:2 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 17.1-1ubuntu2 (tar) [44.5 kB] 207s Get:3 http://ftpmaster.internal/ubuntu plucky/universe postgresql-filedump 17.1-1ubuntu2 (diff) [4172 B] 207s gpgv: Signature made Thu Mar 6 05:08:18 2025 UTC 207s gpgv: using RSA key 5C7ABEA20F8630459CC8C8B5E27F2CF8458C2FA4 207s gpgv: Can't check signature: No public key 207s dpkg-source: warning: cannot verify inline signature for ./postgresql-filedump_17.1-1ubuntu2.dsc: no acceptable signature found 207s autopkgtest [16:50:59]: testing package postgresql-filedump version 17.1-1ubuntu2 207s autopkgtest [16:50:59]: build not needed 208s autopkgtest [16:51:00]: test installcheck: preparing testbed 208s Reading package lists... 208s Building dependency tree... 208s Reading state information... 208s Starting pkgProblemResolver with broken count: 0 208s Starting 2 pkgProblemResolver with broken count: 0 208s Done 208s The following NEW packages will be installed: 208s libio-pty-perl libipc-run-perl libjson-perl libllvm20 libpq5 libxslt1.1 208s postgresql postgresql-17 postgresql-client-17 postgresql-client-common 208s postgresql-common postgresql-common-dev postgresql-filedump ssl-cert 208s 0 upgraded, 14 newly installed, 0 to remove and 0 not upgraded. 208s Need to get 49.8 MB of archives. 208s After this operation, 213 MB of additional disk space will be used. 208s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libjson-perl all 4.10000-1 [81.9 kB] 209s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-client-common all 274 [47.6 kB] 209s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 209s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 209s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-common-dev all 274 [73.0 kB] 209s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x ssl-cert all 1.1.3ubuntu1 [18.7 kB] 209s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-common all 274 [101 kB] 209s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libllvm20 s390x 1:20.1.0~+rc2-1~exp2ubuntu0.4 [31.3 MB] 246s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libpq5 s390x 17.4-1 [147 kB] 246s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libxslt1.1 s390x 1.1.39-0exp1ubuntu2 [169 kB] 246s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-client-17 s390x 17.4-1 [1367 kB] 248s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql-17 s390x 17.4-1 [16.3 MB] 267s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x postgresql all 17+274 [14.4 kB] 267s Get:14 http://ftpmaster.internal/ubuntu plucky/universe s390x postgresql-filedump s390x 17.1-1ubuntu2 [47.1 kB] 267s Preconfiguring packages ... 267s /var/cache/debconf/tmp.ci/postgresql.config.c3MpJV: 12: pg_lsclusters: not found 267s Fetched 49.8 MB in 59s (847 kB/s) 267s Selecting previously unselected package libjson-perl. 267s (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 ... 56326 files and directories currently installed.) 267s Preparing to unpack .../00-libjson-perl_4.10000-1_all.deb ... 267s Unpacking libjson-perl (4.10000-1) ... 267s Selecting previously unselected package postgresql-client-common. 267s Preparing to unpack .../01-postgresql-client-common_274_all.deb ... 267s Unpacking postgresql-client-common (274) ... 267s Selecting previously unselected package libio-pty-perl. 267s Preparing to unpack .../02-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 267s Unpacking libio-pty-perl (1:1.20-1build3) ... 267s Selecting previously unselected package libipc-run-perl. 267s Preparing to unpack .../03-libipc-run-perl_20231003.0-2_all.deb ... 267s Unpacking libipc-run-perl (20231003.0-2) ... 267s Selecting previously unselected package postgresql-common-dev. 267s Preparing to unpack .../04-postgresql-common-dev_274_all.deb ... 267s Unpacking postgresql-common-dev (274) ... 268s Selecting previously unselected package ssl-cert. 268s Preparing to unpack .../05-ssl-cert_1.1.3ubuntu1_all.deb ... 268s Unpacking ssl-cert (1.1.3ubuntu1) ... 268s Selecting previously unselected package postgresql-common. 268s Preparing to unpack .../06-postgresql-common_274_all.deb ... 268s Adding 'diversion of /usr/bin/pg_config to /usr/bin/pg_config.libpq-dev by postgresql-common' 268s Unpacking postgresql-common (274) ... 268s Selecting previously unselected package libllvm20:s390x. 268s Preparing to unpack .../07-libllvm20_1%3a20.1.0~+rc2-1~exp2ubuntu0.4_s390x.deb ... 268s Unpacking libllvm20:s390x (1:20.1.0~+rc2-1~exp2ubuntu0.4) ... 268s Selecting previously unselected package libpq5:s390x. 268s Preparing to unpack .../08-libpq5_17.4-1_s390x.deb ... 268s Unpacking libpq5:s390x (17.4-1) ... 268s Selecting previously unselected package libxslt1.1:s390x. 268s Preparing to unpack .../09-libxslt1.1_1.1.39-0exp1ubuntu2_s390x.deb ... 268s Unpacking libxslt1.1:s390x (1.1.39-0exp1ubuntu2) ... 268s Selecting previously unselected package postgresql-client-17. 268s Preparing to unpack .../10-postgresql-client-17_17.4-1_s390x.deb ... 268s Unpacking postgresql-client-17 (17.4-1) ... 268s Selecting previously unselected package postgresql-17. 268s Preparing to unpack .../11-postgresql-17_17.4-1_s390x.deb ... 268s Unpacking postgresql-17 (17.4-1) ... 268s Selecting previously unselected package postgresql. 268s Preparing to unpack .../12-postgresql_17+274_all.deb ... 268s Unpacking postgresql (17+274) ... 268s Selecting previously unselected package postgresql-filedump. 268s Preparing to unpack .../13-postgresql-filedump_17.1-1ubuntu2_s390x.deb ... 268s Unpacking postgresql-filedump (17.1-1ubuntu2) ... 268s Setting up postgresql-client-common (274) ... 268s Setting up libio-pty-perl (1:1.20-1build3) ... 268s Setting up libpq5:s390x (17.4-1) ... 268s Setting up ssl-cert (1.1.3ubuntu1) ... 269s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 269s Setting up postgresql-filedump (17.1-1ubuntu2) ... 269s Setting up libllvm20:s390x (1:20.1.0~+rc2-1~exp2ubuntu0.4) ... 269s Setting up libipc-run-perl (20231003.0-2) ... 269s Setting up libjson-perl (4.10000-1) ... 269s Setting up libxslt1.1:s390x (1.1.39-0exp1ubuntu2) ... 269s Setting up postgresql-common-dev (274) ... 269s Setting up postgresql-client-17 (17.4-1) ... 269s 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 269s Setting up postgresql-common (274) ... 269s Creating config file /etc/postgresql-common/createcluster.conf with new version 270s Building PostgreSQL dictionaries from installed myspell/hunspell packages... 270s Removing obsolete dictionary files: 270s Created symlink '/etc/systemd/system/multi-user.target.wants/postgresql.service' → '/usr/lib/systemd/system/postgresql.service'. 270s Setting up postgresql-17 (17.4-1) ... 271s Creating new PostgreSQL cluster 17/main ... 271s /usr/lib/postgresql/17/bin/initdb -D /var/lib/postgresql/17/main --auth-local peer --auth-host scram-sha-256 --no-instructions 271s The files belonging to this database system will be owned by user "postgres". 271s This user must also own the server process. 271s 271s The database cluster will be initialized with locale "C.UTF-8". 271s The default database encoding has accordingly been set to "UTF8". 271s The default text search configuration will be set to "english". 271s 271s Data page checksums are disabled. 271s 271s fixing permissions on existing directory /var/lib/postgresql/17/main ... ok 271s creating subdirectories ... ok 271s selecting dynamic shared memory implementation ... posix 271s selecting default "max_connections" ... 100 271s selecting default "shared_buffers" ... 128MB 271s selecting default time zone ... Etc/UTC 271s creating configuration files ... ok 271s running bootstrap script ... ok 271s performing post-bootstrap initialization ... ok 271s syncing data to disk ... ok 274s Setting up postgresql (17+274) ... 274s Processing triggers for man-db (2.13.0-1) ... 275s Processing triggers for libc-bin (2.41-1ubuntu2) ... 276s autopkgtest [16:52:08]: test installcheck: [----------------------- 276s + rm -rf results tmp_check *.heap [1-9]*[0-9] 276s + id -u 276s + [ 1000 = 0 ] 276s + pg_virtualenv make installcheck 276s Creating new PostgreSQL cluster 17/regress ... 279s 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 toast 279s # +++ regress install-check in +++ 279s # using postmaster on localhost, port 5433 279s ok 1 - datatypes 78 ms 279s ok 2 - float 20 ms 279s ok 3 - numeric 20 ms 279s ok 4 - xml 20 ms 279s not ok 5 - toast 45 ms 279s 1..5 279s *** /tmp/pg_virtualenv.HBbucO/log/postgresql-17-regress.log (last 100 lines) *** 279s # 1 of 5 tests failed. 279s # The differences that caused some tests to fail can be viewed in the file "/tmp/autopkgtest.KEXb3A/build.9tN/src/regression.diffs". 279s # A copy of the test summary that you see above is saved in the file "/tmp/autopkgtest.KEXb3A/build.9tN/src/regression.out". 279s make: *** [/usr/lib/postgresql/17/lib/pgxs/src/makefiles/pgxs.mk:436: installcheck] Error 1 279s 2025-03-15 16:52:09.214 UTC [3522] LOG: starting PostgreSQL 17.4 (Ubuntu 17.4-1) on s390x-ibm-linux-gnu, compiled by gcc (Ubuntu 14.2.0-17ubuntu3) 14.2.0, 64-bit 279s 2025-03-15 16:52:09.214 UTC [3522] LOG: listening on IPv6 address "::1", port 5433 279s 2025-03-15 16:52:09.214 UTC [3522] LOG: listening on IPv4 address "127.0.0.1", port 5433 279s 2025-03-15 16:52:09.214 UTC [3522] LOG: listening on Unix socket "/tmp/.s.PGSQL.5433" 279s 2025-03-15 16:52:09.215 UTC [3525] LOG: database system was shut down at 2025-03-15 16:52:09 UTC 279s 2025-03-15 16:52:09.217 UTC [3522] LOG: database system is ready to accept connections 279s 2025-03-15 16:52:11.439 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.443 UTC [3523] LOG: checkpoint complete: wrote 935 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=4280 kB, estimate=4280 kB; lsn=0/191B150, redo lsn=0/191B0F8 279s 2025-03-15 16:52:11.448 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.449 UTC [3523] 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=3860 kB; lsn=0/1930608, redo lsn=0/19305B0 279s 2025-03-15 16:52:11.452 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.452 UTC [3523] 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=3483 kB; lsn=0/19465C0, redo lsn=0/1946568 279s 2025-03-15 16:52:11.454 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.454 UTC [3523] 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=3144 kB; lsn=0/195D088, redo lsn=0/195D030 279s 2025-03-15 16:52:11.457 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.457 UTC [3523] 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=2839 kB; lsn=0/19746C8, redo lsn=0/1974670 279s 2025-03-15 16:52:11.460 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.460 UTC [3523] 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=2564 kB; lsn=0/198C958, redo lsn=0/198C900 279s 2025-03-15 16:52:11.462 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.462 UTC [3523] 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=2318 kB; lsn=0/19A6E70, redo lsn=0/19A6E18 279s 2025-03-15 16:52:11.465 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.465 UTC [3523] 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=2098 kB; lsn=0/19C4390, redo lsn=0/19C4338 279s 2025-03-15 16:52:11.467 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.467 UTC [3523] 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=1898 kB; lsn=0/19DCA90, redo lsn=0/19DCA38 279s 2025-03-15 16:52:11.469 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.470 UTC [3523] 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=1718 kB; lsn=0/19F5D78, redo lsn=0/19F5D20 279s 2025-03-15 16:52:11.472 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.472 UTC [3523] 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=1557 kB; lsn=0/1A0FB90, redo lsn=0/1A0FB38 279s 2025-03-15 16:52:11.474 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.474 UTC [3523] 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=1412 kB; lsn=0/1A2A888, redo lsn=0/1A2A830 279s 2025-03-15 16:52:11.477 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.477 UTC [3523] 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=1284 kB; lsn=0/1A4B1E8, redo lsn=0/1A4B190 279s 2025-03-15 16:52:11.479 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.479 UTC [3523] 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=1166 kB; lsn=0/1A661D8, redo lsn=0/1A66180 279s 2025-03-15 16:52:11.481 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.482 UTC [3523] 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=1060 kB; lsn=0/1A7FD50, redo lsn=0/1A7FCF8 279s 2025-03-15 16:52:11.484 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.484 UTC [3523] 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=964 kB; lsn=0/1A9A4A0, redo lsn=0/1A9A448 279s 2025-03-15 16:52:11.487 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.487 UTC [3523] 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=879 kB; lsn=0/1AB5748, redo lsn=0/1AB56F0 279s 2025-03-15 16:52:11.489 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.489 UTC [3523] 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=801 kB; lsn=0/1ACF4F0, redo lsn=0/1ACF498 279s 2025-03-15 16:52:11.492 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.492 UTC [3523] 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=734 kB; lsn=0/1AEF088, redo lsn=0/1AEF030 279s 2025-03-15 16:52:11.494 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.494 UTC [3523] 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=671 kB; lsn=0/1B09120, redo lsn=0/1B090C8 279s 2025-03-15 16:52:11.496 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.496 UTC [3523] 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/1B23D30, redo lsn=0/1B23CD8 279s 2025-03-15 16:52:11.515 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.515 UTC [3523] LOG: checkpoint complete: wrote 27 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/1B3E940, redo lsn=0/1B3E8E8 279s 2025-03-15 16:52:11.518 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.518 UTC [3523] 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/1B58070, redo lsn=0/1B58018 279s 2025-03-15 16:52:11.537 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.538 UTC [3523] 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=479 kB; lsn=0/1B79498, redo lsn=0/1B79440 279s 2025-03-15 16:52:11.560 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.561 UTC [3523] 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/1B99018, redo lsn=0/1B98FC0 279s 2025-03-15 16:52:11.583 UTC [3523] LOG: checkpoint starting: immediate force wait 279s 2025-03-15 16:52:11.583 UTC [3523] LOG: checkpoint complete: wrote 39 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=134 kB, estimate=413 kB; lsn=0/1BBAB30, redo lsn=0/1BBAAD8 279s Dropping cluster 17/regress ... 279s + head -n1000 regression.diffs tmp_check/log/* 279s ==> regression.diffs <== 279s diff -U3 /tmp/autopkgtest.KEXb3A/build.9tN/src/expected/toast.out /tmp/autopkgtest.KEXb3A/build.9tN/src/results/toast.out 279s --- /tmp/autopkgtest.KEXb3A/build.9tN/src/expected/toast.out 2024-11-07 15:26:21.000000000 +0000 279s +++ /tmp/autopkgtest.KEXb3A/build.9tN/src/results/toast.out 2025-03-15 16:52:11.606909649 +0000 279s @@ -48,7 +48,7 @@ 279s Item 1 -- Length: 41 Offset: 8144 (0x1fd0) Flags: NORMAL 279s COPY: short inline xxx 279s Item 2 -- Length: 256 Offset: 7888 (0x1ed0) Flags: NORMAL 279s -COPY: long inline uncompressed xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx 279s +Error: unable to decode a tuple, 125 bytes left, 0 expected. Partial data: long inline uncompressed xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx 279s Item 3 -- Length: 64 Offset: 7824 (0x1e90) Flags: NORMAL 279s COPY: external uncompressed (TOASTED,uncompressed) 279s Item 4 -- Length: 107 Offset: 7712 (0x1e20) Flags: NORMAL 279s @@ -84,7 +84,7 @@ 279s Item 1 -- Length: 41 Offset: 8144 (0x1fd0) Flags: NORMAL 279s COPY: short inline xxx 279s Item 2 -- Length: 256 Offset: 7888 (0x1ed0) Flags: NORMAL 279s -COPY: long inline uncompressed xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx 279s +Error: unable to decode a tuple, 125 bytes left, 0 expected. Partial data: long inline uncompressed xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx 279s Item 3 -- Length: 64 Offset: 7824 (0x1e90) Flags: NORMAL 279s TOAST value. Raw size: 2804, external size: 2800, value id: ....., toast relation id: ....., chunks: 2 279s COPY: external uncompressed 0123456789 279s head: cannot open 'tmp_check/log/*' for reading: No such file or directory 280s autopkgtest [16:52:12]: test installcheck: -----------------------] 280s autopkgtest [16:52:12]: test installcheck: - - - - - - - - - - results - - - - - - - - - - 280s installcheck FAIL non-zero exit status 1 282s autopkgtest [16:52:14]: @@@@@@@@@@@@@@@@@@@@ summary 282s installcheck FAIL non-zero exit status 1 302s nova [W] Using flock in prodstack6-s390x 304s flock: timeout while waiting to get lock 304s Creating nova instance adt-plucky-s390x-postgresql-filedump-20250315-164732-juju-7f2275-prod-proposed-migration-environment-15-49f541ee-6d03-47c2-a9d4-4207e75ddf9a from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 304s nova [W] Timed out waiting for 4cfd3126-304e-403b-a8ac-669fa49262ad to get deleted.