0s autopkgtest [13:11:28]: starting date and time: 2025-03-22 13:11:28+0000 0s autopkgtest [13:11:28]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [13:11:28]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.d79g3nup/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:guile-fibers --apt-upgrade guile-fibers --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=guile-fibers/1.3.1-4 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-16.secgroup --name adt-plucky-s390x-guile-fibers-20250322-131128-juju-7f2275-prod-proposed-migration-environment-2-5ad89fbf-40cf-4d7a-aa30-37c7fb678436 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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/ 52s autopkgtest [13:12:20]: testbed dpkg architecture: s390x 52s autopkgtest [13:12:20]: testbed apt version: 2.9.33 53s autopkgtest [13:12:21]: @@@@@@@@@@@@@@@@@@@@ test bed setup 53s autopkgtest [13:12:21]: testbed release detected to be: None 54s autopkgtest [13:12:22]: updating testbed package index (apt update) 54s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 54s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 54s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 54s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 54s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [1232 B] 54s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [11.5 kB] 54s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [29.9 kB] 54s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [288 kB] 55s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [41.5 kB] 55s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1264 B] 55s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 55s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [182 kB] 55s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [10.7 kB] 55s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2512 B] 55s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [316 B] 55s Fetched 695 kB in 1s (920 kB/s) 56s Reading package lists... 56s autopkgtest [13:12:24]: upgrading testbed (apt dist-upgrade and autopurge) 57s Reading package lists... 57s Building dependency tree... 57s Reading state information... 57s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 57s Starting 2 pkgProblemResolver with broken count: 0 57s Done 57s Entering ResolveByKeep 57s 57s Calculating upgrade... 57s The following packages will be upgraded: 57s apparmor apt apt-utils dpkg dpkg-dev fwupd gcc-14-base ibverbs-providers 57s libapparmor1 libapt-pkg7.0 libdpkg-perl libfwupd3 libibverbs1 libldap-common 57s libldap2 libxmlb2 ubuntu-pro-client ubuntu-pro-client-l10n 57s 18 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 57s Need to get 12.7 MB of archives. 57s After this operation, 180 kB of additional disk space will be used. 57s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libapt-pkg7.0 s390x 2.9.34 [1114 kB] 58s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x dpkg s390x 1.22.18ubuntu2 [1300 kB] 58s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x apt s390x 2.9.34 [1424 kB] 58s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x apt-utils s390x 2.9.34 [215 kB] 58s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-19ubuntu1 [53.9 kB] 58s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libapparmor1 s390x 4.1.0~beta5-0ubuntu11 [55.0 kB] 58s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x ubuntu-pro-client-l10n s390x 35 [19.7 kB] 58s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x ubuntu-pro-client s390x 35 [257 kB] 58s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x apparmor s390x 4.1.0~beta5-0ubuntu11 [759 kB] 58s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libibverbs1 s390x 56.0-3ubuntu1 [70.0 kB] 58s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x ibverbs-providers s390x 56.0-3ubuntu1 [415 kB] 58s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x dpkg-dev all 1.22.18ubuntu2 [1089 kB] 58s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libdpkg-perl all 1.22.18ubuntu2 [281 kB] 58s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x fwupd s390x 2.0.6-7 [5235 kB] 58s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libfwupd3 s390x 2.0.6-7 [134 kB] 58s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libxmlb2 s390x 0.3.22-1 [69.2 kB] 58s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libldap-common all 2.6.9+dfsg-2ubuntu1 [35.5 kB] 58s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x libldap2 s390x 2.6.9+dfsg-2ubuntu1 [206 kB] 58s Preconfiguring packages ... 58s Fetched 12.7 MB in 1s (13.5 MB/s) 59s (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 ... 81426 files and directories currently installed.) 59s Preparing to unpack .../libapt-pkg7.0_2.9.34_s390x.deb ... 59s Unpacking libapt-pkg7.0:s390x (2.9.34) over (2.9.33) ... 59s Setting up libapt-pkg7.0:s390x (2.9.34) ... 59s (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 ... 81426 files and directories currently installed.) 59s Preparing to unpack .../dpkg_1.22.18ubuntu2_s390x.deb ... 59s Unpacking dpkg (1.22.18ubuntu2) over (1.22.15ubuntu1) ... 59s Setting up dpkg (1.22.18ubuntu2) ... 59s (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 ... 81425 files and directories currently installed.) 59s Preparing to unpack .../archives/apt_2.9.34_s390x.deb ... 59s Unpacking apt (2.9.34) over (2.9.33) ... 59s Setting up apt (2.9.34) ... 60s (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 ... 81425 files and directories currently installed.) 60s Preparing to unpack .../00-apt-utils_2.9.34_s390x.deb ... 60s Unpacking apt-utils (2.9.34) over (2.9.33) ... 60s Preparing to unpack .../01-gcc-14-base_14.2.0-19ubuntu1_s390x.deb ... 60s Unpacking gcc-14-base:s390x (14.2.0-19ubuntu1) over (14.2.0-17ubuntu3) ... 60s Preparing to unpack .../02-libapparmor1_4.1.0~beta5-0ubuntu11_s390x.deb ... 60s Unpacking libapparmor1:s390x (4.1.0~beta5-0ubuntu11) over (4.1.0~beta5-0ubuntu9) ... 60s Preparing to unpack .../03-ubuntu-pro-client-l10n_35_s390x.deb ... 60s Unpacking ubuntu-pro-client-l10n (35) over (34.1.3) ... 60s Preparing to unpack .../04-ubuntu-pro-client_35_s390x.deb ... 60s Unpacking ubuntu-pro-client (35) over (34.1.3) ... 60s Preparing to unpack .../05-apparmor_4.1.0~beta5-0ubuntu11_s390x.deb ... 60s Unpacking apparmor (4.1.0~beta5-0ubuntu11) over (4.1.0~beta5-0ubuntu9) ... 60s Preparing to unpack .../06-libibverbs1_56.0-3ubuntu1_s390x.deb ... 60s Unpacking libibverbs1:s390x (56.0-3ubuntu1) over (55.0-1ubuntu1) ... 60s Preparing to unpack .../07-ibverbs-providers_56.0-3ubuntu1_s390x.deb ... 60s Unpacking ibverbs-providers:s390x (56.0-3ubuntu1) over (55.0-1ubuntu1) ... 60s Preparing to unpack .../08-dpkg-dev_1.22.18ubuntu2_all.deb ... 60s Unpacking dpkg-dev (1.22.18ubuntu2) over (1.22.15ubuntu1) ... 60s Preparing to unpack .../09-libdpkg-perl_1.22.18ubuntu2_all.deb ... 60s Unpacking libdpkg-perl (1.22.18ubuntu2) over (1.22.15ubuntu1) ... 60s Preparing to unpack .../10-fwupd_2.0.6-7_s390x.deb ... 60s Unpacking fwupd (2.0.6-7) over (2.0.6-5) ... 60s Preparing to unpack .../11-libfwupd3_2.0.6-7_s390x.deb ... 60s Unpacking libfwupd3:s390x (2.0.6-7) over (2.0.6-5) ... 60s Preparing to unpack .../12-libxmlb2_0.3.22-1_s390x.deb ... 60s Unpacking libxmlb2:s390x (0.3.22-1) over (0.3.21-1) ... 60s Preparing to unpack .../13-libldap-common_2.6.9+dfsg-2ubuntu1_all.deb ... 60s Unpacking libldap-common (2.6.9+dfsg-2ubuntu1) over (2.6.9+dfsg-1~exp2ubuntu1) ... 60s Preparing to unpack .../14-libldap2_2.6.9+dfsg-2ubuntu1_s390x.deb ... 60s Unpacking libldap2:s390x (2.6.9+dfsg-2ubuntu1) over (2.6.9+dfsg-1~exp2ubuntu1) ... 60s Setting up libibverbs1:s390x (56.0-3ubuntu1) ... 60s Setting up libapparmor1:s390x (4.1.0~beta5-0ubuntu11) ... 60s Setting up apt-utils (2.9.34) ... 60s Setting up ibverbs-providers:s390x (56.0-3ubuntu1) ... 60s Setting up gcc-14-base:s390x (14.2.0-19ubuntu1) ... 60s Setting up libxmlb2:s390x (0.3.22-1) ... 60s Setting up libfwupd3:s390x (2.0.6-7) ... 60s Setting up libldap-common (2.6.9+dfsg-2ubuntu1) ... 60s Setting up apparmor (4.1.0~beta5-0ubuntu11) ... 61s Reloading AppArmor profiles 63s Setting up libdpkg-perl (1.22.18ubuntu2) ... 63s Setting up ubuntu-pro-client (35) ... 63s Installing new version of config file /etc/apparmor.d/ubuntu_pro_apt_news ... 63s Installing new version of config file /etc/apt/apt.conf.d/20apt-esm-hook.conf ... 64s Setting up libldap2:s390x (2.6.9+dfsg-2ubuntu1) ... 64s Setting up fwupd (2.0.6-7) ... 64s fwupd-refresh.service is a disabled or a static unit not running, not starting it. 64s fwupd.service is a disabled or a static unit not running, not starting it. 64s Setting up ubuntu-pro-client-l10n (35) ... 64s Setting up dpkg-dev (1.22.18ubuntu2) ... 64s Processing triggers for libc-bin (2.41-1ubuntu2) ... 64s Processing triggers for systemd (257.4-1ubuntu1) ... 64s Processing triggers for man-db (2.13.0-1) ... 66s Processing triggers for dbus (1.16.2-2ubuntu1) ... 66s Reading package lists... 67s Building dependency tree... 67s Reading state information... 67s Starting pkgProblemResolver with broken count: 0 67s Starting 2 pkgProblemResolver with broken count: 0 67s Done 67s Solving dependencies... 67s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 67s autopkgtest [13:12:35]: rebooting testbed after setup commands that affected boot 86s autopkgtest [13:12:54]: testbed running kernel: Linux 6.14.0-11-generic #11-Ubuntu SMP Mon Mar 17 11:24:17 UTC 2025 88s autopkgtest [13:12:56]: @@@@@@@@@@@@@@@@@@@@ apt-source guile-fibers 90s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (dsc) [2405 B] 90s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (tar) [581 kB] 90s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (diff) [4516 B] 90s gpgv: Signature made Thu Feb 20 07:20:23 2025 UTC 90s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 90s gpgv: issuer "simon@josefsson.org" 90s gpgv: Can't check signature: No public key 90s dpkg-source: warning: cannot verify inline signature for ./guile-fibers_1.3.1-4.dsc: no acceptable signature found 90s autopkgtest [13:12:58]: testing package guile-fibers version 1.3.1-4 91s autopkgtest [13:12:59]: build not needed 93s autopkgtest [13:13:01]: test guile-tests-basic: preparing testbed 94s Reading package lists... 94s Building dependency tree... 94s Reading state information... 94s Starting pkgProblemResolver with broken count: 0 94s Starting 2 pkgProblemResolver with broken count: 0 94s Done 94s The following NEW packages will be installed: 94s guile-3.0 guile-3.0-libs guile-fibers libgc1 94s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 94s Need to get 8331 kB of archives. 94s After this operation, 59.6 MB of additional disk space will be used. 94s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libgc1 s390x 1:8.2.8-1 [93.7 kB] 95s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x guile-3.0-libs s390x 3.0.10+really3.0.10-4 [7889 kB] 95s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x guile-3.0 s390x 3.0.10+really3.0.10-4 [16.4 kB] 95s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x guile-fibers s390x 1.3.1-4 [332 kB] 96s Fetched 8331 kB in 1s (6230 kB/s) 96s Selecting previously unselected package libgc1:s390x. 96s (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 ... 81447 files and directories currently installed.) 96s Preparing to unpack .../libgc1_1%3a8.2.8-1_s390x.deb ... 96s Unpacking libgc1:s390x (1:8.2.8-1) ... 96s Selecting previously unselected package guile-3.0-libs:s390x. 96s Preparing to unpack .../guile-3.0-libs_3.0.10+really3.0.10-4_s390x.deb ... 96s Unpacking guile-3.0-libs:s390x (3.0.10+really3.0.10-4) ... 96s Selecting previously unselected package guile-3.0. 96s Preparing to unpack .../guile-3.0_3.0.10+really3.0.10-4_s390x.deb ... 96s Unpacking guile-3.0 (3.0.10+really3.0.10-4) ... 96s Selecting previously unselected package guile-fibers. 96s Preparing to unpack .../guile-fibers_1.3.1-4_s390x.deb ... 96s Unpacking guile-fibers (1.3.1-4) ... 96s Setting up libgc1:s390x (1:8.2.8-1) ... 96s Setting up guile-3.0-libs:s390x (3.0.10+really3.0.10-4) ... 96s Setting up guile-3.0 (3.0.10+really3.0.10-4) ... 96s update-alternatives: using /usr/lib/s390x-linux-gnu/guile/3.0/bin/guile to provide /usr/bin/guile (guile) in auto mode 96s Setting up guile-fibers (1.3.1-4) ... 96s Processing triggers for libc-bin (2.41-1ubuntu2) ... 96s Processing triggers for man-db (2.13.0-1) ... 96s Processing triggers for install-info (7.1.1-1) ... 98s autopkgtest [13:13:06]: test guile-tests-basic: guile tests/basic.scm 98s autopkgtest [13:13:06]: test guile-tests-basic: [----------------------- 100s assert #f equal to #f: ok 100s assert #t terminates: ok 100s assert (false-if-exception (begin (run-fibers) #t)) equal to #f: ok 101s assert terminates: (run-fibers (lambda () (sleep 1)) #:drain? #t): ok (1.000863115 s) 101s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (3.43375e-4 s) 101s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (1.90449e-4 s) 101s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (3.13111e-4 s) 101s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.001197501 s) 101s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.010147224 s) 101s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.174467879 s) 101s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t))) #:drain? #t): ok (0.114024046 s) 106s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4))) #:drain? #t): ok (5.20192332 s) 109s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4 #:parallel? #t))) #:drain? #t): ok (2.784280205 s) 110s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.00112499 s) 111s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001124828 s) 112s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001448083 s) 199s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.087540958 s) 200s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.037954896 s) 201s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.372662863 s) 208s assert terminates: (run-fibers (lambda () (do-times 1000000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (6.91509093 s) 209s assert terminates: (run-fibers (lambda () (spawn-fiber-tree 7 (lambda () (sleep 1)))) #:drain? #t): ok (1.029209828 s) 209s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5)) #:drain? #t): ok (3.33284e-4 s) 209s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50)) #:drain? #t): ok (4.51142e-4 s) 209s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500)) #:drain? #t): ok (0.002418591 s) 209s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000)) #:drain? #t): ok (0.021628249 s) 209s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50000)) #:drain? #t): ok (0.212871645 s) 212s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500000)) #:drain? #t): ok (2.174007356 s) 232s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000000)) #:drain? #t): ok (20.138471889 s) 232s assert terminates: (run-fibers (lambda () (test-run-order 10)) #:parallelism 1 #:drain? #t): ok (0.008455067 s) 232s assert terminates: (run-fibers (lambda () (test-wakeup-order 10)) #:parallelism 1 #:drain? #t): ok (1.43226e-4 s) 232s assert terminates: (run-fibers (lambda () 1) #:drain? #t): ok (1.84389e-4 s) 232s assert run-fiber-return-vals equal to (1): ok 233s assert terminates: (run-fibers (lambda () (do-times 20 (check-sleep (random 1.0)))) #:drain? #t): assert sleep 0.020608996598312723s < actual 0.020642382s: #t (diff: 0.16199430927176164%) 233s assert sleep 0.03456686196908243s < actual 0.034773602s: #t (diff: 0.5980873563312894%) 233s assert sleep 0.061024566720115414s < actual 0.061531635s: #t (diff: 0.8309248342724295%) 233s assert sleep 0.11746097407324547s < actual 0.117670075s: #t (diff: 0.1780173614294518%) 233s assert sleep 0.13706411233780896s < actual 0.137582674s: #t (diff: 0.378335111464462%) 233s assert sleep 0.2275100133355504s < actual 0.22778786s: #t (diff: 0.12212502666412914%) 233s assert sleep 0.23514019199674957s < actual 0.235621564s: #t (diff: 0.20471702398588226%) 233s assert sleep 0.3507339219939192s < actual 0.350938534s: #t (diff: 0.0583382425394252%) 233s assert sleep 0.36970041583284435s < actual 0.369823998s: #t (diff: 0.03342765165065702%) 233s assert sleep 0.5002849968690339s < actual 0.500395718s: #t (diff: 0.022131611313359462%) 233s assert sleep 0.6727431540500131s < actual 0.672867504s: #t (diff: 0.018484015666051075%) 233s assert sleep 0.7000185262935479s < actual 0.700134305s: #t (diff: 0.016539377474064643%) 233s assert sleep 0.7148624304124437s < actual 0.715308378s: #t (diff: 0.062382294632415805%) 233s assert sleep 0.721997240644088s < actual 0.722137714s: #t (diff: 0.01945621783633643%) 233s assert sleep 0.7233843607837082s < actual 0.723681511s: #t (diff: 0.0410777772372507%) 233s assert sleep 0.7603254888022394s < actual 0.76041724s: #t (diff: 0.012067357876578954%) 233s assert sleep 0.7965046199252751s < actual 0.796581122s: #t (diff: 0.009604724544102251%) 233s assert sleep 0.8067622687831747s < actual 0.807069672s: #t (diff: 0.0381033209806614%) 233s assert sleep 0.9299942644443119s < actual 0.930278502s: #t (diff: 0.030563366523331047%) 233s assert sleep 0.999598330708636s < actual 1.000022519s: #t (diff: 0.04243587432397172%) 233s ok (1.000490606 s) 233s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 233s ;;; or pass the --no-auto-compile argument to disable. 233s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/basic.scm 233s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 233s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/basic.scm.go 233s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 233s autopkgtest [13:15:21]: test guile-tests-basic: -----------------------] 234s autopkgtest [13:15:22]: test guile-tests-basic: - - - - - - - - - - results - - - - - - - - - - 234s guile-tests-basic PASS 234s autopkgtest [13:15:22]: test guile-tests-conditions: preparing testbed 234s Reading package lists... 234s Building dependency tree... 234s Reading state information... 234s Starting pkgProblemResolver with broken count: 0 234s Starting 2 pkgProblemResolver with broken count: 0 234s Done 235s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 235s autopkgtest [13:15:23]: test guile-tests-conditions: guile tests/conditions.scm 235s autopkgtest [13:15:23]: test guile-tests-conditions: [----------------------- 236s assert (condition? cv) equal to #t: ok 236s assert run-fibers on (wait/timeout cv) terminates: ok (0.050582424 s) 236s assert run-fiber-return-vals equal to (#f): ok 236s assert run-fibers on (wait/timeout cv) terminates: ok (0.050768159 s) 236s assert run-fiber-return-vals equal to (#f): ok 236s assert (signal-condition! cv) equal to #t: ok 236s assert (signal-condition! cv) equal to #f: ok 236s assert run-fibers on (wait/timeout cv) terminates: ok (2.42467e-4 s) 236s assert run-fiber-return-vals equal to (#t): ok 236s assert run-fibers on (wait/timeout cv) terminates: ok (1.72138e-4 s) 236s assert run-fiber-return-vals equal to (#t): ok 236s assert run-fibers on (let ((cv (make-condition))) (spawn-fiber (lambda () (signal-condition! cv))) (wait cv) #t) terminates: ok (2.03092e-4 s) 236s assert run-fiber-return-vals equal to (#t): ok 236s assert (let ((cv (make-condition))) (run-fibers (lambda () (spawn-fiber (lambda () (wait cv))) (yield-current-task)) #:drain? #f #:hz 0 #:parallelism 1) (signal-condition! cv)) equal to #t: ok 236s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 236s ;;; or pass the --no-auto-compile argument to disable. 236s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/conditions.scm 236s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/conditions.scm.go 237s autopkgtest [13:15:25]: test guile-tests-conditions: -----------------------] 237s autopkgtest [13:15:25]: test guile-tests-conditions: - - - - - - - - - - results - - - - - - - - - - 237s guile-tests-conditions PASS 237s autopkgtest [13:15:25]: test guile-tests-channels: preparing testbed 238s Reading package lists... 238s Building dependency tree... 238s Reading state information... 238s Starting pkgProblemResolver with broken count: 0 238s Starting 2 pkgProblemResolver with broken count: 0 238s Done 238s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 239s autopkgtest [13:15:27]: test guile-tests-channels: guile tests/channels.scm 239s autopkgtest [13:15:27]: test guile-tests-channels: [----------------------- 240s assert run-fibers on (rpc 1) terminates: ok (7.44158e-4 s) 240s assert run-fiber-return-vals equal to (1): ok 242s assert run-fibers on (rpc-fib 24) terminates: ok (1.836632256 s) 242s assert run-fiber-return-vals equal to (75025): ok 242s assert run-fibers on (pingpong (current-processor-count) 1000) terminates: ok (0.025972654 s) 242s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 242s ;;; or pass the --no-auto-compile argument to disable. 242s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/channels.scm 242s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/channels.scm.go 242s autopkgtest [13:15:30]: test guile-tests-channels: -----------------------] 242s autopkgtest [13:15:30]: test guile-tests-channels: - - - - - - - - - - results - - - - - - - - - - 242s guile-tests-channels PASS 243s autopkgtest [13:15:31]: test guile-tests-foreign: preparing testbed 243s Reading package lists... 243s Building dependency tree... 243s Reading state information... 243s Starting pkgProblemResolver with broken count: 0 243s Starting 2 pkgProblemResolver with broken count: 0 243s Done 243s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 244s autopkgtest [13:15:32]: test guile-tests-foreign: guile tests/foreign.scm 244s autopkgtest [13:15:32]: test guile-tests-foreign: [----------------------- 245s assert #f equal to #f: ok 245s assert #t terminates: ok 246s assert (sleep 1) terminates: ok 247s assert (perform-operation (sleep-operation 1)) terminates: ok 247s assert (receive-from-fiber 42) equal to 42: ok 247s assert (send-to-fiber 42) equal to 42: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 247s ;;; or pass the --no-auto-compile argument to disable. 247s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/foreign.scm 247s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 247s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/foreign.scm.go 247s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 247s ok 247s autopkgtest [13:15:35]: test guile-tests-foreign: -----------------------] 248s autopkgtest [13:15:36]: test guile-tests-foreign: - - - - - - - - - - results - - - - - - - - - - 248s guile-tests-foreign PASS 248s autopkgtest [13:15:36]: test guile-tests-io-wakeup: preparing testbed 248s Reading package lists... 248s Building dependency tree... 248s Reading state information... 249s Starting pkgProblemResolver with broken count: 0 249s Starting 2 pkgProblemResolver with broken count: 0 249s Done 249s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 249s autopkgtest [13:15:37]: test guile-tests-io-wakeup: guile tests/io-wakeup.scm 249s autopkgtest [13:15:37]: test guile-tests-io-wakeup: [----------------------- 251s assert run-fibers on (readable/timeout? s) terminates: ok (0.050770954 s) 251s assert run-fiber-return-vals equal to (#t): ok 251s assert (readable/timeout? s) equal to #t: ok 251s assert run-fibers on (readable/timeout? A) terminates: ok (0.050321467 s) 251s assert run-fiber-return-vals equal to (#t): ok 251s assert (readable/timeout? A) equal to #t: ok 251s assert run-fibers on (writable/timeout? B) terminates: ok (2.8267e-4 s) 251s assert run-fiber-return-vals equal to (#f): ok 251s assert (writable/timeout? B) equal to #f: ok 251s assert run-fibers on (writable/timeout? B) terminates: ok (0.050422282 s) 251s assert run-fiber-return-vals equal to (#t): ok 251s assert run-fibers on (readable/timeout? A) terminates: ok (2.86721e-4 s) 251s assert run-fiber-return-vals equal to (#f): ok 251s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 251s ;;; or pass the --no-auto-compile argument to disable. 251s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/io-wakeup.scm 251s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/io-wakeup.scm.go 251s autopkgtest [13:15:39]: test guile-tests-io-wakeup: -----------------------] 252s autopkgtest [13:15:40]: test guile-tests-io-wakeup: - - - - - - - - - - results - - - - - - - - - - 252s guile-tests-io-wakeup PASS 252s autopkgtest [13:15:40]: test guile-tests-parameters: preparing testbed 252s Reading package lists... 252s Building dependency tree... 252s Reading state information... 252s Starting pkgProblemResolver with broken count: 0 252s Starting 2 pkgProblemResolver with broken count: 0 252s Done 253s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 253s autopkgtest [13:15:41]: test guile-tests-parameters: guile tests/parameters.scm 253s autopkgtest [13:15:41]: test guile-tests-parameters: [----------------------- 254s assert run-fibers on (my-param) terminates: ok (6.57255e-4 s) 254s assert run-fiber-return-vals equal to (#f): ok 254s assert run-fibers on (rpc (my-param)) terminates: ok (3.31279e-4 s) 254s assert run-fiber-return-vals equal to (#f): ok 254s assert run-fibers on (rpc (begin (my-param 42) (my-param))) terminates: ok (2.3539e-4 s) 254s assert run-fiber-return-vals equal to (42): ok 254s assert run-fibers on (my-param) terminates: ok (1.96485e-4 s) 254s assert run-fiber-return-vals equal to (#f): ok 254s assert run-fibers on (begin (my-param 100) (rpc (my-param))) terminates: ok (2.08895e-4 s) 254s assert run-fiber-return-vals equal to (100): ok 254s assert run-fibers on (my-param) terminates: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 254s ;;; or pass the --no-auto-compile argument to disable. 254s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/parameters.scm 254s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/parameters.scm.go 254s ok (1.71038e-4 s) 254s assert run-fiber-return-vals equal to (#f): ok 254s assert (my-param) equal to #f: ok 254s assert (begin (my-param (quote foo)) (my-param)) equal to foo: ok 254s assert run-fibers on (my-param) terminates: ok (2.05456e-4 s) 254s assert run-fiber-return-vals equal to (foo): ok 254s assert run-fibers on (rpc (my-param)) terminates: ok (1.97176e-4 s) 254s assert run-fiber-return-vals equal to (foo): ok 255s autopkgtest [13:15:43]: test guile-tests-parameters: -----------------------] 255s guile-tests-parameters PASS 255s autopkgtest [13:15:43]: test guile-tests-parameters: - - - - - - - - - - results - - - - - - - - - - 256s autopkgtest [13:15:44]: test guile-tests-preemption: preparing testbed 256s Reading package lists... 256s Building dependency tree... 256s Reading state information... 256s Starting pkgProblemResolver with broken count: 0 256s Starting 2 pkgProblemResolver with broken count: 0 256s Done 256s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 257s autopkgtest [13:15:45]: test guile-tests-preemption: guile tests/preemption.scm 257s autopkgtest [13:15:45]: test guile-tests-preemption: [----------------------- 258s assert run-fibers on (let lp ((n 0)) (when (< n 100000000) (lp (#{1+}# n)))) terminates: ok (0.473576062 s) 261s assert run-fibers on (race-until 100) terminates: ok (2.987243358 s) 261s assert run-fiber-return-vals equal to (100): ok 261s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 261s ;;; or pass the --no-auto-compile argument to disable. 261s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/preemption.scm 261s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/preemption.scm.go 261s autopkgtest [13:15:49]: test guile-tests-preemption: -----------------------] 262s autopkgtest [13:15:50]: test guile-tests-preemption: - - - - - - - - - - results - - - - - - - - - - 262s guile-tests-preemption PASS 262s autopkgtest [13:15:50]: test guile-tests-speedup: preparing testbed 263s Reading package lists... 263s Building dependency tree... 263s Reading state information... 263s Starting pkgProblemResolver with broken count: 0 263s Starting 2 pkgProblemResolver with broken count: 0 263s Done 263s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 264s autopkgtest [13:15:52]: test guile-tests-speedup: guile tests/speedup.scm 264s autopkgtest [13:15:52]: test guile-tests-speedup: [----------------------- 265s speedup for (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t)): 0.161163616 s / 0.127729976 s = 1.2617524957493143x (2 cpus) 267s speedup for (do-times 40000 (spawn-fiber (lambda () (sleep 1)) #:parallel? #t)): 1.173753429 s / 1.10475864 s = 1.06245236425578x (2 cpus) 281s speedup for (do-times 100000 (spawn-fiber (lambda () (loop-to 10000)) #:parallel? #t)): 8.974304365 s / 4.417694715 s = 2.031445118769372x (2 cpus) 293s speedup for (do-times 10000 (spawn-fiber (lambda () (loop-to 100000)) #:parallel? #t)): 8.46059251 s / 4.328635449 s = 1.9545634206628704x (2 cpus) 306s speedup for (do-times 1000 (spawn-fiber (lambda () (loop-to 1000000)) #:parallel? #t)): 8.537571738 s / 4.251730468 s = 2.008022804422042x (2 cpus) 317s speedup for (do-times 100000 (spawn-fiber (lambda () (alloc-to 4 1000)) #:parallel? #t)): 6.349861786 s / 3.956865813 s = 1.604770564909728x (2 cpus) 326s speedup for (do-times 10000 (spawn-fiber (lambda () (alloc-to 4 10000)) #:parallel? #t)): 5.846132155 s / 3.622422139 s = 1.6138737923608963x (2 cpus) 335s speedup for (do-times 1000 (spawn-fiber (lambda () (alloc-to 4 100000)) #:parallel? #t)): 5.440327885 s / 3.35054718 s = 1.6237132601726265x (2 cpus) 335s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 335s ;;; or pass the --no-auto-compile argument to disable. 335s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/speedup.scm 335s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 335s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/speedup.scm.go 335s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 335s autopkgtest [13:17:03]: test guile-tests-speedup: -----------------------] 336s guile-tests-speedup PASS 336s autopkgtest [13:17:04]: test guile-tests-speedup: - - - - - - - - - - results - - - - - - - - - - 336s autopkgtest [13:17:04]: test guile-tests-timer-wheel: preparing testbed 336s Reading package lists... 336s Building dependency tree... 336s Reading state information... 336s Starting pkgProblemResolver with broken count: 0 336s Starting 2 pkgProblemResolver with broken count: 0 336s Done 337s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 337s autopkgtest [13:17:05]: test guile-tests-timer-wheel: guile tests/timer-wheel.scm 337s autopkgtest [13:17:05]: test guile-tests-timer-wheel: [----------------------- 339s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 339s ;;; or pass the --no-auto-compile argument to disable. 339s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/timer-wheel.scm 339s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/timer-wheel.scm.go 340s autopkgtest [13:17:08]: test guile-tests-timer-wheel: -----------------------] 340s guile-tests-timer-wheel PASS 340s autopkgtest [13:17:08]: test guile-tests-timer-wheel: - - - - - - - - - - results - - - - - - - - - - 340s autopkgtest [13:17:08]: test guile-tests-ports: preparing testbed 341s Reading package lists... 341s Building dependency tree... 341s Reading state information... 341s Starting pkgProblemResolver with broken count: 0 341s Starting 2 pkgProblemResolver with broken count: 0 341s Done 341s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 342s autopkgtest [13:17:10]: test guile-tests-ports: guile tests/ports.scm 342s autopkgtest [13:17:10]: test guile-tests-ports: [----------------------- 347s 347s ;;; (listening-socket #) 347s 347s ;;; (listening-socket #) 347s 347s ;;; (listening-socket #) 347s 347s ;;; (listening-socket #) 347s 347s ;;; (listening-socket #) 347s 347s ;;; (listening-socket #) 347s 347s ;;; (accepted-connection (# . #(2 2130706433 43620))) 347s 347s ;;; (connected #(2 2130706433 5556)) 347s 347s ;;; (received "hello!") 347s 347s ;;; (echo "hello!") 347s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 347s ;;; or pass the --no-auto-compile argument to disable. 347s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/ports.scm 347s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 347s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/ports.scm.go 347s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 347s success 348s autopkgtest [13:17:16]: test guile-tests-ports: -----------------------] 348s autopkgtest [13:17:16]: test guile-tests-ports: - - - - - - - - - - results - - - - - - - - - - 348s guile-tests-ports PASS 348s autopkgtest [13:17:16]: test guile-tests-concurrent-web-server: preparing testbed 349s Reading package lists... 349s Building dependency tree... 349s Reading state information... 349s Starting pkgProblemResolver with broken count: 0 349s Starting 2 pkgProblemResolver with broken count: 0 349s Done 349s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 350s autopkgtest [13:17:18]: test guile-tests-concurrent-web-server: guile tests/concurrent-web-server.scm 350s autopkgtest [13:17:18]: test guile-tests-concurrent-web-server: [----------------------- 356s assert (length data) equal to 10000: no (got 890) 356s assert (length data) equal to 10000: no (got 896) 356s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 356s ;;; or pass the --no-auto-compile argument to disable. 356s ;;; compiling /tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/concurrent-web-server.scm 356s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.9pmkHP/build.Qwc/src/tests/concurrent-web-server.scm.go 356s autopkgtest [13:17:24]: test guile-tests-concurrent-web-server: -----------------------] 356s guile-tests-concurrent-web-server FAIL non-zero exit status 1 356s autopkgtest [13:17:24]: test guile-tests-concurrent-web-server: - - - - - - - - - - results - - - - - - - - - - 357s autopkgtest [13:17:25]: @@@@@@@@@@@@@@@@@@@@ summary 357s guile-tests-basic PASS 357s guile-tests-conditions PASS 357s guile-tests-channels PASS 357s guile-tests-foreign PASS 357s guile-tests-io-wakeup PASS 357s guile-tests-parameters PASS 357s guile-tests-preemption PASS 357s guile-tests-speedup PASS 357s guile-tests-timer-wheel PASS 357s guile-tests-ports PASS 357s guile-tests-concurrent-web-server FAIL non-zero exit status 1 374s nova [W] Using flock in prodstack6-s390x 374s Creating nova instance adt-plucky-s390x-guile-fibers-20250322-131128-juju-7f2275-prod-proposed-migration-environment-2-5ad89fbf-40cf-4d7a-aa30-37c7fb678436 from image adt/ubuntu-plucky-s390x-server-20250321.img (UUID 6ae96d8b-e719-4c1b-8809-3b6533761d24)... 374s nova [W] Timed out waiting for 9e542afe-edc6-4c52-9691-7114df85cc71 to get deleted.