7s autopkgtest [10:44:06]: starting date and time: 2025-03-13 10:44:06+0000 7s autopkgtest [10:44:10]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 7s autopkgtest [10:44:10]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4ockyo0a/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-20@bos03-arm64-24.secgroup --name adt-plucky-arm64-guile-fibers-20250313-104342-juju-7f2275-prod-proposed-migration-environment-20-b547ea3e-4ba8-482f-ae9c-72edbb06fc5e --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --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/ 328s autopkgtest [10:49:31]: testbed dpkg architecture: arm64 328s autopkgtest [10:49:31]: testbed apt version: 2.9.32ubuntu1 328s autopkgtest [10:49:31]: @@@@@@@@@@@@@@@@@@@@ test bed setup 329s autopkgtest [10:49:32]: testbed release detected to be: None 329s autopkgtest [10:49:32]: updating testbed package index (apt update) 330s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 330s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 330s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 330s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 330s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [396 kB] 330s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [43.9 kB] 330s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [11.5 kB] 330s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [54.8 kB] 330s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [340 kB] 330s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [3244 B] 331s Fetched 960 kB in 1s (1049 kB/s) 332s Reading package lists... 332s Reading package lists... 333s Building dependency tree... 333s Reading state information... 334s Calculating upgrade... 334s Calculating upgrade... 334s The following packages will be upgraded: 334s gir1.2-glib-2.0 htop libglib2.0-0t64 libglib2.0-data 335s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 335s Need to get 1982 kB of archives. 335s After this operation, 0 B of additional disk space will be used. 335s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 gir1.2-glib-2.0 arm64 2.84.0-1 [185 kB] 335s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 libglib2.0-0t64 arm64 2.84.0-1 [1571 kB] 335s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 libglib2.0-data all 2.84.0-1 [53.0 kB] 335s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 htop arm64 3.4.0-2 [173 kB] 336s Fetched 1982 kB in 1s (2820 kB/s) 336s (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 ... 117763 files and directories currently installed.) 336s Preparing to unpack .../gir1.2-glib-2.0_2.84.0-1_arm64.deb ... 336s Unpacking gir1.2-glib-2.0:arm64 (2.84.0-1) over (2.83.5-1) ... 336s Preparing to unpack .../libglib2.0-0t64_2.84.0-1_arm64.deb ... 336s Unpacking libglib2.0-0t64:arm64 (2.84.0-1) over (2.83.5-1) ... 336s Preparing to unpack .../libglib2.0-data_2.84.0-1_all.deb ... 336s Unpacking libglib2.0-data (2.84.0-1) over (2.83.5-1) ... 336s Preparing to unpack .../htop_3.4.0-2_arm64.deb ... 336s Unpacking htop (3.4.0-2) over (3.4.0-1) ... 336s Setting up htop (3.4.0-2) ... 336s Setting up libglib2.0-0t64:arm64 (2.84.0-1) ... 336s No schema files found: doing nothing. 337s Setting up libglib2.0-data (2.84.0-1) ... 337s Setting up gir1.2-glib-2.0:arm64 (2.84.0-1) ... 337s Processing triggers for man-db (2.13.0-1) ... 337s Processing triggers for libc-bin (2.41-1ubuntu1) ... 337s Reading package lists... 338s Building dependency tree... 338s Reading state information... 338s Solving dependencies... 338s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 338s autopkgtest [10:49:41]: upgrading testbed (apt dist-upgrade and autopurge) 339s Reading package lists... 339s Building dependency tree... 339s Reading state information... 339s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 340s Starting 2 pkgProblemResolver with broken count: 0 340s Done 340s Entering ResolveByKeep 341s 341s Calculating upgrade... 341s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 341s Reading package lists... 342s Building dependency tree... 342s Reading state information... 342s Starting pkgProblemResolver with broken count: 0 342s Starting 2 pkgProblemResolver with broken count: 0 342s Done 343s Solving dependencies... 343s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 345s autopkgtest [10:49:48]: testbed running kernel: Linux 6.14.0-7-generic #7-Ubuntu SMP PREEMPT_DYNAMIC Fri Feb 28 11:47:29 UTC 2025 346s autopkgtest [10:49:49]: @@@@@@@@@@@@@@@@@@@@ apt-source guile-fibers 347s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (dsc) [2405 B] 347s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (tar) [581 kB] 347s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (diff) [4516 B] 347s gpgv: Signature made Thu Feb 20 07:20:23 2025 UTC 347s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 347s gpgv: issuer "simon@josefsson.org" 347s gpgv: Can't check signature: No public key 347s dpkg-source: warning: cannot verify inline signature for ./guile-fibers_1.3.1-4.dsc: no acceptable signature found 348s autopkgtest [10:49:51]: testing package guile-fibers version 1.3.1-4 350s autopkgtest [10:49:53]: build not needed 351s autopkgtest [10:49:54]: test guile-tests-basic: preparing testbed 351s Reading package lists... 352s Building dependency tree... 352s Reading state information... 352s Starting pkgProblemResolver with broken count: 0 352s Starting 2 pkgProblemResolver with broken count: 0 352s Done 353s The following NEW packages will be installed: 353s guile-3.0 guile-3.0-libs guile-fibers libgc1 353s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 353s Need to get 8329 kB of archives. 353s After this operation, 59.9 MB of additional disk space will be used. 353s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libgc1 arm64 1:8.2.8-1 [90.1 kB] 353s Get:2 http://ftpmaster.internal/ubuntu plucky/universe arm64 guile-3.0-libs arm64 3.0.10+really3.0.10-4 [7891 kB] 354s Get:3 http://ftpmaster.internal/ubuntu plucky/universe arm64 guile-3.0 arm64 3.0.10+really3.0.10-4 [16.4 kB] 354s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 guile-fibers arm64 1.3.1-4 [332 kB] 354s Fetched 8329 kB in 1s (9271 kB/s) 354s Selecting previously unselected package libgc1:arm64. 354s (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 ... 117763 files and directories currently installed.) 354s Preparing to unpack .../libgc1_1%3a8.2.8-1_arm64.deb ... 354s Unpacking libgc1:arm64 (1:8.2.8-1) ... 354s Selecting previously unselected package guile-3.0-libs:arm64. 354s Preparing to unpack .../guile-3.0-libs_3.0.10+really3.0.10-4_arm64.deb ... 354s Unpacking guile-3.0-libs:arm64 (3.0.10+really3.0.10-4) ... 355s Selecting previously unselected package guile-3.0. 355s Preparing to unpack .../guile-3.0_3.0.10+really3.0.10-4_arm64.deb ... 355s Unpacking guile-3.0 (3.0.10+really3.0.10-4) ... 355s Selecting previously unselected package guile-fibers. 355s Preparing to unpack .../guile-fibers_1.3.1-4_arm64.deb ... 355s Unpacking guile-fibers (1.3.1-4) ... 355s Setting up libgc1:arm64 (1:8.2.8-1) ... 355s Setting up guile-3.0-libs:arm64 (3.0.10+really3.0.10-4) ... 355s Setting up guile-3.0 (3.0.10+really3.0.10-4) ... 355s update-alternatives: using /usr/lib/aarch64-linux-gnu/guile/3.0/bin/guile to provide /usr/bin/guile (guile) in auto mode 355s Setting up guile-fibers (1.3.1-4) ... 355s Processing triggers for libc-bin (2.41-1ubuntu1) ... 355s Processing triggers for man-db (2.13.0-1) ... 355s Processing triggers for install-info (7.1.1-1) ... 356s autopkgtest [10:49:59]: test guile-tests-basic: guile tests/basic.scm 356s autopkgtest [10:49:59]: test guile-tests-basic: [----------------------- 358s assert #f equal to #f: ok 358s assert #t terminates: ok 358s assert (false-if-exception (begin (run-fibers) #t)) equal to #f: ok 359s assert terminates: (run-fibers (lambda () (sleep 1)) #:drain? #t): ok (1.008792328 s) 359s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (8.60091e-4 s) 359s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.001054732 s) 359s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.001287795 s) 359s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.002053148 s) 359s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.009066958 s) 359s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.167065588 s) 359s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t))) #:drain? #t): ok (0.144639982 s) 361s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4))) #:drain? #t): ok (2.049661873 s) 362s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4 #:parallel? #t))) #:drain? #t): ok (1.055789317 s) 363s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.009524319 s) 364s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001599695 s) 365s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.002371041 s) 366s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.003971854 s) 367s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.021588368 s) 368s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.311902147 s) 375s assert terminates: (run-fibers (lambda () (do-times 1000000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (6.276050641 s) 376s assert terminates: (run-fibers (lambda () (spawn-fiber-tree 7 (lambda () (sleep 1)))) #:drain? #t): ok (1.019854609 s) 376s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5)) #:drain? #t): ok (8.25931e-4 s) 376s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50)) #:drain? #t): ok (8.37614e-4 s) 376s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500)) #:drain? #t): ok (0.001853082 s) 376s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000)) #:drain? #t): ok (0.012810764 s) 376s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50000)) #:drain? #t): ok (0.11847764 s) 377s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500000)) #:drain? #t): ok (1.194005192 s) 389s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000000)) #:drain? #t): ok (11.775772185 s) 389s assert terminates: (run-fibers (lambda () (test-run-order 10)) #:parallelism 1 #:drain? #t): ok (0.013033505 s) 389s assert terminates: (run-fibers (lambda () (test-wakeup-order 10)) #:parallelism 1 #:drain? #t): ok (4.08927e-4 s) 389s assert terminates: (run-fibers (lambda () 1) #:drain? #t): ok (6.00728e-4 s) 389s assert run-fiber-return-vals equal to (1): ok 390s assert terminates: (run-fibers (lambda () (do-times 20 (check-sleep (random 1.0)))) #:drain? #t): assert sleep 0.020608996598312723s < actual 0.020846856s: #t (diff: 1.154153238623713%) 390s assert sleep 0.03456686196908243s < actual 0.034986118s: #t (diff: 1.2128842684434626%) 390s assert sleep 0.061024566720115414s < actual 0.061217404s: #t (diff: 0.31599942490214256%) 390s assert sleep 0.11746097407324547s < actual 0.11785539s: #t (diff: 0.33578465517285966%) 390s assert sleep 0.13706411233780896s < actual 0.137249894s: #t (diff: 0.13554362190240538%) 390s assert sleep 0.2275100133355504s < actual 0.227987768s: #t (diff: 0.2099928075451218%) 390s assert sleep 0.23514019199674957s < actual 0.23530005s: #t (diff: 0.06798412550953867%) 390s assert sleep 0.3507339219939192s < actual 0.35114657s: #t (diff: 0.11765272196510614%) 390s assert sleep 0.36970041583284435s < actual 0.370024165s: #t (diff: 0.08757067974249773%) 390s assert sleep 0.5002849968690339s < actual 0.500582149s: #t (diff: 0.05939657051994863%) 390s assert sleep 0.6727431540500131s < actual 0.673076016s: #t (diff: 0.04947831099923678%) 390s assert sleep 0.7000185262935479s < actual 0.700328961s: #t (diff: 0.04434664152330034%) 390s assert sleep 0.7148624304124437s < actual 0.714996416s: #t (diff: 0.018742849233100815%) 390s assert sleep 0.721997240644088s < actual 0.722352228s: #t (diff: 0.04916741171965062%) 390s assert sleep 0.7233843607837082s < actual 0.723890636s: #t (diff: 0.06998702816071109%) 390s assert sleep 0.7603254888022394s < actual 0.760617246s: #t (diff: 0.03837267092285141%) 390s assert sleep 0.7965046199252751s < actual 0.79677793s: #t (diff: 0.0343136835478123%) 390s assert sleep 0.8067622687831747s < actual 0.807265407s: #t (diff: 0.06236511501513625%) 390s assert sleep 0.9299942644443119s < actual 0.930479217s: #t (diff: 0.05214575769216307%) 390s assert sleep 0.999598330708636s < actual 0.999727283s: #t (diff: 0.01290041083527891%) 390s ok (1.000602028 s) 390s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 390s ;;; or pass the --no-auto-compile argument to disable. 390s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/basic.scm 390s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 390s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/basic.scm.go 390s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 390s autopkgtest [10:50:33]: test guile-tests-basic: -----------------------] 391s autopkgtest [10:50:34]: test guile-tests-basic: - - - - - - - - - - results - - - - - - - - - - 391s guile-tests-basic PASS 391s autopkgtest [10:50:34]: test guile-tests-conditions: preparing testbed 391s Reading package lists... 391s Building dependency tree... 391s Reading state information... 392s Starting pkgProblemResolver with broken count: 0 392s Starting 2 pkgProblemResolver with broken count: 0 392s Done 393s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 393s autopkgtest [10:50:36]: test guile-tests-conditions: guile tests/conditions.scm 393s autopkgtest [10:50:36]: test guile-tests-conditions: [----------------------- 394s assert (condition? cv) equal to #t: ok 394s assert run-fibers on (wait/timeout cv) terminates: ok (0.051796058 s) 394s assert run-fiber-return-vals equal to (#f): ok 394s assert run-fibers on (wait/timeout cv) terminates: ok (0.05108664 s) 394s assert run-fiber-return-vals equal to (#f): ok 394s assert (signal-condition! cv) equal to #t: ok 394s assert (signal-condition! cv) equal to #f: ok 394s assert run-fibers on (wait/timeout cv) terminates: ok (8.09238e-4 s) 394s assert run-fiber-return-vals equal to (#t): ok 394s assert run-fibers on (wait/timeout cv) terminates: ok (7.85113e-4 s) 394s assert run-fiber-return-vals equal to (#t): ok 394s assert run-fibers on (let ((cv (make-condition))) (spawn-fiber (lambda () (signal-condition! cv))) (wait cv) #t) terminates: ok (8.23761e-4 s) 394s assert run-fiber-return-vals equal to (#t): ok 394s 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 394s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 394s ;;; or pass the --no-auto-compile argument to disable. 394s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/conditions.scm 394s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/conditions.scm.go 395s autopkgtest [10:50:38]: test guile-tests-conditions: -----------------------] 395s guile-tests-conditions PASS 395s autopkgtest [10:50:38]: test guile-tests-conditions: - - - - - - - - - - results - - - - - - - - - - 395s autopkgtest [10:50:38]: test guile-tests-channels: preparing testbed 396s Reading package lists... 396s Building dependency tree... 396s Reading state information... 396s Starting pkgProblemResolver with broken count: 0 396s Starting 2 pkgProblemResolver with broken count: 0 396s Done 397s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 398s autopkgtest [10:50:41]: test guile-tests-channels: guile tests/channels.scm 398s autopkgtest [10:50:41]: test guile-tests-channels: [----------------------- 399s assert run-fibers on (rpc 1) terminates: ok (0.001366291 s) 399s assert run-fiber-return-vals equal to (1): ok 400s assert run-fibers on (rpc-fib 24) terminates: ok (1.037411909 s) 400s assert run-fiber-return-vals equal to (75025): ok 400s assert run-fibers on (pingpong (current-processor-count) 1000) terminates: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 400s ;;; or pass the --no-auto-compile argument to disable. 400s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/channels.scm 400s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/channels.scm.go 400s ok (0.024498948 s) 400s autopkgtest [10:50:43]: test guile-tests-channels: -----------------------] 400s guile-tests-channels PASS 400s autopkgtest [10:50:43]: test guile-tests-channels: - - - - - - - - - - results - - - - - - - - - - 401s autopkgtest [10:50:44]: test guile-tests-foreign: preparing testbed 401s Reading package lists... 401s Building dependency tree... 401s Reading state information... 402s Starting pkgProblemResolver with broken count: 0 402s Starting 2 pkgProblemResolver with broken count: 0 402s Done 402s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 403s autopkgtest [10:50:46]: test guile-tests-foreign: guile tests/foreign.scm 403s autopkgtest [10:50:46]: test guile-tests-foreign: [----------------------- 404s assert #f equal to #f: ok 404s assert #t terminates: ok 405s assert (sleep 1) terminates: ok 406s assert (perform-operation (sleep-operation 1)) terminates: ok 406s assert (receive-from-fiber 42) equal to 42: ok 406s assert (send-to-fiber 42) equal to 42: ok 406s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 406s ;;; or pass the --no-auto-compile argument to disable. 406s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/foreign.scm 406s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 406s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/foreign.scm.go 406s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 406s autopkgtest [10:50:49]: test guile-tests-foreign: -----------------------] 406s guile-tests-foreign PASS 406s autopkgtest [10:50:49]: test guile-tests-foreign: - - - - - - - - - - results - - - - - - - - - - 407s autopkgtest [10:50:50]: test guile-tests-io-wakeup: preparing testbed 407s Reading package lists... 407s Building dependency tree... 407s Reading state information... 408s Starting pkgProblemResolver with broken count: 0 408s Starting 2 pkgProblemResolver with broken count: 0 408s Done 408s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 409s autopkgtest [10:50:52]: test guile-tests-io-wakeup: guile tests/io-wakeup.scm 409s autopkgtest [10:50:52]: test guile-tests-io-wakeup: [----------------------- 410s assert run-fibers on (readable/timeout? s) terminates: ok (0.052075353 s) 410s assert run-fiber-return-vals equal to (#t): ok 410s assert (readable/timeout? s) equal to #t: ok 410s assert run-fibers on (readable/timeout? A) terminates: ok (0.05136694 s) 410s assert run-fiber-return-vals equal to (#t): ok 410s assert (readable/timeout? A) equal to #t: ok 410s assert run-fibers on (writable/timeout? B) terminates: ok (9.38672e-4 s) 410s assert run-fiber-return-vals equal to (#f): ok 410s assert (writable/timeout? B) equal to #f: ok 410s assert run-fibers on (writable/timeout? B) terminates: ok (0.050985281 s) 410s assert run-fiber-return-vals equal to (#t): ok 410s assert run-fibers on (readable/timeout? A) terminates: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 410s ;;; or pass the --no-auto-compile argument to disable. 410s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/io-wakeup.scm 410s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/io-wakeup.scm.go 410s ok (0.001183688 s) 410s assert run-fiber-return-vals equal to (#f): ok 411s autopkgtest [10:50:54]: test guile-tests-io-wakeup: -----------------------] 411s autopkgtest [10:50:54]: test guile-tests-io-wakeup: - - - - - - - - - - results - - - - - - - - - - 411s guile-tests-io-wakeup PASS 412s autopkgtest [10:50:55]: test guile-tests-parameters: preparing testbed 412s Reading package lists... 412s Building dependency tree... 412s Reading state information... 413s Starting pkgProblemResolver with broken count: 0 413s Starting 2 pkgProblemResolver with broken count: 0 413s Done 413s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 414s autopkgtest [10:50:57]: test guile-tests-parameters: guile tests/parameters.scm 414s autopkgtest [10:50:57]: test guile-tests-parameters: [----------------------- 415s assert run-fibers on (my-param) terminates: ok (0.001455933 s) 415s assert run-fiber-return-vals equal to (#f): ok 415s assert run-fibers on (rpc (my-param)) terminates: ok (9.93151e-4 s) 415s assert run-fiber-return-vals equal to (#f): ok 415s assert run-fibers on (rpc (begin (my-param 42) (my-param))) terminates: ok (7.78436e-4 s) 415s assert run-fiber-return-vals equal to (42): ok 415s assert run-fibers on (my-param) terminates: ok (5.44885e-4 s) 415s assert run-fiber-return-vals equal to (#f): ok 415s assert run-fibers on (begin (my-param 100) (rpc (my-param))) terminates: ok (0.001138201 s) 415s assert run-fiber-return-vals equal to (100): ok 415s assert run-fibers on (my-param) terminates: ok (5.16011e-4 s) 415s assert run-fiber-return-vals equal to (#f): ok 415s assert (my-param) equal to #f: ok 415s assert (begin (my-param (quote foo)) (my-param)) equal to foo: ok 415s assert run-fibers on (my-param) terminates: ok (4.7678e-4 s) 415s assert run-fiber-return-vals equal to (foo): ok 415s assert run-fibers on (rpc (my-param)) terminates: ok (7.47403e-4 s) 415s assert run-fiber-return-vals equal to (foo): ok 415s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 415s ;;; or pass the --no-auto-compile argument to disable. 415s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/parameters.scm 415s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/parameters.scm.go 415s autopkgtest [10:50:58]: test guile-tests-parameters: -----------------------] 416s autopkgtest [10:50:59]: test guile-tests-parameters: - - - - - - - - - - results - - - - - - - - - - 416s guile-tests-parameters PASS 416s autopkgtest [10:50:59]: test guile-tests-preemption: preparing testbed 416s Reading package lists... 417s Building dependency tree... 417s Reading state information... 417s Starting pkgProblemResolver with broken count: 0 417s Starting 2 pkgProblemResolver with broken count: 0 417s Done 418s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 419s autopkgtest [10:51:02]: test guile-tests-preemption: guile tests/preemption.scm 419s autopkgtest [10:51:02]: test guile-tests-preemption: [----------------------- 419s assert run-fibers on (let lp ((n 0)) (when (< n 100000000) (lp (#{1+}# n)))) terminates: ok (0.188935983 s) 420s assert run-fibers on (race-until 100) terminates: ok (0.59976886 s) 420s assert run-fiber-return-vals equal to (100): ok 420s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 420s ;;; or pass the --no-auto-compile argument to disable. 420s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/preemption.scm 420s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/preemption.scm.go 420s autopkgtest [10:51:03]: test guile-tests-preemption: -----------------------] 421s autopkgtest [10:51:04]: test guile-tests-preemption: - - - - - - - - - - results - - - - - - - - - - 421s guile-tests-preemption PASS 421s autopkgtest [10:51:04]: test guile-tests-speedup: preparing testbed 421s Reading package lists... 422s Building dependency tree... 422s Reading state information... 422s Starting pkgProblemResolver with broken count: 0 422s Starting 2 pkgProblemResolver with broken count: 0 422s Done 423s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 424s autopkgtest [10:51:07]: test guile-tests-speedup: guile tests/speedup.scm 424s autopkgtest [10:51:07]: test guile-tests-speedup: [----------------------- 425s speedup for (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t)): 0.143401032 s / 0.134167104 s = 1.06882408373367x (2 cpus) 427s speedup for (do-times 40000 (spawn-fiber (lambda () (sleep 1)) #:parallel? #t)): 1.12911168 s / 1.082119397 s = 1.0434261534635443x (2 cpus) 430s speedup for (do-times 100000 (spawn-fiber (lambda () (loop-to 10000)) #:parallel? #t)): 2.192738647 s / 1.133526619 s = 1.9344394831542993x (2 cpus) 433s speedup for (do-times 10000 (spawn-fiber (lambda () (loop-to 100000)) #:parallel? #t)): 2.11044344 s / 1.054260878 s = 2.0018227784413716x (2 cpus) 437s speedup for (do-times 1000 (spawn-fiber (lambda () (loop-to 1000000)) #:parallel? #t)): 2.098673272 s / 1.048346261 s = 2.0018894043635074x (2 cpus) 443s speedup for (do-times 100000 (spawn-fiber (lambda () (alloc-to 4 1000)) #:parallel? #t)): 3.944353575 s / 2.954903291 s = 1.3348503103345728x (2 cpus) 450s speedup for (do-times 10000 (spawn-fiber (lambda () (alloc-to 4 10000)) #:parallel? #t)): 3.515870643 s / 2.676775414 s = 1.3134724058699083x (2 cpus) 456s speedup for (do-times 1000 (spawn-fiber (lambda () (alloc-to 4 100000)) #:parallel? #t)): 3.519702133 s / 2.644220499 s = 1.331092522099081x (2 cpus) 456s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 456s ;;; or pass the --no-auto-compile argument to disable. 456s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/speedup.scm 456s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 456s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/speedup.scm.go 456s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 456s autopkgtest [10:51:39]: test guile-tests-speedup: -----------------------] 457s guile-tests-speedup PASS 457s autopkgtest [10:51:40]: test guile-tests-speedup: - - - - - - - - - - results - - - - - - - - - - 457s autopkgtest [10:51:40]: test guile-tests-timer-wheel: preparing testbed 457s Reading package lists... 457s Building dependency tree... 457s Reading state information... 458s Starting pkgProblemResolver with broken count: 0 458s Starting 2 pkgProblemResolver with broken count: 0 458s Done 459s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 459s autopkgtest [10:51:42]: test guile-tests-timer-wheel: guile tests/timer-wheel.scm 459s autopkgtest [10:51:42]: test guile-tests-timer-wheel: [----------------------- 461s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 461s ;;; or pass the --no-auto-compile argument to disable. 461s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/timer-wheel.scm 461s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/timer-wheel.scm.go 461s autopkgtest [10:51:44]: test guile-tests-timer-wheel: -----------------------] 462s autopkgtest [10:51:45]: test guile-tests-timer-wheel: - - - - - - - - - - results - - - - - - - - - - 462s guile-tests-timer-wheel PASS 462s autopkgtest [10:51:45]: test guile-tests-ports: preparing testbed 463s Reading package lists... 463s Building dependency tree... 463s Reading state information... 463s Starting pkgProblemResolver with broken count: 0 463s Starting 2 pkgProblemResolver with broken count: 0 463s Done 464s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 465s autopkgtest [10:51:48]: test guile-tests-ports: guile tests/ports.scm 465s autopkgtest [10:51:48]: test guile-tests-ports: [----------------------- 470s 470s ;;; (listening-socket #) 470s 470s ;;; (listening-socket #) 470s 470s ;;; (listening-socket #) 470s 470s ;;; (listening-socket #) 470s 470s ;;; (listening-socket #) 470s 470s ;;; (listening-socket #) 470s 470s ;;; (accepted-connection (# . #(2 2130706433 39870))) 470s 470s ;;; (connected #(2 2130706433 5556)) 470s 470s ;;; (received "hello!") 470s 470s ;;; (echo "hello!") 470s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 470s ;;; or pass the --no-auto-compile argument to disable. 470s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/ports.scm 470s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 470s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/ports.scm.go 470s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 470s success 471s autopkgtest [10:51:54]: test guile-tests-ports: -----------------------] 471s guile-tests-ports PASS 471s autopkgtest [10:51:54]: test guile-tests-ports: - - - - - - - - - - results - - - - - - - - - - 471s autopkgtest [10:51:54]: test guile-tests-concurrent-web-server: preparing testbed 472s Reading package lists... 472s Building dependency tree... 472s Reading state information... 472s Starting pkgProblemResolver with broken count: 0 472s Starting 2 pkgProblemResolver with broken count: 0 472s Done 473s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 474s autopkgtest [10:51:57]: test guile-tests-concurrent-web-server: guile tests/concurrent-web-server.scm 474s autopkgtest [10:51:57]: test guile-tests-concurrent-web-server: [----------------------- 480s assert (length data) equal to 10000: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 480s ;;; or pass the --no-auto-compile argument to disable. 480s ;;; compiling /tmp/autopkgtest.cYUq3l/build.ijw/src/tests/concurrent-web-server.scm 480s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cYUq3l/build.ijw/src/tests/concurrent-web-server.scm.go 480s no (got 890) 480s assert (length data) equal to 10000: no (got 896) 480s autopkgtest [10:52:03]: test guile-tests-concurrent-web-server: -----------------------] 480s autopkgtest [10:52:03]: test guile-tests-concurrent-web-server: - - - - - - - - - - results - - - - - - - - - - 480s guile-tests-concurrent-web-server FAIL non-zero exit status 1 481s autopkgtest [10:52:04]: @@@@@@@@@@@@@@@@@@@@ summary 481s guile-tests-basic PASS 481s guile-tests-conditions PASS 481s guile-tests-channels PASS 481s guile-tests-foreign PASS 481s guile-tests-io-wakeup PASS 481s guile-tests-parameters PASS 481s guile-tests-preemption PASS 481s guile-tests-speedup PASS 481s guile-tests-timer-wheel PASS 481s guile-tests-ports PASS 481s guile-tests-concurrent-web-server FAIL non-zero exit status 1 498s nova [W] Using flock in prodstack6-arm64 498s Creating nova instance adt-plucky-arm64-guile-fibers-20250313-104342-juju-7f2275-prod-proposed-migration-environment-20-b547ea3e-4ba8-482f-ae9c-72edbb06fc5e from image adt/ubuntu-plucky-arm64-server-20250313.img (UUID 1e0d5b46-827c-482e-8813-f0c13efee277)... 498s nova [W] Timed out waiting for cb1131cc-7de2-4f1f-a3d1-205896ba1ce2 to get deleted.