0s autopkgtest [13:06:14]: starting date and time: 2025-03-22 13:06:14+0000 0s autopkgtest [13:06:14]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [13:06:14]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.px6umclm/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-12.secgroup --name adt-plucky-arm64-guile-fibers-20250322-130614-juju-7f2275-prod-proposed-migration-environment-2-aa5a4a2e-e691-4a27-b023-2262196e97ef --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 163s autopkgtest [13:08:57]: testbed dpkg architecture: arm64 164s autopkgtest [13:08:58]: testbed apt version: 2.9.33 164s autopkgtest [13:08:58]: @@@@@@@@@@@@@@@@@@@@ test bed setup 164s autopkgtest [13:08:58]: testbed release detected to be: None 165s autopkgtest [13:08:59]: updating testbed package index (apt update) 165s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 166s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 166s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 166s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 166s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [1232 B] 166s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [11.5 kB] 166s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [29.9 kB] 166s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [288 kB] 166s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [38.0 kB] 166s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 c-n-f Metadata [1292 B] 166s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 c-n-f Metadata [116 B] 166s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [192 kB] 166s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 c-n-f Metadata [11.1 kB] 166s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [3244 B] 166s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 c-n-f Metadata [316 B] 168s Fetched 703 kB in 1s (739 kB/s) 168s Reading package lists... 169s autopkgtest [13:09:03]: upgrading testbed (apt dist-upgrade and autopurge) 169s Reading package lists... 170s Building dependency tree... 170s 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 173s 173s Calculating upgrade... 173s The following packages will be upgraded: 173s apparmor apt apt-utils dpkg dpkg-dev flash-kernel fwupd gcc-14-base 173s ibverbs-providers libapparmor1 libapt-pkg7.0 libdpkg-perl libfwupd3 173s libibverbs1 174s 14 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 174s Need to get 12.0 MB of archives. 174s After this operation, 90.1 kB of additional disk space will be used. 174s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libapt-pkg7.0 arm64 2.9.34 [1030 kB] 175s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 dpkg arm64 1.22.18ubuntu2 [1291 kB] 176s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 apt arm64 2.9.34 [1368 kB] 177s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 apt-utils arm64 2.9.34 [207 kB] 177s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 gcc-14-base arm64 14.2.0-19ubuntu1 [54.0 kB] 177s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 libapparmor1 arm64 4.1.0~beta5-0ubuntu11 [54.4 kB] 177s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 apparmor arm64 4.1.0~beta5-0ubuntu11 [662 kB] 177s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 libibverbs1 arm64 56.0-3ubuntu1 [67.9 kB] 177s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 ibverbs-providers arm64 56.0-3ubuntu1 [388 kB] 177s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 dpkg-dev all 1.22.18ubuntu2 [1089 kB] 178s Get:11 http://ftpmaster.internal/ubuntu plucky/main arm64 libdpkg-perl all 1.22.18ubuntu2 [281 kB] 178s Get:12 http://ftpmaster.internal/ubuntu plucky/main arm64 flash-kernel arm64 3.108ubuntu2 [49.8 kB] 178s Get:13 http://ftpmaster.internal/ubuntu plucky/main arm64 fwupd arm64 2.0.6-7 [5287 kB] 180s Get:14 http://ftpmaster.internal/ubuntu plucky/main arm64 libfwupd3 arm64 2.0.6-7 [129 kB] 181s Preconfiguring packages ... 181s Fetched 12.0 MB in 6s (1884 kB/s) 181s (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 ... 117729 files and directories currently installed.) 181s Preparing to unpack .../libapt-pkg7.0_2.9.34_arm64.deb ... 181s Unpacking libapt-pkg7.0:arm64 (2.9.34) over (2.9.33) ... 181s Setting up libapt-pkg7.0:arm64 (2.9.34) ... 181s (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 ... 117729 files and directories currently installed.) 181s Preparing to unpack .../dpkg_1.22.18ubuntu2_arm64.deb ... 181s Unpacking dpkg (1.22.18ubuntu2) over (1.22.15ubuntu1) ... 182s Setting up dpkg (1.22.18ubuntu2) ... 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 ... 117728 files and directories currently installed.) 182s Preparing to unpack .../archives/apt_2.9.34_arm64.deb ... 182s Unpacking apt (2.9.34) over (2.9.33) ... 182s Setting up apt (2.9.34) ... 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 ... 117728 files and directories currently installed.) 183s Preparing to unpack .../00-apt-utils_2.9.34_arm64.deb ... 183s Unpacking apt-utils (2.9.34) over (2.9.33) ... 183s Preparing to unpack .../01-gcc-14-base_14.2.0-19ubuntu1_arm64.deb ... 183s Unpacking gcc-14-base:arm64 (14.2.0-19ubuntu1) over (14.2.0-17ubuntu3) ... 183s Preparing to unpack .../02-libapparmor1_4.1.0~beta5-0ubuntu11_arm64.deb ... 183s Unpacking libapparmor1:arm64 (4.1.0~beta5-0ubuntu11) over (4.1.0~beta5-0ubuntu9) ... 183s Preparing to unpack .../03-apparmor_4.1.0~beta5-0ubuntu11_arm64.deb ... 184s Unpacking apparmor (4.1.0~beta5-0ubuntu11) over (4.1.0~beta5-0ubuntu9) ... 184s Preparing to unpack .../04-libibverbs1_56.0-3ubuntu1_arm64.deb ... 184s Unpacking libibverbs1:arm64 (56.0-3ubuntu1) over (55.0-1ubuntu1) ... 184s Preparing to unpack .../05-ibverbs-providers_56.0-3ubuntu1_arm64.deb ... 185s Unpacking ibverbs-providers:arm64 (56.0-3ubuntu1) over (55.0-1ubuntu1) ... 185s Preparing to unpack .../06-dpkg-dev_1.22.18ubuntu2_all.deb ... 185s Unpacking dpkg-dev (1.22.18ubuntu2) over (1.22.15ubuntu1) ... 185s Preparing to unpack .../07-libdpkg-perl_1.22.18ubuntu2_all.deb ... 185s Unpacking libdpkg-perl (1.22.18ubuntu2) over (1.22.15ubuntu1) ... 185s Preparing to unpack .../08-flash-kernel_3.108ubuntu2_arm64.deb ... 185s Unpacking flash-kernel (3.108ubuntu2) over (3.108ubuntu1) ... 185s Preparing to unpack .../09-fwupd_2.0.6-7_arm64.deb ... 185s Unpacking fwupd (2.0.6-7) over (2.0.6-6) ... 185s Preparing to unpack .../10-libfwupd3_2.0.6-7_arm64.deb ... 185s Unpacking libfwupd3:arm64 (2.0.6-7) over (2.0.6-6) ... 185s Setting up libibverbs1:arm64 (56.0-3ubuntu1) ... 185s Setting up libapparmor1:arm64 (4.1.0~beta5-0ubuntu11) ... 185s Setting up apt-utils (2.9.34) ... 185s Setting up flash-kernel (3.108ubuntu2) ... 186s flash-kernel: deferring update (trigger activated) 186s Setting up ibverbs-providers:arm64 (56.0-3ubuntu1) ... 186s Setting up gcc-14-base:arm64 (14.2.0-19ubuntu1) ... 186s Setting up libfwupd3:arm64 (2.0.6-7) ... 186s Setting up apparmor (4.1.0~beta5-0ubuntu11) ... 187s Reloading AppArmor profiles 188s Setting up libdpkg-perl (1.22.18ubuntu2) ... 188s Setting up fwupd (2.0.6-7) ... 189s fwupd-refresh.service is a disabled or a static unit not running, not starting it. 189s fwupd.service is a disabled or a static unit not running, not starting it. 189s Setting up dpkg-dev (1.22.18ubuntu2) ... 189s Processing triggers for initramfs-tools (0.146ubuntu1) ... 189s update-initramfs: Generating /boot/initrd.img-6.14.0-11-generic 189s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 208s System running in EFI mode, skipping. 208s Processing triggers for libc-bin (2.41-1ubuntu2) ... 208s Processing triggers for systemd (257.4-1ubuntu1) ... 208s Processing triggers for man-db (2.13.0-1) ... 210s Processing triggers for dbus (1.16.2-2ubuntu1) ... 210s Processing triggers for flash-kernel (3.108ubuntu2) ... 210s System running in EFI mode, skipping. 211s Reading package lists... 211s Building dependency tree... 211s Reading state information... 212s Starting pkgProblemResolver with broken count: 0 212s Starting 2 pkgProblemResolver with broken count: 0 212s Done 212s Solving dependencies... 213s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 213s autopkgtest [13:09:47]: rebooting testbed after setup commands that affected boot 236s autopkgtest [13:10:10]: testbed running kernel: Linux 6.14.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Mon Mar 17 12:19:09 UTC 2025 239s autopkgtest [13:10:13]: @@@@@@@@@@@@@@@@@@@@ apt-source guile-fibers 241s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (dsc) [2405 B] 241s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (tar) [581 kB] 241s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (diff) [4516 B] 241s gpgv: Signature made Thu Feb 20 07:20:23 2025 UTC 241s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 241s gpgv: issuer "simon@josefsson.org" 241s gpgv: Can't check signature: No public key 241s dpkg-source: warning: cannot verify inline signature for ./guile-fibers_1.3.1-4.dsc: no acceptable signature found 242s autopkgtest [13:10:16]: testing package guile-fibers version 1.3.1-4 242s autopkgtest [13:10:16]: build not needed 244s autopkgtest [13:10:18]: test guile-tests-basic: preparing testbed 244s Reading package lists... 244s Building dependency tree... 244s Reading state information... 244s Starting pkgProblemResolver with broken count: 0 244s Starting 2 pkgProblemResolver with broken count: 0 244s Done 245s The following NEW packages will be installed: 245s guile-3.0 guile-3.0-libs guile-fibers libgc1 245s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 245s Need to get 8329 kB of archives. 245s After this operation, 59.9 MB of additional disk space will be used. 245s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libgc1 arm64 1:8.2.8-1 [90.1 kB] 246s Get:2 http://ftpmaster.internal/ubuntu plucky/universe arm64 guile-3.0-libs arm64 3.0.10+really3.0.10-4 [7891 kB] 248s Get:3 http://ftpmaster.internal/ubuntu plucky/universe arm64 guile-3.0 arm64 3.0.10+really3.0.10-4 [16.4 kB] 248s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 guile-fibers arm64 1.3.1-4 [332 kB] 248s Fetched 8329 kB in 3s (2870 kB/s) 248s Selecting previously unselected package libgc1:arm64. 249s (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 ... 117732 files and directories currently installed.) 249s Preparing to unpack .../libgc1_1%3a8.2.8-1_arm64.deb ... 249s Unpacking libgc1:arm64 (1:8.2.8-1) ... 249s Selecting previously unselected package guile-3.0-libs:arm64. 249s Preparing to unpack .../guile-3.0-libs_3.0.10+really3.0.10-4_arm64.deb ... 249s Unpacking guile-3.0-libs:arm64 (3.0.10+really3.0.10-4) ... 249s Selecting previously unselected package guile-3.0. 249s Preparing to unpack .../guile-3.0_3.0.10+really3.0.10-4_arm64.deb ... 249s Unpacking guile-3.0 (3.0.10+really3.0.10-4) ... 249s Selecting previously unselected package guile-fibers. 249s Preparing to unpack .../guile-fibers_1.3.1-4_arm64.deb ... 249s Unpacking guile-fibers (1.3.1-4) ... 249s Setting up libgc1:arm64 (1:8.2.8-1) ... 249s Setting up guile-3.0-libs:arm64 (3.0.10+really3.0.10-4) ... 249s Setting up guile-3.0 (3.0.10+really3.0.10-4) ... 249s update-alternatives: using /usr/lib/aarch64-linux-gnu/guile/3.0/bin/guile to provide /usr/bin/guile (guile) in auto mode 249s Setting up guile-fibers (1.3.1-4) ... 249s Processing triggers for libc-bin (2.41-1ubuntu2) ... 249s Processing triggers for man-db (2.13.0-1) ... 250s Processing triggers for install-info (7.1.1-1) ... 251s autopkgtest [13:10:25]: test guile-tests-basic: guile tests/basic.scm 251s autopkgtest [13:10:25]: test guile-tests-basic: [----------------------- 252s assert #f equal to #f: ok 252s assert #t terminates: ok 252s assert (false-if-exception (begin (run-fibers) #t)) equal to #f: ok 253s assert terminates: (run-fibers (lambda () (sleep 1)) #:drain? #t): ok (1.0085356 s) 253s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (9.0524e-4 s) 253s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (7.0652e-4 s) 253s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (9.1952e-4 s) 253s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.00176804 s) 253s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.00889232 s) 254s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.13566872 s) 254s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t))) #:drain? #t): ok (0.12589908 s) 256s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4))) #:drain? #t): ok (1.96641892 s) 257s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4 #:parallel? #t))) #:drain? #t): ok (1.0496496 s) 258s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.00119948 s) 259s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.00166548 s) 260s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.0024082 s) 261s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.00348928 s) 262s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.02101584 s) 263s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.357216984 s) 269s assert terminates: (run-fibers (lambda () (do-times 1000000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (5.702453888 s) 270s assert terminates: (run-fibers (lambda () (spawn-fiber-tree 7 (lambda () (sleep 1)))) #:drain? #t): ok (1.024575025 s) 270s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5)) #:drain? #t): ok (7.78605e-4 s) 270s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50)) #:drain? #t): ok (9.02756e-4 s) 270s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500)) #:drain? #t): ok (0.00246343 s) 270s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000)) #:drain? #t): ok (0.015215538 s) 270s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50000)) #:drain? #t): ok (0.143350734 s) 271s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500000)) #:drain? #t): ok (1.383365209 s) 284s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000000)) #:drain? #t): ok (12.454525577 s) 284s assert terminates: (run-fibers (lambda () (test-run-order 10)) #:parallelism 1 #:drain? #t): ok (0.095923014 s) 284s assert terminates: (run-fibers (lambda () (test-wakeup-order 10)) #:parallelism 1 #:drain? #t): ok (5.12908e-4 s) 284s assert terminates: (run-fibers (lambda () 1) #:drain? #t): ok (4.49439e-4 s) 284s assert run-fiber-return-vals equal to (1): ok 285s assert terminates: (run-fibers (lambda () (do-times 20 (check-sleep (random 1.0)))) #:drain? #t): assert sleep 0.020608996598312723s < actual 0.020832622s: #t (diff: 1.0850863147096823%) 285s assert sleep 0.03456686196908243s < actual 0.034970184s: #t (diff: 1.1667880968724156%) 285s assert sleep 0.061024566720115414s < actual 0.061197314s: #t (diff: 0.2830782571171394%) 285s assert sleep 0.11746097407324547s < actual 0.117854274s: #t (diff: 0.33483455237589715%) 285s assert sleep 0.13706411233780896s < actual 0.137260643s: #t (diff: 0.14338593730987292%) 285s assert sleep 0.2275100133355504s < actual 0.227968166s: #t (diff: 0.20137692303409732%) 285s assert sleep 0.23514019199674957s < actual 0.235274457s: #t (diff: 0.05709998027571494%) 285s assert sleep 0.3507339219939192s < actual 0.351132255s: #t (diff: 0.1135712804214407%) 285s assert sleep 0.36970041583284435s < actual 0.370010807s: #t (diff: 0.08395748391475527%) 285s assert sleep 0.5002849968690339s < actual 0.500569071s: #t (diff: 0.0567824605462754%) 285s assert sleep 0.6727431540500131s < actual 0.673054268s: #t (diff: 0.04624557650479237%) 285s assert sleep 0.7000185262935479s < actual 0.700318223s: #t (diff: 0.04281268212128136%) 285s assert sleep 0.7148624304124437s < actual 0.714973124s: #t (diff: 0.015484599951974914%) 285s assert sleep 0.721997240644088s < actual 0.722324115s: #t (diff: 0.045273629525294665%) 285s assert sleep 0.7233843607837082s < actual 0.723876906s: #t (diff: 0.06808900537443326%) 285s assert sleep 0.7603254888022394s < actual 0.760605367s: #t (diff: 0.03681031372518522%) 285s assert sleep 0.7965046199252751s < actual 0.796756038s: #t (diff: 0.031565174693969605%) 285s assert sleep 0.8067622687831747s < actual 0.807257855s: #t (diff: 0.06142902760844427%) 285s assert sleep 0.9299942644443119s < actual 0.930491072s: #t (diff: 0.053420496736611756%) 285s assert sleep 0.999598330708636s < actual 0.999742155s: #t (diff: 0.014388208437882%) 285s ok (1.000418638 s) 285s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 285s ;;; or pass the --no-auto-compile argument to disable. 285s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/basic.scm 285s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 285s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/basic.scm.go 285s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 285s autopkgtest [13:10:59]: test guile-tests-basic: -----------------------] 286s autopkgtest [13:11:00]: test guile-tests-basic: - - - - - - - - - - results - - - - - - - - - - 286s guile-tests-basic PASS 286s autopkgtest [13:11:00]: test guile-tests-conditions: preparing testbed 286s Reading package lists... 287s Building dependency tree... 287s Reading state information... 287s Starting pkgProblemResolver with broken count: 0 287s Starting 2 pkgProblemResolver with broken count: 0 287s Done 288s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 289s autopkgtest [13:11:03]: test guile-tests-conditions: guile tests/conditions.scm 289s autopkgtest [13:11:03]: test guile-tests-conditions: [----------------------- 289s assert (condition? cv) equal to #t: ok 290s assert run-fibers on (wait/timeout cv) terminates: ok (0.05158979 s) 290s assert run-fiber-return-vals equal to (#f): ok 290s assert run-fibers on (wait/timeout cv) terminates: ok (0.05114214 s) 290s assert run-fiber-return-vals equal to (#f): ok 290s assert (signal-condition! cv) equal to #t: ok 290s assert (signal-condition! cv) equal to #f: ok 290s assert run-fibers on (wait/timeout cv) terminates: ok (6.9335e-4 s) 290s assert run-fiber-return-vals equal to (#t): ok 290s assert run-fibers on (wait/timeout cv) terminates: ok (6.08141e-4 s) 290s assert run-fiber-return-vals equal to (#t): ok 290s assert run-fibers on (let ((cv (make-condition))) (spawn-fiber (lambda () (signal-condition! cv))) (wait cv) #t) terminates: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 290s ;;; or pass the --no-auto-compile argument to disable. 290s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/conditions.scm 290s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/conditions.scm.go 290s ok (5.00419e-4 s) 290s assert run-fiber-return-vals equal to (#t): ok 290s 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 290s autopkgtest [13:11:04]: test guile-tests-conditions: -----------------------] 290s autopkgtest [13:11:04]: test guile-tests-conditions: - - - - - - - - - - results - - - - - - - - - - 290s guile-tests-conditions PASS 291s autopkgtest [13:11:05]: test guile-tests-channels: preparing testbed 291s Reading package lists... 291s Building dependency tree... 291s Reading state information... 292s Starting pkgProblemResolver with broken count: 0 292s Starting 2 pkgProblemResolver with broken count: 0 292s Done 293s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 293s autopkgtest [13:11:07]: test guile-tests-channels: guile tests/channels.scm 293s autopkgtest [13:11:07]: test guile-tests-channels: [----------------------- 294s assert run-fibers on (rpc 1) terminates: ok (0.001585305 s) 294s assert run-fiber-return-vals equal to (1): ok 295s assert run-fibers on (rpc-fib 24) terminates: ok (1.229099131 s) 295s assert run-fiber-return-vals equal to (75025): ok 295s assert run-fibers on (pingpong (current-processor-count) 1000) terminates: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 295s ;;; or pass the --no-auto-compile argument to disable. 295s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/channels.scm 295s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/channels.scm.go 295s ok (0.067988688 s) 296s autopkgtest [13:11:10]: test guile-tests-channels: -----------------------] 296s autopkgtest [13:11:10]: test guile-tests-channels: - - - - - - - - - - results - - - - - - - - - - 296s guile-tests-channels PASS 297s autopkgtest [13:11:11]: test guile-tests-foreign: preparing testbed 297s Reading package lists... 297s Building dependency tree... 297s Reading state information... 298s Starting pkgProblemResolver with broken count: 0 298s Starting 2 pkgProblemResolver with broken count: 0 298s Done 299s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 300s autopkgtest [13:11:14]: test guile-tests-foreign: guile tests/foreign.scm 300s autopkgtest [13:11:14]: test guile-tests-foreign: [----------------------- 300s assert #f equal to #f: ok 300s assert #t terminates: ok 301s assert (sleep 1) terminates: ok 302s assert (perform-operation (sleep-operation 1)) terminates: ok 302s assert (receive-from-fiber 42) equal to 42: ok 302s assert (send-to-fiber 42) equal to 42: ok 302s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 302s ;;; or pass the --no-auto-compile argument to disable. 302s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/foreign.scm 302s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 302s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/foreign.scm.go 302s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 303s autopkgtest [13:11:17]: test guile-tests-foreign: -----------------------] 303s guile-tests-foreign PASS 303s autopkgtest [13:11:17]: test guile-tests-foreign: - - - - - - - - - - results - - - - - - - - - - 304s autopkgtest [13:11:18]: test guile-tests-io-wakeup: preparing testbed 304s Reading package lists... 304s Building dependency tree... 304s Reading state information... 304s Starting pkgProblemResolver with broken count: 0 304s Starting 2 pkgProblemResolver with broken count: 0 304s Done 305s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 306s autopkgtest [13:11:20]: test guile-tests-io-wakeup: guile tests/io-wakeup.scm 306s autopkgtest [13:11:20]: test guile-tests-io-wakeup: [----------------------- 307s assert run-fibers on (readable/timeout? s) terminates: ok (0.051531317 s) 307s assert run-fiber-return-vals equal to (#t): ok 307s assert (readable/timeout? s) equal to #t: ok 307s assert run-fibers on (readable/timeout? A) terminates: ok (0.051590155 s) 307s assert run-fiber-return-vals equal to (#t): ok 307s assert (readable/timeout? A) equal to #t: ok 307s assert run-fibers on (writable/timeout? B) terminates: ok (8.30777e-4 s) 307s assert run-fiber-return-vals equal to (#f): ok 307s assert (writable/timeout? B) equal to #f: ok 307s assert run-fibers on (writable/timeout? B) terminates: ok (0.051102328 s) 307s assert run-fiber-return-vals equal to (#t): ok 307s assert run-fibers on (readable/timeout? A) terminates: ok (8.94735e-4 s) 307s assert run-fiber-return-vals equal to (#f): ok 307s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 307s ;;; or pass the --no-auto-compile argument to disable. 307s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/io-wakeup.scm 307s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/io-wakeup.scm.go 307s autopkgtest [13:11:21]: test guile-tests-io-wakeup: -----------------------] 308s autopkgtest [13:11:22]: test guile-tests-io-wakeup: - - - - - - - - - - results - - - - - - - - - - 308s guile-tests-io-wakeup PASS 308s autopkgtest [13:11:22]: test guile-tests-parameters: preparing testbed 309s Reading package lists... 309s Building dependency tree... 309s Reading state information... 309s Starting pkgProblemResolver with broken count: 0 309s Starting 2 pkgProblemResolver with broken count: 0 309s Done 310s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 311s autopkgtest [13:11:25]: test guile-tests-parameters: guile tests/parameters.scm 311s autopkgtest [13:11:25]: test guile-tests-parameters: [----------------------- 312s assert run-fibers on (my-param) terminates: ok (0.001254893 s) 312s assert run-fiber-return-vals equal to (#f): ok 312s assert run-fibers on (rpc (my-param)) terminates: ok (8.31142e-4 s) 312s assert run-fiber-return-vals equal to (#f): ok 312s assert run-fibers on (rpc (begin (my-param 42) (my-param))) terminates: ok (6.08107e-4 s) 312s assert run-fiber-return-vals equal to (42): ok 312s assert run-fibers on (my-param) terminates: ok (5.56548e-4 s) 312s assert run-fiber-return-vals equal to (#f): ok 312s assert run-fibers on (begin (my-param 100) (rpc (my-param))) terminates: ok (6.69225e-4 s) 312s assert run-fiber-return-vals equal to (100): ok 312s assert run-fibers on (my-param) terminates: ok (5.23309e-4 s) 312s assert run-fiber-return-vals equal to (#f): ok 312s assert (my-param) equal to #f: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 312s ;;; or pass the --no-auto-compile argument to disable. 312s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/parameters.scm 312s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/parameters.scm.go 312s ok 312s assert (begin (my-param (quote foo)) (my-param)) equal to foo: ok 312s assert run-fibers on (my-param) terminates: ok (4.03431e-4 s) 312s assert run-fiber-return-vals equal to (foo): ok 312s assert run-fibers on (rpc (my-param)) terminates: ok (6.40266e-4 s) 312s assert run-fiber-return-vals equal to (foo): ok 312s autopkgtest [13:11:26]: test guile-tests-parameters: -----------------------] 313s autopkgtest [13:11:27]: test guile-tests-parameters: - - - - - - - - - - results - - - - - - - - - - 313s guile-tests-parameters PASS 313s autopkgtest [13:11:27]: test guile-tests-preemption: preparing testbed 313s Reading package lists... 313s Building dependency tree... 313s Reading state information... 314s Starting pkgProblemResolver with broken count: 0 314s Starting 2 pkgProblemResolver with broken count: 0 314s Done 315s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 315s autopkgtest [13:11:29]: test guile-tests-preemption: guile tests/preemption.scm 315s autopkgtest [13:11:29]: test guile-tests-preemption: [----------------------- 316s assert run-fibers on (let lp ((n 0)) (when (< n 100000000) (lp (#{1+}# n)))) terminates: ok (0.187693748 s) 317s assert run-fibers on (race-until 100) terminates: ok (0.595057406 s) 317s assert run-fiber-return-vals equal to (100): ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 317s ;;; or pass the --no-auto-compile argument to disable. 317s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/preemption.scm 317s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/preemption.scm.go 317s ok 317s autopkgtest [13:11:31]: test guile-tests-preemption: -----------------------] 318s autopkgtest [13:11:32]: test guile-tests-preemption: - - - - - - - - - - results - - - - - - - - - - 318s guile-tests-preemption PASS 318s autopkgtest [13:11:32]: test guile-tests-speedup: preparing testbed 318s Reading package lists... 318s Building dependency tree... 318s Reading state information... 319s Starting pkgProblemResolver with broken count: 0 319s Starting 2 pkgProblemResolver with broken count: 0 319s Done 320s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 320s autopkgtest [13:11:34]: test guile-tests-speedup: guile tests/speedup.scm 320s autopkgtest [13:11:34]: test guile-tests-speedup: [----------------------- 321s speedup for (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t)): 0.153989452 s / 0.129858527 s = 1.1858247244711162x (2 cpus) 324s speedup for (do-times 40000 (spawn-fiber (lambda () (sleep 1)) #:parallel? #t)): 1.103121789 s / 1.085554815 s = 1.0161824845298115x (2 cpus) 327s speedup for (do-times 100000 (spawn-fiber (lambda () (loop-to 10000)) #:parallel? #t)): 2.189498979 s / 1.139039287 s = 1.9222330642928915x (2 cpus) 330s speedup for (do-times 10000 (spawn-fiber (lambda () (loop-to 100000)) #:parallel? #t)): 2.122283832 s / 1.059269755 s = 2.0035348144156155x (2 cpus) 333s speedup for (do-times 1000 (spawn-fiber (lambda () (loop-to 1000000)) #:parallel? #t)): 2.100554557 s / 1.048623314 s = 2.003154544587972x (2 cpus) 340s speedup for (do-times 100000 (spawn-fiber (lambda () (alloc-to 4 1000)) #:parallel? #t)): 3.899432206 s / 2.879456673 s = 1.3542249975712692x (2 cpus) 346s speedup for (do-times 10000 (spawn-fiber (lambda () (alloc-to 4 10000)) #:parallel? #t)): 3.480704846 s / 2.598851036 s = 1.3393244929333457x (2 cpus) 352s speedup for (do-times 1000 (spawn-fiber (lambda () (alloc-to 4 100000)) #:parallel? #t)): 3.428535102 s / 2.519122051 s = 1.3610039659011346x (2 cpus) 352s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 352s ;;; or pass the --no-auto-compile argument to disable. 352s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/speedup.scm 352s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 352s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/speedup.scm.go 352s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 352s autopkgtest [13:12:06]: test guile-tests-speedup: -----------------------] 353s guile-tests-speedup PASS 353s autopkgtest [13:12:07]: test guile-tests-speedup: - - - - - - - - - - results - - - - - - - - - - 353s autopkgtest [13:12:07]: test guile-tests-timer-wheel: preparing testbed 354s Reading package lists... 354s Building dependency tree... 354s Reading state information... 354s Starting pkgProblemResolver with broken count: 0 354s Starting 2 pkgProblemResolver with broken count: 0 354s Done 355s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 356s autopkgtest [13:12:10]: test guile-tests-timer-wheel: guile tests/timer-wheel.scm 356s autopkgtest [13:12:10]: test guile-tests-timer-wheel: [----------------------- 357s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 357s ;;; or pass the --no-auto-compile argument to disable. 357s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/timer-wheel.scm 357s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/timer-wheel.scm.go 357s autopkgtest [13:12:11]: test guile-tests-timer-wheel: -----------------------] 358s autopkgtest [13:12:12]: test guile-tests-timer-wheel: - - - - - - - - - - results - - - - - - - - - - 358s guile-tests-timer-wheel PASS 358s autopkgtest [13:12:12]: test guile-tests-ports: preparing testbed 358s Reading package lists... 358s Building dependency tree... 358s Reading state information... 359s Starting pkgProblemResolver with broken count: 0 359s Starting 2 pkgProblemResolver with broken count: 0 359s Done 360s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 361s autopkgtest [13:12:15]: test guile-tests-ports: guile tests/ports.scm 361s autopkgtest [13:12:15]: test guile-tests-ports: [----------------------- 366s 366s ;;; (listening-socket #) 366s 366s ;;; (listening-socket #) 366s 366s ;;; (listening-socket #) 366s 366s ;;; (listening-socket #) 366s 366s ;;; (listening-socket #) 366s 366s ;;; (listening-socket #) 366s 366s ;;; (accepted-connection (# . #(2 2130706433 60276))) 366s 366s ;;; (connected #(2 2130706433 5556)) 366s 366s ;;; (received "hello!") 366s 366s ;;; (echo "hello!") 366s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 366s ;;; or pass the --no-auto-compile argument to disable. 366s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/ports.scm 366s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 366s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/ports.scm.go 366s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 366s success 366s autopkgtest [13:12:20]: test guile-tests-ports: -----------------------] 367s guile-tests-ports PASS 367s autopkgtest [13:12:21]: test guile-tests-ports: - - - - - - - - - - results - - - - - - - - - - 367s autopkgtest [13:12:21]: test guile-tests-concurrent-web-server: preparing testbed 368s Reading package lists... 368s Building dependency tree... 368s Reading state information... 368s Starting pkgProblemResolver with broken count: 0 368s Starting 2 pkgProblemResolver with broken count: 0 368s Done 369s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 370s autopkgtest [13:12:24]: test guile-tests-concurrent-web-server: guile tests/concurrent-web-server.scm 370s autopkgtest [13:12:24]: test guile-tests-concurrent-web-server: [----------------------- 376s assert (length data) equal to 10000: no (got 890) 376s assert (length data) equal to 10000: no (got 896) 376s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 376s ;;; or pass the --no-auto-compile argument to disable. 376s ;;; compiling /tmp/autopkgtest.58t513/build.PTV/src/tests/concurrent-web-server.scm 376s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.58t513/build.PTV/src/tests/concurrent-web-server.scm.go 376s autopkgtest [13:12:30]: test guile-tests-concurrent-web-server: -----------------------] 377s guile-tests-concurrent-web-server FAIL non-zero exit status 1 377s autopkgtest [13:12:31]: test guile-tests-concurrent-web-server: - - - - - - - - - - results - - - - - - - - - - 377s autopkgtest [13:12:31]: @@@@@@@@@@@@@@@@@@@@ summary 377s guile-tests-basic PASS 377s guile-tests-conditions PASS 377s guile-tests-channels PASS 377s guile-tests-foreign PASS 377s guile-tests-io-wakeup PASS 377s guile-tests-parameters PASS 377s guile-tests-preemption PASS 377s guile-tests-speedup PASS 377s guile-tests-timer-wheel PASS 377s guile-tests-ports PASS 377s guile-tests-concurrent-web-server FAIL non-zero exit status 1 395s nova [W] Using flock in prodstack6-arm64 395s flock: timeout while waiting to get lock 395s Creating nova instance adt-plucky-arm64-guile-fibers-20250322-130614-juju-7f2275-prod-proposed-migration-environment-2-aa5a4a2e-e691-4a27-b023-2262196e97ef from image adt/ubuntu-plucky-arm64-server-20250321.img (UUID b4313911-f3e3-46a3-9c15-4e718cedb875)... 395s nova [W] Timed out waiting for de2b72be-1ed7-4dd8-89c6-deea52158543 to get deleted.