1s autopkgtest [02:16:09]: starting date and time: 2026-02-05 02:16:09+0000 1s autopkgtest [02:16:09]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 1s autopkgtest [02:16:09]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.oeq02rel/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc,src:chiark-tcl --apt-upgrade guile-fibers --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=glibc/2.42-2ubuntu5 chiark-tcl/1.3.7build1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-arm64-30.secgroup --name adt-resolute-arm64-guile-fibers-20260205-021608-juju-7f2275-prod-proposed-migration-environment-15-241b9376-e4a1-4e85-b78a-1f8bc157a37c --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-arm64-guile-fibers-20260205-021608-juju-7f2275-prod-proposed-migration-environment-15-241b9376-e4a1-4e85-b78a-1f8bc157a37c from image adt/ubuntu-resolute-arm64-server-20260204.img (UUID f58d981d-b271-4157-b9b1-fd704695563c)... 166s autopkgtest [02:18:54]: testbed dpkg architecture: arm64 174s autopkgtest [02:19:02]: testbed apt version: 3.1.14 182s autopkgtest [02:19:10]: @@@@@@@@@@@@@@@@@@@@ test bed setup 196s autopkgtest [02:19:24]: testbed release detected to be: None 209s autopkgtest [02:19:37]: updating testbed package index (apt update) 211s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 211s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 211s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 211s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 211s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1719 kB] 211s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [227 kB] 211s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB] 212s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 212s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [265 kB] 212s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [7328 B] 212s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [52.9 kB] 212s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [328 B] 212s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [1481 kB] 212s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [40.2 kB] 212s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [26.0 kB] 212s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [776 B] 216s Fetched 3984 kB in 1s (2920 kB/s) 216s Reading package lists... 227s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 227s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 227s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 227s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 228s Reading package lists... 228s Reading package lists... 228s Building dependency tree... 228s Reading state information... 229s Calculating upgrade... 229s The following packages will be upgraded: 229s libc-bin libc-gconv-modules-extra libc6 locales pollinate python3-linkify-it 229s python3-referencing sed 229s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 229s Need to get 8110 kB of archives. 229s After this operation, 0 B of additional disk space will be used. 229s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 sed arm64 4.9-2build3 [193 kB] 229s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-gconv-modules-extra arm64 2.42-2ubuntu5 [1413 kB] 229s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc6 arm64 2.42-2ubuntu5 [1594 kB] 230s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-bin arm64 2.42-2ubuntu5 [599 kB] 230s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 locales all 2.42-2ubuntu5 [4255 kB] 230s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-linkify-it all 2.0.3-1ubuntu3 [19.4 kB] 230s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 pollinate all 4.33-4ubuntu5 [14.0 kB] 230s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-referencing all 0.36.2-1ubuntu2 [22.2 kB] 230s dpkg-preconfigure: unable to re-open stdin: No such file or directory 230s Fetched 8110 kB in 1s (14.1 MB/s) 230s (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 ... 89360 files and directories currently installed.) 230s Preparing to unpack .../sed_4.9-2build3_arm64.deb ... 230s Unpacking sed (4.9-2build3) over (4.9-2build2) ... 230s Setting up sed (4.9-2build3) ... 230s (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 ... 89360 files and directories currently installed.) 230s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_arm64.deb ... 230s Unpacking libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 231s Setting up libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) ... 231s (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 ... 89360 files and directories currently installed.) 231s Preparing to unpack .../libc6_2.42-2ubuntu5_arm64.deb ... 231s Unpacking libc6:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 231s Setting up libc6:arm64 (2.42-2ubuntu5) ... 231s (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 ... 89360 files and directories currently installed.) 231s Preparing to unpack .../libc-bin_2.42-2ubuntu5_arm64.deb ... 231s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 231s Setting up libc-bin (2.42-2ubuntu5) ... 231s (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 ... 89360 files and directories currently installed.) 231s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 231s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 232s Preparing to unpack .../python3-linkify-it_2.0.3-1ubuntu3_all.deb ... 232s Unpacking python3-linkify-it (2.0.3-1ubuntu3) over (2.0.3-1ubuntu2) ... 232s Preparing to unpack .../pollinate_4.33-4ubuntu5_all.deb ... 232s Unpacking pollinate (4.33-4ubuntu5) over (4.33-4ubuntu4) ... 232s Preparing to unpack .../python3-referencing_0.36.2-1ubuntu2_all.deb ... 232s Unpacking python3-referencing (0.36.2-1ubuntu2) over (0.36.2-1ubuntu1) ... 232s Setting up locales (2.42-2ubuntu5) ... 233s Generating locales (this might take a while)... 235s en_US.UTF-8... done 235s Generation complete. 235s Setting up pollinate (4.33-4ubuntu5) ... 245s Setting up python3-linkify-it (2.0.3-1ubuntu3) ... 246s Setting up python3-referencing (0.36.2-1ubuntu2) ... 246s Processing triggers for man-db (2.13.1-1) ... 247s Processing triggers for install-info (7.2-5) ... 247s Processing triggers for systemd (259-1ubuntu3) ... 248s autopkgtest [02:20:16]: upgrading testbed (apt dist-upgrade and autopurge) 249s Reading package lists... 249s Building dependency tree... 249s Reading state information... 250s Calculating upgrade... 250s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 250s Reading package lists... 251s Building dependency tree... 251s Reading state information... 251s Solving dependencies... 251s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 252s autopkgtest [02:20:20]: rebooting testbed after setup commands that affected boot 290s autopkgtest [02:20:58]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:41:39 UTC 2026 292s autopkgtest [02:21:00]: @@@@@@@@@@@@@@@@@@@@ apt-source guile-fibers 295s Get:1 http://ftpmaster.internal/ubuntu resolute/universe guile-fibers 1.3.1-3 (dsc) [2405 B] 295s Get:2 http://ftpmaster.internal/ubuntu resolute/universe guile-fibers 1.3.1-3 (tar) [581 kB] 295s Get:3 http://ftpmaster.internal/ubuntu resolute/universe guile-fibers 1.3.1-3 (diff) [4372 B] 295s gpgv: Signature made Tue Feb 18 08:17:57 2025 UTC 295s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 295s gpgv: issuer "simon@josefsson.org" 295s gpgv: Can't check signature: No public key 295s dpkg-source: warning: cannot verify inline signature for ./guile-fibers_1.3.1-3.dsc: no acceptable signature found 295s autopkgtest [02:21:03]: testing package guile-fibers version 1.3.1-3 295s autopkgtest [02:21:03]: build not needed 295s autopkgtest [02:21:03]: test guile-tests-basic: preparing testbed 296s Reading package lists... 296s Building dependency tree... 296s Reading state information... 296s Solving dependencies... 296s The following NEW packages will be installed: 296s guile-3.0 guile-3.0-libs guile-fibers libgc1 297s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 297s Need to get 8389 kB of archives. 297s After this operation, 59.7 MB of additional disk space will be used. 297s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libgc1 arm64 1:8.2.10-1 [95.9 kB] 297s Get:2 http://ftpmaster.internal/ubuntu resolute/universe arm64 guile-3.0-libs arm64 3.0.11-1 [7944 kB] 298s Get:3 http://ftpmaster.internal/ubuntu resolute/universe arm64 guile-3.0 arm64 3.0.11-1 [17.5 kB] 298s Get:4 http://ftpmaster.internal/ubuntu resolute/universe arm64 guile-fibers arm64 1.3.1-3 [332 kB] 298s Fetched 8389 kB in 2s (5250 kB/s) 298s Selecting previously unselected package libgc1:arm64. 299s (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 ... 89360 files and directories currently installed.) 299s Preparing to unpack .../libgc1_1%3a8.2.10-1_arm64.deb ... 299s Unpacking libgc1:arm64 (1:8.2.10-1) ... 299s Selecting previously unselected package guile-3.0-libs:arm64. 299s Preparing to unpack .../guile-3.0-libs_3.0.11-1_arm64.deb ... 299s Unpacking guile-3.0-libs:arm64 (3.0.11-1) ... 299s Selecting previously unselected package guile-3.0. 299s Preparing to unpack .../guile-3.0_3.0.11-1_arm64.deb ... 299s Unpacking guile-3.0 (3.0.11-1) ... 299s Selecting previously unselected package guile-fibers. 299s Preparing to unpack .../guile-fibers_1.3.1-3_arm64.deb ... 299s Unpacking guile-fibers (1.3.1-3) ... 299s Setting up libgc1:arm64 (1:8.2.10-1) ... 299s Setting up guile-3.0-libs:arm64 (3.0.11-1) ... 299s Setting up guile-3.0 (3.0.11-1) ... 299s update-alternatives: using /usr/lib/aarch64-linux-gnu/guile/3.0/bin/guile to provide /usr/bin/guile (guile) in auto mode 299s Setting up guile-fibers (1.3.1-3) ... 299s Processing triggers for libc-bin (2.42-2ubuntu5) ... 299s Processing triggers for man-db (2.13.1-1) ... 300s Processing triggers for install-info (7.2-5) ... 301s autopkgtest [02:21:09]: test guile-tests-basic: guile tests/basic.scm 301s autopkgtest [02:21:09]: test guile-tests-basic: [----------------------- 302s assert #f equal to #f: ok 302s assert #t terminates: ok 302s assert (false-if-exception (begin (run-fibers) #t)) equal to #f: ok 303s assert terminates: (run-fibers (lambda () (sleep 1)) #:drain? #t): ok (1.00200088 s) 303s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (9.6044e-4 s) 303s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (7.7952e-4 s) 303s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.00102392 s) 303s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.00177456 s) 303s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.00849976 s) 303s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.16485996 s) 303s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t))) #:drain? #t): ok (0.12685928 s) 306s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4))) #:drain? #t): ok (1.93155932 s) 306s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4 #:parallel? #t))) #:drain? #t): ok (1.00522032 s) 307s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.0081782 s) 308s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.00147772 s) 309s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.00257436 s) 310s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.00320844 s) 311s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.01946268 s) 312s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.3159426 s) 317s assert terminates: (run-fibers (lambda () (do-times 1000000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (4.64329132 s) 318s assert terminates: (run-fibers (lambda () (spawn-fiber-tree 7 (lambda () (sleep 1)))) #:drain? #t): ok (1.02082052 s) 318s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5)) #:drain? #t): ok (6.9392e-4 s) 318s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50)) #:drain? #t): ok (7.3544e-4 s) 318s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500)) #:drain? #t): ok (0.0018238 s) 318s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000)) #:drain? #t): ok (0.01242532 s) 318s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50000)) #:drain? #t): ok (0.11579812 s) 319s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500000)) #:drain? #t): ok (1.19962296 s) 331s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000000)) #:drain? #t): ok (11.65199692 s) 331s assert terminates: (run-fibers (lambda () (test-run-order 10)) #:parallelism 1 #:drain? #t): ok (0.0311814 s) 331s assert terminates: (run-fibers (lambda () (test-wakeup-order 10)) #:parallelism 1 #:drain? #t): ok (3.0372e-4 s) 331s assert terminates: (run-fibers (lambda () 1) #:drain? #t): ok (7.0368e-4 s) 331s assert run-fiber-return-vals equal to (1): ok 332s assert terminates: (run-fibers (lambda () (do-times 20 (check-sleep (random 1.0)))) #:drain? #t): assert sleep 0.020608996598312723s < actual 0.0211456s: #t (diff: 2.6037337583490574%) 332s assert sleep 0.03456686196908243s < actual 0.0347578s: #t (diff: 0.5523730533837554%) 332s assert sleep 0.061024566720115414s < actual 0.06151472s: #t (diff: 0.8032064891712203%) 332s assert sleep 0.11746097407324547s < actual 0.11763396s: #t (diff: 0.14727097925022445%) 332s assert sleep 0.13706411233780896s < actual 0.13756604s: #t (diff: 0.3661991849142711%) 332s assert sleep 0.2275100133355504s < actual 0.22776396s: #t (diff: 0.11161999453406134%) 332s assert sleep 0.23514019199674957s < actual 0.23558852s: #t (diff: 0.19066413080781314%) 332s assert sleep 0.3507339219939192s < actual 0.35106612s: #t (diff: 0.09471510602460853%) 332s assert sleep 0.36970041583284435s < actual 0.36979936s: #t (diff: 0.026763336722997987%) 332s assert sleep 0.5002849968690339s < actual 0.50035768s: #t (diff: 0.01452834512747068%) 332s assert sleep 0.6727431540500131s < actual 0.67284588s: #t (diff: 0.015269713168914883%) 332s assert sleep 0.7000185262935479s < actual 0.70010696s: #t (diff: 0.012633052287958782%) 332s assert sleep 0.7148624304124437s < actual 0.7152938s: #t (diff: 0.06034302114707793%) 332s assert sleep 0.721997240644088s < actual 0.72211548s: #t (diff: 0.016376704681940565%) 332s assert sleep 0.7233843607837082s < actual 0.72367084s: #t (diff: 0.039602627845245424%) 332s assert sleep 0.7603254888022394s < actual 0.76039388s: #t (diff: 0.008994989483822923%) 332s assert sleep 0.7965046199252751s < actual 0.7965928s: #t (diff: 0.011070880509539154%) 332s assert sleep 0.8067622687831747s < actual 0.80704592s: #t (diff: 0.03515920709245637%) 332s assert sleep 0.9299942644443119s < actual 0.9302552s: #t (diff: 0.028057759672741902%) 332s assert sleep 0.999598330708636s < actual 0.9999898s: #t (diff: 0.039162659574121225%) 332s ok (1.0008066 s) 332s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 332s ;;; or pass the --no-auto-compile argument to disable. 332s ;;; compiling /tmp/autopkgtest.cGuziR/build.YXh/src/tests/basic.scm 332s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 332s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.cGuziR/build.YXh/src/tests/basic.scm.go 332s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 332s autopkgtest [02:21:40]: test guile-tests-basic: -----------------------] 333s guile-tests-basic PASS 333s autopkgtest [02:21:41]: test guile-tests-basic: - - - - - - - - - - results - - - - - - - - - - 333s autopkgtest [02:21:41]: @@@@@@@@@@@@@@@@@@@@ summary 333s guile-tests-basic PASS