0s autopkgtest [16:29:24]: starting date and time: 2025-03-15 16:29:24+0000
  0s autopkgtest [16:29:24]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production'
  0s autopkgtest [16:29:24]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.wz339rgi/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade pandoc-citeproc-preamble --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-16.secgroup --name adt-plucky-s390x-pandoc-citeproc-preamble-20250315-162924-juju-7f2275-prod-proposed-migration-environment-15-9f84da40-c220-4d82-91f6-af706179825a --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/
162s autopkgtest [16:32:06]: testbed dpkg architecture: s390x
162s autopkgtest [16:32:06]: testbed apt version: 2.9.33
162s autopkgtest [16:32:06]: @@@@@@@@@@@@@@@@@@@@ test bed setup
162s autopkgtest [16:32:06]: testbed release detected to be: None
163s autopkgtest [16:32:07]: updating testbed package index (apt update)
163s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB]
164s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease
164s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease
164s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease
164s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB]
164s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB]
164s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB]
164s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [113 kB]
165s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B]
165s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B]
165s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [320 kB]
165s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.4 kB]
165s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3776 B]
165s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B]
165s Fetched 1073 kB in 2s (613 kB/s)
166s Reading package lists...
166s + lsb_release --codename --short
166s + RELEASE=plucky
166s + cat
166s + [ plucky != trusty ]
166s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade
166s Reading package lists...
167s Building dependency tree...
167s Reading state information...
167s Calculating upgrade...
167s Calculating upgrade...
167s The following packages were automatically installed and are no longer required:
167s   libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64
167s   linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic
167s   linux-modules-6.11.0-8-generic linux-tools-6.11.0-8
167s   linux-tools-6.11.0-8-generic
167s Use 'sudo apt autoremove' to remove them.
167s The following packages will be upgraded:
167s   pinentry-curses python3-jinja2 strace
167s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
167s Need to get 652 kB of archives.
167s After this operation, 27.6 kB of additional disk space will be used.
167s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB]
168s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB]
168s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB]
168s Fetched 652 kB in 1s (614 kB/s)
168s (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.)
168s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ...
168s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ...
168s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ...
168s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ...
168s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ...
168s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ...
168s Setting up pinentry-curses (1.3.1-2ubuntu3) ...
168s Setting up python3-jinja2 (3.1.5-2ubuntu1) ...
168s Setting up strace (6.13+ds-1ubuntu1) ...
168s Processing triggers for man-db (2.13.0-1) ...
169s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref
169s + /usr/lib/apt/apt-helper analyze-pattern ?true
169s + uname -r
169s + sed s/\./\\./g
169s + running_kernel_pattern=^linux-.*6\.14\.0-10-generic.*
169s + apt list ?obsolete
169s + tail -n+2
169s + cut+ grep -v ^linux-.*6\.14\.0-10-generic.*
169s  -d/ -f1
169s + obsolete_pkgs=linux-headers-6.11.0-8-generic
169s linux-headers-6.11.0-8
169s linux-modules-6.11.0-8-generic
169s linux-tools-6.11.0-8-generic
169s linux-tools-6.11.0-8
169s + 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
169s Reading package lists...
169s Building dependency tree...
169s Reading state information...
169s Solving dependencies...
169s The following packages will be REMOVED:
169s   libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64*
169s   linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic*
169s   linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8*
169s   linux-tools-6.11.0-8-generic*
170s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded.
170s After this operation, 167 MB disk space will be freed.
170s (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.)
170s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ...
170s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ...
170s Removing libpython3.12t64:s390x (3.12.9-1) ...
170s Removing libpython3.12-stdlib:s390x (3.12.9-1) ...
170s Removing libnsl2:s390x (1.3.0-3build3) ...
170s Removing libpython3.12-minimal:s390x (3.12.9-1) ...
170s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ...
170s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ...
171s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ...
171s Processing triggers for libc-bin (2.41-1ubuntu1) ...
171s (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.)
171s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ...
171s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ...
171s + grep -q trusty /etc/lsb-release
171s + [ ! -d /usr/share/doc/unattended-upgrades ]
171s + [ ! -d /usr/share/doc/lxd ]
171s + [ ! -d /usr/share/doc/lxd-client ]
171s + [ ! -d /usr/share/doc/snapd ]
171s + type iptables
171s + cat
171s + chmod 755 /etc/rc.local
171s + . /etc/rc.local
171s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
171s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP
171s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP
171s + uname -m
171s + [ s390x = ppc64le ]
171s + [ -d /run/systemd/system ]
171s + systemd-detect-virt --quiet --vm
171s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/
171s + cat
171s + grep -q lz4 /etc/initramfs-tools/initramfs.conf
171s + echo COMPRESS=lz4
171s autopkgtest [16:32:15]: upgrading testbed (apt dist-upgrade and autopurge)
171s Reading package lists...
171s Building dependency tree...
171s Reading state information...
171s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
171s Starting 2 pkgProblemResolver with broken count: 0
171s Done
172s Entering ResolveByKeep
172s 
172s Calculating upgrade...
172s The following packages will be upgraded:
172s   libc-bin libc-dev-bin libc6 libc6-dev locales
172s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
172s Need to get 9512 kB of archives.
172s After this operation, 8192 B of additional disk space will be used.
172s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB]
174s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB]
174s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB]
177s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB]
177s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB]
182s Preconfiguring packages ...
182s Fetched 9512 kB in 10s (942 kB/s)
182s (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.)
182s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ...
182s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ...
182s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ...
182s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ...
182s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ...
182s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ...
182s Setting up libc6:s390x (2.41-1ubuntu2) ...
182s (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.)
182s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ...
182s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ...
183s Setting up libc-bin (2.41-1ubuntu2) ...
183s (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.)
183s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ...
183s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ...
183s Setting up locales (2.41-1ubuntu2) ...
183s Generating locales (this might take a while)...
184s   en_US.UTF-8... done
184s Generation complete.
184s Setting up libc-dev-bin (2.41-1ubuntu2) ...
184s Setting up libc6-dev:s390x (2.41-1ubuntu2) ...
184s Processing triggers for man-db (2.13.0-1) ...
184s Processing triggers for systemd (257.3-1ubuntu3) ...
185s Reading package lists...
186s Building dependency tree...
186s Reading state information...
186s Starting pkgProblemResolver with broken count: 0
186s Starting 2 pkgProblemResolver with broken count: 0
186s Done
186s Solving dependencies...
186s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
186s autopkgtest [16:32:30]: rebooting testbed after setup commands that affected boot
208s autopkgtest [16:32:52]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP Wed Mar 12 14:53:49 UTC 2025
215s autopkgtest [16:32:59]: @@@@@@@@@@@@@@@@@@@@ apt-source pandoc-citeproc-preamble
217s Get:1 http://ftpmaster.internal/ubuntu plucky/universe pandoc-citeproc-preamble 1.7-2 (dsc) [2317 B]
217s Get:2 http://ftpmaster.internal/ubuntu plucky/universe pandoc-citeproc-preamble 1.7-2 (tar) [18.8 kB]
217s Get:3 http://ftpmaster.internal/ubuntu plucky/universe pandoc-citeproc-preamble 1.7-2 (diff) [3516 B]
217s gpgv: Signature made Thu Mar 21 11:14:45 2024 UTC
217s gpgv:                using RSA key 9B917007AE030E36E4FC248B695B7AE4BF066240
217s gpgv: Can't check signature: No public key
217s dpkg-source: warning: cannot verify inline signature for ./pandoc-citeproc-preamble_1.7-2.dsc: no acceptable signature found
217s autopkgtest [16:33:01]: testing package pandoc-citeproc-preamble version 1.7-2
218s autopkgtest [16:33:02]: build not needed
218s autopkgtest [16:33:02]: test command1: preparing testbed
219s Reading package lists...
219s Building dependency tree...
219s Reading state information...
219s Starting pkgProblemResolver with broken count: 0
219s Starting 2 pkgProblemResolver with broken count: 0
219s Done
219s The following NEW packages will be installed:
219s   liblua5.4-0 pandoc pandoc-citeproc-preamble pandoc-data
220s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded.
220s Need to get 62.0 MB of archives.
220s After this operation, 430 MB of additional disk space will be used.
220s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x liblua5.4-0 s390x 5.4.7-1 [174 kB]
220s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x pandoc-data all 3.1.11.1-3build1 [78.8 kB]
220s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x pandoc s390x 3.1.11.1+ds-2 [52.5 MB]
275s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x pandoc-citeproc-preamble s390x 1.7-2 [9251 kB]
286s Fetched 62.0 MB in 1min 7s (927 kB/s)
286s Selecting previously unselected package liblua5.4-0:s390x.
287s (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.)
287s Preparing to unpack .../liblua5.4-0_5.4.7-1_s390x.deb ...
287s Unpacking liblua5.4-0:s390x (5.4.7-1) ...
287s Selecting previously unselected package pandoc-data.
287s Preparing to unpack .../pandoc-data_3.1.11.1-3build1_all.deb ...
287s Unpacking pandoc-data (3.1.11.1-3build1) ...
287s Selecting previously unselected package pandoc.
287s Preparing to unpack .../pandoc_3.1.11.1+ds-2_s390x.deb ...
287s Unpacking pandoc (3.1.11.1+ds-2) ...
288s Selecting previously unselected package pandoc-citeproc-preamble.
288s Preparing to unpack .../pandoc-citeproc-preamble_1.7-2_s390x.deb ...
288s Unpacking pandoc-citeproc-preamble (1.7-2) ...
288s Setting up pandoc-citeproc-preamble (1.7-2) ...
288s Setting up liblua5.4-0:s390x (5.4.7-1) ...
288s Setting up pandoc-data (3.1.11.1-3build1) ...
288s Setting up pandoc (3.1.11.1+ds-2) ...
288s Processing triggers for man-db (2.13.0-1) ...
288s Processing triggers for libc-bin (2.41-1ubuntu2) ...
290s autopkgtest [16:34:14]: test command1: test/test
290s autopkgtest [16:34:14]: test command1: [-----------------------
290s testing with pandoc 3.1.11.1
290s success: citeproc preamble inserted as expected
290s autopkgtest [16:34:14]: test command1: -----------------------]
291s autopkgtest [16:34:15]: test command1:  - - - - - - - - - - results - - - - - - - - - -
291s command1             PASS
291s autopkgtest [16:34:15]: @@@@@@@@@@@@@@@@@@@@ summary
291s command1             PASS
309s nova [W] Using flock in prodstack6-s390x
309s Creating nova instance adt-plucky-s390x-pandoc-citeproc-preamble-20250315-162924-juju-7f2275-prod-proposed-migration-environment-15-9f84da40-c220-4d82-91f6-af706179825a from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)...
309s nova [W] Timed out waiting for 683cc3a2-57ea-43e0-aca2-89d3bd378fbe to get deleted.