0s autopkgtest [18:36:25]: starting date and time: 2025-03-15 18:36:25+0000 0s autopkgtest [18:36:25]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [18:36:25]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.c1c29d5a/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade racon --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-20@bos03-s390x-18.secgroup --name adt-plucky-s390x-racon-20250315-183625-juju-7f2275-prod-proposed-migration-environment-20-5a48083b-4bdb-46f7-a1a6-c04a3efe0866 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-s390x -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 136s autopkgtest [18:38:41]: testbed dpkg architecture: s390x 136s autopkgtest [18:38:41]: testbed apt version: 2.9.33 136s autopkgtest [18:38:41]: @@@@@@@@@@@@@@@@@@@@ test bed setup 136s autopkgtest [18:38:41]: testbed release detected to be: None 137s autopkgtest [18:38:42]: updating testbed package index (apt update) 137s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 138s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 138s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 138s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 138s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 138s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 138s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 138s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [113 kB] 138s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B] 138s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 138s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [320 kB] 139s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.4 kB] 139s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3776 B] 139s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B] 139s Fetched 1073 kB in 2s (643 kB/s) 140s Reading package lists... 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 140s Calculating upgrade... 140s Calculating upgrade... 141s The following packages were automatically installed and are no longer required: 141s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 141s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 141s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 141s linux-tools-6.11.0-8-generic 141s Use 'sudo apt autoremove' to remove them. 141s The following packages will be upgraded: 141s pinentry-curses python3-jinja2 strace 141s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 141s Need to get 652 kB of archives. 141s After this operation, 27.6 kB of additional disk space will be used. 141s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 141s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB] 141s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 142s Fetched 652 kB in 1s (713 kB/s) 142s (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.) 142s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 142s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 142s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ... 142s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 142s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 142s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 142s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 142s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 142s Setting up strace (6.13+ds-1ubuntu1) ... 142s Processing triggers for man-db (2.13.0-1) ... 143s Reading package lists... 143s Building dependency tree... 143s Reading state information... 143s Solving dependencies... 143s The following packages will be REMOVED: 143s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 143s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 143s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 143s linux-tools-6.11.0-8-generic* 143s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 143s After this operation, 167 MB disk space will be freed. 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 ... 81428 files and directories currently installed.) 143s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 143s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 143s Removing libpython3.12t64:s390x (3.12.9-1) ... 143s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 143s Removing libnsl2:s390x (1.3.0-3build3) ... 143s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 143s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 143s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 146s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 146s autopkgtest [18:38:49]: upgrading testbed (apt dist-upgrade and autopurge) 146s Processing triggers for libc-bin (2.41-1ubuntu1) ... 146s (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.) 146s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 146s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 146s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 146s Starting 2 pkgProblemResolver with broken count: 0 146s Done 146s Entering ResolveByKeep 146s 146s Calculating upgrade... 146s The following packages will be upgraded: 146s libc-bin libc-dev-bin libc6 libc6-dev locales 146s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 146s Need to get 9512 kB of archives. 146s After this operation, 8192 B of additional disk space will be used. 146s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 147s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 147s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 150s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 150s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 153s Preconfiguring packages ... 153s Fetched 9512 kB in 8s (1249 kB/s) 153s (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.) 153s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 153s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 153s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 153s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 153s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 153s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 154s Setting up libc6:s390x (2.41-1ubuntu2) ... 154s (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.) 154s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 154s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 154s Setting up libc-bin (2.41-1ubuntu2) ... 154s (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.) 154s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 154s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 154s Setting up locales (2.41-1ubuntu2) ... 154s Generating locales (this might take a while)... 155s en_US.UTF-8... done 155s Generation complete. 155s Setting up libc-dev-bin (2.41-1ubuntu2) ... 155s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 155s Processing triggers for man-db (2.13.0-1) ... 156s Processing triggers for systemd (257.3-1ubuntu3) ... 157s Reading package lists... 157s Building dependency tree... 157s Reading state information... 157s Starting pkgProblemResolver with broken count: 0 157s Starting 2 pkgProblemResolver with broken count: 0 157s Done 157s Solving dependencies... 157s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 157s autopkgtest [18:39:02]: rebooting testbed after setup commands that affected boot 175s autopkgtest [18:39:20]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP Wed Mar 12 14:53:49 UTC 2025 178s autopkgtest [18:39:23]: @@@@@@@@@@@@@@@@@@@@ apt-source racon 181s Get:1 http://ftpmaster.internal/ubuntu plucky/universe racon 1.5.0-3 (dsc) [2106 B] 181s Get:2 http://ftpmaster.internal/ubuntu plucky/universe racon 1.5.0-3 (tar) [3084 kB] 181s Get:3 http://ftpmaster.internal/ubuntu plucky/universe racon 1.5.0-3 (diff) [20.7 kB] 182s gpgv: Signature made Thu Sep 29 12:45:21 2022 UTC 182s gpgv: using RSA key F1F007320A035541F0A663CA578A0494D1C646D1 182s gpgv: issuer "tille@debian.org" 182s gpgv: Can't check signature: No public key 182s dpkg-source: warning: cannot verify inline signature for ./racon_1.5.0-3.dsc: no acceptable signature found 182s autopkgtest [18:39:27]: testing package racon version 1.5.0-3 182s autopkgtest [18:39:27]: build not needed 183s autopkgtest [18:39:28]: test run-unit-test: preparing testbed 183s Reading package lists... 183s Building dependency tree... 183s Reading state information... 183s Starting pkgProblemResolver with broken count: 0 183s Starting 2 pkgProblemResolver with broken count: 0 183s Done 183s The following NEW packages will be installed: 183s libedlib1 libspoa7.0.0 racon 184s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 184s Need to get 3219 kB of archives. 184s After this operation, 3500 kB of additional disk space will be used. 184s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x libedlib1 s390x 1.2.7-6build2 [24.6 kB] 184s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x libspoa7.0.0 s390x 4.1.4-2 [82.1 kB] 184s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x racon s390x 1.5.0-3 [3112 kB] 187s Fetched 3219 kB in 3s (1047 kB/s) 187s Selecting previously unselected package libedlib1:s390x. 187s (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.) 187s Preparing to unpack .../libedlib1_1.2.7-6build2_s390x.deb ... 187s Unpacking libedlib1:s390x (1.2.7-6build2) ... 187s Selecting previously unselected package libspoa7.0.0:s390x. 187s Preparing to unpack .../libspoa7.0.0_4.1.4-2_s390x.deb ... 187s Unpacking libspoa7.0.0:s390x (4.1.4-2) ... 187s Selecting previously unselected package racon. 187s Preparing to unpack .../racon_1.5.0-3_s390x.deb ... 187s Unpacking racon (1.5.0-3) ... 187s Setting up libspoa7.0.0:s390x (4.1.4-2) ... 187s Setting up libedlib1:s390x (1.2.7-6build2) ... 187s Setting up racon (1.5.0-3) ... 187s Processing triggers for man-db (2.13.0-1) ... 187s Processing triggers for libc-bin (2.41-1ubuntu2) ... 188s autopkgtest [18:39:33]: test run-unit-test: [----------------------- 188s [racon::Polisher::initialize] loaded target sequences 0.000378 s 188s [racon::Polisher::initialize] loaded sequences 0.026683 s 188s [racon::Polisher::initialize] loaded overlaps 0.000235 s 189s [racon::Polisher::initialize] aligning overlaps [=> ] 0.058232 s [racon::Polisher::initialize] aligning overlaps [==> ] 0.100191 s [racon::Polisher::initialize] aligning overlaps [===> ] 0.136451 s [racon::Polisher::initialize] aligning overlaps [====> ] 0.178641 s [racon::Polisher::initialize] aligning overlaps [=====> ] 0.214325 s [racon::Polisher::initialize] aligning overlaps [======> ] 0.243614 s [racon::Polisher::initialize] aligning overlaps [=======> ] 0.297851 s [racon::Polisher::initialize] aligning overlaps [========> ] 0.343271 s [racon::Polisher::initialize] aligning overlaps [=========> ] 0.390214 s [racon::Polisher::initialize] aligning overlaps [==========> ] 0.436011 s [racon::Polisher::initialize] aligning overlaps [===========> ] 0.475837 s [racon::Polisher::initialize] aligning overlaps [============> ] 0.532899 s [racon::Polisher::initialize] aligning overlaps [=============> ] 0.585615 s [racon::Polisher::initialize] aligning overlaps [==============> ] 0.618975 s [racon::Polisher::initialize] aligning overlaps [===============> ] 0.676318 s [racon::Polisher::initialize] aligning overlaps [================> ] 0.733816 s [racon::Polisher::initialize] aligning overlaps [=================> ] 0.778467 s [racon::Polisher::initialize] aligning overlaps [==================> ] 0.827981 s [racon::Polisher::initialize] aligning overlaps [===================>] 0.882606 s [racon::Polisher::initialize] aligning overlaps [====================] 0.947565 s 189s [racon::Polisher::initialize] transformed data into windows 0.001990 s 202s [racon::Polisher::polish] generating consensus [=> ] 0.512216 s [racon::Polisher::polish] generating consensus [==> ] 1.033948 s [racon::Polisher::polish] generating consensus [===> ] 1.551668 s [racon::Polisher::polish] generating consensus [====> ] 1.907034 s [racon::Polisher::polish] generating consensus [=====> ] 2.114872 s [racon::Polisher::polish] generating consensus [======> ] 2.334689 s [racon::Polisher::polish] generating consensus [=======> ] 2.546721 s [racon::Polisher::polish] generating consensus [========> ] 2.802820 s [racon::Polisher::polish] generating consensus [=========> ] 3.123248 s [racon::Polisher::polish] generating consensus [==========> ] 3.602929 s [racon::Polisher::polish] generating consensus [===========> ] 4.253377 s [racon::Polisher::polish] generating consensus [============> ] 4.974193 s [racon::Polisher::polish] generating consensus [=============> ] 5.965264 s [racon::Polisher::polish] generating consensus [==============> ] 6.987655 s [racon::Polisher::polish] generating consensus [===============> ] 7.750155 s [racon::Polisher::polish] generating consensus [================> ] 8.376520 s [racon::Polisher::polish] generating consensus [=================> ] 9.066457 s [racon::Polisher::polish] generating consensus [==================> ] 9.549391 s [racon::Polisher::polish] generating consensus [===================>] 10.054316 s [racon::Polisher::polish] generating consensus [====================] 12.613579 s 202s [racon::Polisher::] total = 13.590795racon output matches expected output 202s PASS 202s s 202s autopkgtest [18:39:47]: test run-unit-test: -----------------------] 203s autopkgtest [18:39:48]: test run-unit-test: - - - - - - - - - - results - - - - - - - - - - 203s run-unit-test PASS 203s autopkgtest [18:39:48]: @@@@@@@@@@@@@@@@@@@@ summary 203s run-unit-test PASS 220s nova [W] Using flock in prodstack6-s390x 220s flock: timeout while waiting to get lock 220s Creating nova instance adt-plucky-s390x-racon-20250315-183625-juju-7f2275-prod-proposed-migration-environment-20-5a48083b-4bdb-46f7-a1a6-c04a3efe0866 from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 220s nova [W] Timed out waiting for b5cf731c-35a3-4ad6-83db-c28cc75c76ea to get deleted.