0s autopkgtest [11:16:21]: starting date and time: 2025-03-13 11:16:21+0000 0s autopkgtest [11:16:21]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [11:16:21]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.w4yhxwa5/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-ppc64el-12.secgroup --name adt-plucky-ppc64el-guile-fibers-20250313-111621-juju-7f2275-prod-proposed-migration-environment-20-eab44dc1-979d-432b-9782-21b039feabce --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-ppc64el -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/ 75s autopkgtest [11:17:36]: testbed dpkg architecture: ppc64el 76s autopkgtest [11:17:37]: testbed apt version: 2.9.32ubuntu1 76s autopkgtest [11:17:37]: @@@@@@@@@@@@@@@@@@@@ test bed setup 76s autopkgtest [11:17:37]: testbed release detected to be: None 77s autopkgtest [11:17:38]: updating testbed package index (apt update) 77s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 78s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 78s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 78s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 78s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [11.5 kB] 78s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [43.9 kB] 79s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [396 kB] 79s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [56.8 kB] 79s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [328 kB] 79s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [3020 B] 79s Fetched 950 kB in 2s (491 kB/s) 80s Reading package lists... 80s Reading package lists... 81s Building dependency tree... 81s Reading state information... 81s Calculating upgrade... 81s Calculating upgrade... 81s The following NEW packages will be installed: 81s pnp.ids 81s The following packages will be upgraded: 81s gir1.2-glib-2.0 htop hwdata libglib2.0-0t64 libglib2.0-data 81s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 81s Need to get 2297 kB of archives. 81s After this operation, 11.3 kB of additional disk space will be used. 81s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el gir1.2-glib-2.0 ppc64el 2.84.0-1 [184 kB] 81s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libglib2.0-0t64 ppc64el 2.84.0-1 [1802 kB] 82s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libglib2.0-data all 2.84.0-1 [53.0 kB] 82s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el htop ppc64el 3.4.0-2 [227 kB] 82s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el hwdata all 0.393-3 [1562 B] 82s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el pnp.ids all 0.393-3 [29.5 kB] 82s Fetched 2297 kB in 1s (3313 kB/s) 82s (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 ... 107190 files and directories currently installed.) 82s Preparing to unpack .../0-gir1.2-glib-2.0_2.84.0-1_ppc64el.deb ... 82s Unpacking gir1.2-glib-2.0:ppc64el (2.84.0-1) over (2.83.5-1) ... 82s Preparing to unpack .../1-libglib2.0-0t64_2.84.0-1_ppc64el.deb ... 82s Unpacking libglib2.0-0t64:ppc64el (2.84.0-1) over (2.83.5-1) ... 82s Preparing to unpack .../2-libglib2.0-data_2.84.0-1_all.deb ... 82s Unpacking libglib2.0-data (2.84.0-1) over (2.83.5-1) ... 83s Preparing to unpack .../3-htop_3.4.0-2_ppc64el.deb ... 83s Unpacking htop (3.4.0-2) over (3.4.0-1) ... 83s Preparing to unpack .../4-hwdata_0.393-3_all.deb ... 83s Unpacking hwdata (0.393-3) over (0.393-1) ... 83s Selecting previously unselected package pnp.ids. 83s Preparing to unpack .../5-pnp.ids_0.393-3_all.deb ... 83s Unpacking pnp.ids (0.393-3) ... 83s Setting up htop (3.4.0-2) ... 83s Setting up libglib2.0-0t64:ppc64el (2.84.0-1) ... 83s No schema files found: doing nothing. 83s Setting up libglib2.0-data (2.84.0-1) ... 83s Setting up gir1.2-glib-2.0:ppc64el (2.84.0-1) ... 83s Setting up pnp.ids (0.393-3) ... 83s Setting up hwdata (0.393-3) ... 83s Processing triggers for man-db (2.13.0-1) ... 83s Processing triggers for libc-bin (2.41-1ubuntu1) ... 83s Reading package lists... 84s Building dependency tree... 84s Reading state information... 84s Solving dependencies... 84s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 84s autopkgtest [11:17:45]: upgrading testbed (apt dist-upgrade and autopurge) 84s Reading package lists... 84s Building dependency tree... 84s Reading state information... 85s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 85s Starting 2 pkgProblemResolver with broken count: 0 85s Done 85s Entering ResolveByKeep 85s 85s Calculating upgrade... 85s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 86s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s Starting pkgProblemResolver with broken count: 0 86s Starting 2 pkgProblemResolver with broken count: 0 86s Done 87s Solving dependencies... 87s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 89s autopkgtest [11:17:50]: testbed running kernel: Linux 6.14.0-7-generic #7-Ubuntu SMP Fri Feb 28 12:06:33 UTC 2025 89s autopkgtest [11:17:50]: @@@@@@@@@@@@@@@@@@@@ apt-source guile-fibers 91s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (dsc) [2405 B] 91s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (tar) [581 kB] 91s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (diff) [4516 B] 91s gpgv: Signature made Thu Feb 20 07:20:23 2025 UTC 91s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 91s gpgv: issuer "simon@josefsson.org" 91s gpgv: Can't check signature: No public key 91s dpkg-source: warning: cannot verify inline signature for ./guile-fibers_1.3.1-4.dsc: no acceptable signature found 91s autopkgtest [11:17:52]: testing package guile-fibers version 1.3.1-4 92s autopkgtest [11:17:53]: build not needed 93s autopkgtest [11:17:54]: 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 8406 kB of archives. 94s After this operation, 60.3 MB of additional disk space will be used. 94s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el libgc1 ppc64el 1:8.2.8-1 [112 kB] 95s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el guile-3.0-libs ppc64el 3.0.10+really3.0.10-4 [7946 kB] 97s Get:3 http://ftpmaster.internal/ubuntu plucky/universe ppc64el guile-3.0 ppc64el 3.0.10+really3.0.10-4 [16.4 kB] 97s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el guile-fibers ppc64el 1.3.1-4 [332 kB] 97s Fetched 8406 kB in 3s (3023 kB/s) 97s Selecting previously unselected package libgc1:ppc64el. 97s (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 ... 107193 files and directories currently installed.) 97s Preparing to unpack .../libgc1_1%3a8.2.8-1_ppc64el.deb ... 97s Unpacking libgc1:ppc64el (1:8.2.8-1) ... 97s Selecting previously unselected package guile-3.0-libs:ppc64el. 97s Preparing to unpack .../guile-3.0-libs_3.0.10+really3.0.10-4_ppc64el.deb ... 97s Unpacking guile-3.0-libs:ppc64el (3.0.10+really3.0.10-4) ... 98s Selecting previously unselected package guile-3.0. 98s Preparing to unpack .../guile-3.0_3.0.10+really3.0.10-4_ppc64el.deb ... 98s Unpacking guile-3.0 (3.0.10+really3.0.10-4) ... 98s Selecting previously unselected package guile-fibers. 98s Preparing to unpack .../guile-fibers_1.3.1-4_ppc64el.deb ... 98s Unpacking guile-fibers (1.3.1-4) ... 98s Setting up libgc1:ppc64el (1:8.2.8-1) ... 98s Setting up guile-3.0-libs:ppc64el (3.0.10+really3.0.10-4) ... 98s Setting up guile-3.0 (3.0.10+really3.0.10-4) ... 98s update-alternatives: using /usr/lib/powerpc64le-linux-gnu/guile/3.0/bin/guile to provide /usr/bin/guile (guile) in auto mode 98s Setting up guile-fibers (1.3.1-4) ... 98s Processing triggers for libc-bin (2.41-1ubuntu1) ... 98s Processing triggers for man-db (2.13.0-1) ... 98s Processing triggers for install-info (7.1.1-1) ... 99s autopkgtest [11:18:00]: test guile-tests-basic: guile tests/basic.scm 99s autopkgtest [11:18:00]: test guile-tests-basic: [----------------------- 103s assert #f equal to #f: ok 103s assert #t terminates: ok 103s assert (false-if-exception (begin (run-fibers) #t)) equal to #f: ok 104s assert terminates: (run-fibers (lambda () (sleep 1)) #:drain? #t): ok (1.002602555 s) 104s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (6.88975e-4 s) 104s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (6.15003e-4 s) 104s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (7.69114e-4 s) 104s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.002640608 s) 104s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.022564315 s) 104s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.253067807 s) 105s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t))) #:drain? #t): ok (0.296161614 s) 125s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4))) #:drain? #t): ok (20.686095104 s) 136s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4 #:parallel? #t))) #:drain? #t): ok (10.686890732 s) 137s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.003561365 s) 138s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.003754347 s) 139s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.003605156 s) 140s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.009935813 s) 141s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.086647624 s) 143s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.99018198 s) 158s assert terminates: (run-fibers (lambda () (do-times 1000000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (14.911547357 s) 159s assert terminates: (run-fibers (lambda () (spawn-fiber-tree 7 (lambda () (sleep 1)))) #:drain? #t): ok (1.063675178 s) 159s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5)) #:drain? #t): ok (0.00516497 s) 159s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50)) #:drain? #t): ok (0.001795568 s) 159s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500)) #:drain? #t): ok (0.005697215 s) 159s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000)) #:drain? #t): ok (0.048140995 s) 160s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50000)) #:drain? #t): ok (0.462627379 s) 164s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500000)) #:drain? #t): ok (4.662059903 s) 211s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000000)) #:drain? #t): ok (46.394571683 s) 211s assert terminates: (run-fibers (lambda () (test-run-order 10)) #:parallelism 1 #:drain? #t): ok (0.009465081 s) 211s assert terminates: (run-fibers (lambda () (test-wakeup-order 10)) #:parallelism 1 #:drain? #t): ok (2.74265e-4 s) 211s assert terminates: (run-fibers (lambda () 1) #:drain? #t): ok (4.91723e-4 s) 211s assert run-fiber-return-vals equal to (1): ok 212s assert terminates: (run-fibers (lambda () (do-times 20 (check-sleep (random 1.0)))) #:drain? #t): assert sleep 0.020608996598312723s < actual 0.02113729s: #t (diff: 2.5634115623587816%) 212s assert sleep 0.03456686196908243s < actual 0.034723399s: #t (diff: 0.45285288279156904%) 212s assert sleep 0.061024566720115414s < actual 0.061498893s: #t (diff: 0.777271032599128%) 212s assert sleep 0.11746097407324547s < actual 0.117698709s: #t (diff: 0.20239481975202986%) 212s assert sleep 0.13706411233780896s < actual 0.137597204s: #t (diff: 0.38893598995277257%) 212s assert sleep 0.2275100133355504s < actual 0.227823465s: #t (diff: 0.1377748872913539%) 212s assert sleep 0.23514019199674957s < actual 0.235626055s: #t (diff: 0.20662694842791784%) 212s assert sleep 0.3507339219939192s < actual 0.350946541s: #t (diff: 0.060621169709530916%) 212s assert sleep 0.36970041583284435s < actual 0.369805703s: #t (diff: 0.028479050238135645%) 212s assert sleep 0.5002849968690339s < actual 0.500920476s: #t (diff: 0.12702342363715202%) 212s assert sleep 0.6727431540500131s < actual 0.672855101s: #t (diff: 0.016640370000499404%) 212s assert sleep 0.7000185262935479s < actual 0.700139474s: #t (diff: 0.01727778650266505%) 212s assert sleep 0.7148624304124437s < actual 0.715262333s: #t (diff: 0.05594119519269784%) 212s assert sleep 0.721997240644088s < actual 0.722114582s: #t (diff: 0.01625232747528728%) 212s assert sleep 0.7233843607837082s < actual 0.723617603s: #t (diff: 0.03224319309849989%) 212s assert sleep 0.7603254888022394s < actual 0.760435597s: #t (diff: 0.014481718603704799%) 212s assert sleep 0.7965046199252751s < actual 0.797180242s: #t (diff: 0.08482337174495228%) 212s assert sleep 0.8067622687831747s < actual 0.807037508s: #t (diff: 0.034116520749092674%) 212s assert sleep 0.9299942644443119s < actual 0.930229115s: #t (diff: 0.02525290366478746%) 212s assert sleep 0.999598330708636s < actual 1.00014436s: #t (diff: 0.054624870269325575%) 212s ok (1.001003148 s) 212s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 212s ;;; or pass the --no-auto-compile argument to disable. 212s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/basic.scm 212s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 212s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/basic.scm.go 212s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 212s autopkgtest [11:19:53]: test guile-tests-basic: -----------------------] 212s autopkgtest [11:19:53]: test guile-tests-basic: - - - - - - - - - - results - - - - - - - - - - 212s guile-tests-basic PASS 213s autopkgtest [11:19:54]: test guile-tests-conditions: preparing testbed 213s Reading package lists... 213s Building dependency tree... 213s Reading state information... 213s Starting pkgProblemResolver with broken count: 0 213s Starting 2 pkgProblemResolver with broken count: 0 213s Done 214s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 214s autopkgtest [11:19:55]: test guile-tests-conditions: guile tests/conditions.scm 214s autopkgtest [11:19:55]: test guile-tests-conditions: [----------------------- 216s assert (condition? cv) equal to #t: ok 216s assert run-fibers on (wait/timeout cv) terminates: ok (0.051604644 s) 216s assert run-fiber-return-vals equal to (#f): ok 216s assert run-fibers on (wait/timeout cv) terminates: ok (0.058954819 s) 216s assert run-fiber-return-vals equal to (#f): ok 216s assert (signal-condition! cv) equal to #t: ok 216s assert (signal-condition! cv) equal to #f: ok 216s assert run-fibers on (wait/timeout cv) terminates: ok (8.83546e-4 s) 216s assert run-fiber-return-vals equal to (#t): ok 216s assert run-fibers on (wait/timeout cv) terminates: ok (9.2148e-4 s) 216s assert run-fiber-return-vals equal to (#t): ok 216s assert run-fibers on (let ((cv (make-condition))) (spawn-fiber (lambda () (signal-condition! cv))) (wait cv) #t) terminates: ok (9.48222e-4 s) 216s assert run-fiber-return-vals equal to (#t): ok 216s 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 216s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 216s ;;; or pass the --no-auto-compile argument to disable. 216s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/conditions.scm 216s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/conditions.scm.go 216s autopkgtest [11:19:57]: test guile-tests-conditions: -----------------------] 217s guile-tests-conditions PASS 217s autopkgtest [11:19:58]: test guile-tests-conditions: - - - - - - - - - - results - - - - - - - - - - 217s autopkgtest [11:19:58]: test guile-tests-channels: preparing testbed 217s Reading package lists... 218s Building dependency tree... 218s Reading state information... 218s Starting pkgProblemResolver with broken count: 0 218s Starting 2 pkgProblemResolver with broken count: 0 218s Done 218s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 219s autopkgtest [11:20:00]: test guile-tests-channels: guile tests/channels.scm 219s autopkgtest [11:20:00]: test guile-tests-channels: [----------------------- 220s assert run-fibers on (rpc 1) terminates: ok (0.001127129 s) 220s assert run-fiber-return-vals equal to (1): ok 224s assert run-fibers on (rpc-fib 24) terminates: ok (3.78935548 s) 224s assert run-fiber-return-vals equal to (75025): ok 224s assert run-fibers on (pingpong (current-processor-count) 1000) terminates: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 224s ;;; or pass the --no-auto-compile argument to disable. 224s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/channels.scm 224s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/channels.scm.go 224s ok (0.039934986 s) 224s autopkgtest [11:20:05]: test guile-tests-channels: -----------------------] 225s guile-tests-channels PASS 225s autopkgtest [11:20:06]: test guile-tests-channels: - - - - - - - - - - results - - - - - - - - - - 225s autopkgtest [11:20:06]: test guile-tests-foreign: preparing testbed 225s Reading package lists... 225s Building dependency tree... 225s Reading state information... 225s Starting pkgProblemResolver with broken count: 0 225s Starting 2 pkgProblemResolver with broken count: 0 225s Done 226s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 226s autopkgtest [11:20:07]: test guile-tests-foreign: guile tests/foreign.scm 226s autopkgtest [11:20:07]: test guile-tests-foreign: [----------------------- 227s assert #f equal to #f: ok 227s assert #t terminates: ok 228s assert (sleep 1) terminates: ok 229s assert (perform-operation (sleep-operation 1)) terminates: ok 229s assert (receive-from-fiber 42) equal to 42: ok 229s assert (send-to-fiber 42) equal to 42: ok 229s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 229s ;;; or pass the --no-auto-compile argument to disable. 229s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/foreign.scm 229s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 229s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/foreign.scm.go 229s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 230s autopkgtest [11:20:11]: test guile-tests-foreign: -----------------------] 230s autopkgtest [11:20:11]: test guile-tests-foreign: - - - - - - - - - - results - - - - - - - - - - 230s guile-tests-foreign PASS 230s autopkgtest [11:20:11]: test guile-tests-io-wakeup: preparing testbed 231s Reading package lists... 231s Building dependency tree... 231s Reading state information... 231s Starting pkgProblemResolver with broken count: 0 231s Starting 2 pkgProblemResolver with broken count: 0 231s Done 231s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 232s autopkgtest [11:20:13]: test guile-tests-io-wakeup: guile tests/io-wakeup.scm 232s autopkgtest [11:20:13]: test guile-tests-io-wakeup: [----------------------- 234s assert run-fibers on (readable/timeout? s) terminates: ok (0.051597763 s) 234s assert run-fiber-return-vals equal to (#t): ok 234s assert (readable/timeout? s) equal to #t: ok 234s assert run-fibers on (readable/timeout? A) terminates: ok (0.052286732 s) 234s assert run-fiber-return-vals equal to (#t): ok 234s assert (readable/timeout? A) equal to #t: ok 234s assert run-fibers on (writable/timeout? B) terminates: ok (6.2741e-4 s) 234s assert run-fiber-return-vals equal to (#f): ok 234s assert (writable/timeout? B) equal to #f: ok 234s assert run-fibers on (writable/timeout? B) terminates: ok (0.051443136 s) 234s assert run-fiber-return-vals equal to (#t): ok 234s assert run-fibers on (readable/timeout? A) terminates: ok (0.009114614 s) 234s assert run-fiber-return-vals equal to (#f): ok 234s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 234s ;;; or pass the --no-auto-compile argument to disable. 234s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/io-wakeup.scm 234s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/io-wakeup.scm.go 234s autopkgtest [11:20:15]: test guile-tests-io-wakeup: -----------------------] 235s guile-tests-io-wakeup PASS 235s autopkgtest [11:20:16]: test guile-tests-io-wakeup: - - - - - - - - - - results - - - - - - - - - - 235s autopkgtest [11:20:16]: test guile-tests-parameters: preparing testbed 235s Reading package lists... 236s Building dependency tree... 236s Reading state information... 236s Starting pkgProblemResolver with broken count: 0 236s Starting 2 pkgProblemResolver with broken count: 0 236s Done 236s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 237s autopkgtest [11:20:18]: test guile-tests-parameters: guile tests/parameters.scm 237s autopkgtest [11:20:18]: test guile-tests-parameters: [----------------------- 238s assert run-fibers on (my-param) terminates: ok (0.010408263 s) 238s assert run-fiber-return-vals equal to (#f): ok 238s assert run-fibers on (rpc (my-param)) terminates: ok (0.001167998 s) 238s assert run-fiber-return-vals equal to (#f): ok 238s assert run-fibers on (rpc (begin (my-param 42) (my-param))) terminates: ok (5.58115e-4 s) 238s assert run-fiber-return-vals equal to (42): ok 238s assert run-fibers on (my-param) terminates: ok (5.29911e-4 s) 238s assert run-fiber-return-vals equal to (#f): ok 238s assert run-fibers on (begin (my-param 100) (rpc (my-param))) terminates: ok (5.80874e-4 s) 238s assert run-fiber-return-vals equal to (100): ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 238s ;;; or pass the --no-auto-compile argument to disable. 238s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/parameters.scm 238s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/parameters.scm.go 238s ok 238s assert run-fibers on (my-param) terminates: ok (5.35009e-4 s) 238s assert run-fiber-return-vals equal to (#f): ok 238s assert (my-param) equal to #f: ok 238s assert (begin (my-param (quote foo)) (my-param)) equal to foo: ok 238s assert run-fibers on (my-param) terminates: ok (5.64518e-4 s) 238s assert run-fiber-return-vals equal to (foo): ok 238s assert run-fibers on (rpc (my-param)) terminates: ok (5.82727e-4 s) 238s assert run-fiber-return-vals equal to (foo): ok 239s autopkgtest [11:20:20]: test guile-tests-parameters: -----------------------] 239s guile-tests-parameters PASS 239s autopkgtest [11:20:20]: test guile-tests-parameters: - - - - - - - - - - results - - - - - - - - - - 240s autopkgtest [11:20:21]: test guile-tests-preemption: preparing testbed 240s Reading package lists... 240s Building dependency tree... 240s Reading state information... 240s Starting pkgProblemResolver with broken count: 0 240s Starting 2 pkgProblemResolver with broken count: 0 240s Done 240s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 241s autopkgtest [11:20:22]: test guile-tests-preemption: guile tests/preemption.scm 241s autopkgtest [11:20:22]: test guile-tests-preemption: [----------------------- 244s assert run-fibers on (let lp ((n 0)) (when (< n 100000000) (lp (#{1+}# n)))) terminates: ok (2.374915542 s) 246s assert run-fibers on (race-until 100) terminates: ok (1.197111773 s) 246s assert run-fiber-return-vals equal to (100): ok 246s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 246s ;;; or pass the --no-auto-compile argument to disable. 246s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/preemption.scm 246s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/preemption.scm.go 246s autopkgtest [11:20:27]: test guile-tests-preemption: -----------------------] 246s autopkgtest [11:20:27]: test guile-tests-preemption: - - - - - - - - - - results - - - - - - - - - - 246s guile-tests-preemption PASS 247s autopkgtest [11:20:28]: test guile-tests-speedup: preparing testbed 247s Reading package lists... 247s Building dependency tree... 247s Reading state information... 247s Starting pkgProblemResolver with broken count: 0 247s Starting 2 pkgProblemResolver with broken count: 0 247s Done 248s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 248s autopkgtest [11:20:29]: test guile-tests-speedup: guile tests/speedup.scm 248s autopkgtest [11:20:29]: test guile-tests-speedup: [----------------------- 250s speedup for (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t)): 0.290944384 s / 0.294394995 s = 0.9882789753270093x (2 cpus) 253s speedup for (do-times 40000 (spawn-fiber (lambda () (sleep 1)) #:parallel? #t)): 1.335190626 s / 1.252040661 s = 1.0664115532267044x (2 cpus) 289s speedup for (do-times 100000 (spawn-fiber (lambda () (loop-to 10000)) #:parallel? #t)): 23.710065984 s / 12.054545693 s = 1.9668983458885796x (2 cpus) 324s speedup for (do-times 10000 (spawn-fiber (lambda () (loop-to 100000)) #:parallel? #t)): 23.644109025 s / 11.760708835 s = 2.010432309541995x (2 cpus) 359s speedup for (do-times 1000 (spawn-fiber (lambda () (loop-to 1000000)) #:parallel? #t)): 23.077972813 s / 11.564476249 s = 1.9955916996237153x (2 cpus) 377s speedup for (do-times 100000 (spawn-fiber (lambda () (alloc-to 4 1000)) #:parallel? #t)): 10.968642232 s / 6.867927246 s = 1.5970818908118645x (2 cpus) 394s speedup for (do-times 10000 (spawn-fiber (lambda () (alloc-to 4 10000)) #:parallel? #t)): 10.98940575 s / 6.519943356 s = 1.6855063226718008x (2 cpus) 412s speedup for (do-times 1000 (spawn-fiber (lambda () (alloc-to 4 100000)) #:parallel? #t)): 11.017074635 s / 6.30836493 s = 1.7464231630937053x (2 cpus) 412s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 412s ;;; or pass the --no-auto-compile argument to disable. 412s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/speedup.scm 412s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 412s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/speedup.scm.go 412s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 412s autopkgtest [11:23:13]: test guile-tests-speedup: -----------------------] 412s autopkgtest [11:23:13]: test guile-tests-speedup: - - - - - - - - - - results - - - - - - - - - - 412s guile-tests-speedup PASS 413s autopkgtest [11:23:14]: test guile-tests-timer-wheel: preparing testbed 413s Reading package lists... 413s Building dependency tree... 413s Reading state information... 413s Starting pkgProblemResolver with broken count: 0 413s Starting 2 pkgProblemResolver with broken count: 0 413s Done 414s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 415s autopkgtest [11:23:16]: test guile-tests-timer-wheel: guile tests/timer-wheel.scm 415s autopkgtest [11:23:16]: test guile-tests-timer-wheel: [----------------------- 418s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 418s ;;; or pass the --no-auto-compile argument to disable. 418s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/timer-wheel.scm 418s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/timer-wheel.scm.go 418s autopkgtest [11:23:19]: test guile-tests-timer-wheel: -----------------------] 419s guile-tests-timer-wheel PASS 419s autopkgtest [11:23:20]: test guile-tests-timer-wheel: - - - - - - - - - - results - - - - - - - - - - 419s autopkgtest [11:23:20]: test guile-tests-ports: preparing testbed 419s Reading package lists... 419s Building dependency tree... 419s Reading state information... 420s Starting pkgProblemResolver with broken count: 0 420s Starting 2 pkgProblemResolver with broken count: 0 420s Done 420s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 421s autopkgtest [11:23:22]: test guile-tests-ports: guile tests/ports.scm 421s autopkgtest [11:23:22]: test guile-tests-ports: [----------------------- 427s 427s ;;; (listening-socket #) 427s 427s ;;; (listening-socket #) 427s 427s ;;; (listening-socket #) 427s 427s ;;; (listening-socket #) 427s 427s ;;; (listening-socket #) 427s 427s ;;; (listening-socket #) 427s 427s ;;; (accepted-connection (# . #(2 2130706433 59224))) 427s 427s ;;; (connected #(2 2130706433 5556)) 427s 427s ;;; (received "hello!") 427s 427s ;;; (echo "hello!") 427s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 427s ;;; or pass the --no-auto-compile argument to disable. 427s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/ports.scm 427s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 427s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/ports.scm.go 427s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 427s success 427s autopkgtest [11:23:28]: test guile-tests-ports: -----------------------] 427s guile-tests-ports PASS 427s autopkgtest [11:23:28]: test guile-tests-ports: - - - - - - - - - - results - - - - - - - - - - 428s autopkgtest [11:23:29]: test guile-tests-concurrent-web-server: preparing testbed 428s Reading package lists... 428s Building dependency tree... 428s Reading state information... 428s Starting pkgProblemResolver with broken count: 0 428s Starting 2 pkgProblemResolver with broken count: 0 428s Done 429s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 429s autopkgtest [11:23:30]: test guile-tests-concurrent-web-server: guile tests/concurrent-web-server.scm 429s autopkgtest [11:23:30]: test guile-tests-concurrent-web-server: [----------------------- 436s assert (length data) equal to 10000: no (got 890) 436s assert (length data) equal to 10000: no (got 896) 436s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 436s ;;; or pass the --no-auto-compile argument to disable. 436s ;;; compiling /tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/concurrent-web-server.scm 436s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.bKY2nv/build.Pg1/src/tests/concurrent-web-server.scm.go 436s autopkgtest [11:23:37]: test guile-tests-concurrent-web-server: -----------------------] 436s guile-tests-concurrent-web-server FAIL non-zero exit status 1 436s autopkgtest [11:23:37]: test guile-tests-concurrent-web-server: - - - - - - - - - - results - - - - - - - - - - 437s autopkgtest [11:23:38]: @@@@@@@@@@@@@@@@@@@@ summary 437s guile-tests-basic PASS 437s guile-tests-conditions PASS 437s guile-tests-channels PASS 437s guile-tests-foreign PASS 437s guile-tests-io-wakeup PASS 437s guile-tests-parameters PASS 437s guile-tests-preemption PASS 437s guile-tests-speedup PASS 437s guile-tests-timer-wheel PASS 437s guile-tests-ports PASS 437s guile-tests-concurrent-web-server FAIL non-zero exit status 1 454s nova [W] Using flock in prodstack6-ppc64el 454s Creating nova instance adt-plucky-ppc64el-guile-fibers-20250313-111621-juju-7f2275-prod-proposed-migration-environment-20-eab44dc1-979d-432b-9782-21b039feabce from image adt/ubuntu-plucky-ppc64el-server-20250313.img (UUID 039b69e3-5f2d-4af8-9d4f-d440c2e5edc9)... 454s nova [W] Timed out waiting for a6a2969c-308e-409c-99c9-fd7e8a913856 to get deleted.