0s autopkgtest [04:47:23]: starting date and time: 2026-02-05 04:47:23+0000 0s autopkgtest [04:47:23]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [04:47:23]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.o6z66jf5/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade guile-fibers --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.42-2ubuntu5 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-ppc64el-7.secgroup --name adt-resolute-ppc64el-guile-fibers-20260205-044723-juju-7f2275-prod-proposed-migration-environment-15-60ac29b1-3a16-4009-8c49-3872fe926c8e --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-ppc64el-guile-fibers-20260205-044723-juju-7f2275-prod-proposed-migration-environment-15-60ac29b1-3a16-4009-8c49-3872fe926c8e from image adt/ubuntu-resolute-ppc64el-server-20260205.img (UUID f866c950-0b62-4023-bac6-0f13279e15ed)... 60s autopkgtest [04:48:23]: testbed dpkg architecture: ppc64el 61s autopkgtest [04:48:24]: testbed apt version: 3.1.14 61s autopkgtest [04:48:24]: @@@@@@@@@@@@@@@@@@@@ test bed setup 61s autopkgtest [04:48:24]: testbed release detected to be: None 62s autopkgtest [04:48:25]: updating testbed package index (apt update) 62s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 62s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 62s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 62s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 62s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1719 kB] 63s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB] 63s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [227 kB] 63s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 63s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [257 kB] 63s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [1449 kB] 63s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [21.6 kB] 63s Fetched 3839 kB in 1s (4900 kB/s) 64s Reading package lists... 65s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 65s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 65s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 65s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 66s Reading package lists... 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Calculating upgrade... 66s The following packages will be upgraded: 66s libc-bin libc-gconv-modules-extra libc6 locales pollinate 66s python3-referencing sed 66s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s Need to get 8612 kB of archives. 66s After this operation, 0 B of additional disk space will be used. 66s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el sed ppc64el 4.9-2build3 [211 kB] 66s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc-gconv-modules-extra ppc64el 2.42-2ubuntu5 [1448 kB] 67s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc6 ppc64el 2.42-2ubuntu5 [1913 kB] 67s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc-bin ppc64el 2.42-2ubuntu5 [748 kB] 67s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el locales all 2.42-2ubuntu5 [4255 kB] 67s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el pollinate all 4.33-4ubuntu5 [14.0 kB] 67s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-referencing all 0.36.2-1ubuntu2 [22.2 kB] 67s dpkg-preconfigure: unable to re-open stdin: No such file or directory 67s Fetched 8612 kB in 1s (12.3 MB/s) 68s (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 ... 82008 files and directories currently installed.) 68s Preparing to unpack .../sed_4.9-2build3_ppc64el.deb ... 68s Unpacking sed (4.9-2build3) over (4.9-2build2) ... 68s Setting up sed (4.9-2build3) ... 68s (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 ... 82008 files and directories currently installed.) 68s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_ppc64el.deb ... 68s Unpacking libc-gconv-modules-extra:ppc64el (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 68s Setting up libc-gconv-modules-extra:ppc64el (2.42-2ubuntu5) ... 68s (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 ... 82008 files and directories currently installed.) 68s Preparing to unpack .../libc6_2.42-2ubuntu5_ppc64el.deb ... 68s Unpacking libc6:ppc64el (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 68s Setting up libc6:ppc64el (2.42-2ubuntu5) ... 69s (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 ... 82008 files and directories currently installed.) 69s Preparing to unpack .../libc-bin_2.42-2ubuntu5_ppc64el.deb ... 69s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 69s Setting up libc-bin (2.42-2ubuntu5) ... 70s (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 ... 82008 files and directories currently installed.) 70s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 70s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 70s Preparing to unpack .../pollinate_4.33-4ubuntu5_all.deb ... 70s Unpacking pollinate (4.33-4ubuntu5) over (4.33-4ubuntu4) ... 70s Preparing to unpack .../python3-referencing_0.36.2-1ubuntu2_all.deb ... 70s Unpacking python3-referencing (0.36.2-1ubuntu2) over (0.36.2-1ubuntu1) ... 70s Setting up locales (2.42-2ubuntu5) ... 71s Generating locales (this might take a while)... 72s en_US.UTF-8... done 72s Generation complete. 73s Setting up pollinate (4.33-4ubuntu5) ... 84s Setting up python3-referencing (0.36.2-1ubuntu2) ... 84s Processing triggers for man-db (2.13.1-1) ... 85s Processing triggers for install-info (7.2-5) ... 86s Processing triggers for systemd (259-1ubuntu3) ... 87s autopkgtest [04:48:50]: upgrading testbed (apt dist-upgrade and autopurge) 87s Reading package lists... 87s Building dependency tree... 87s Reading state information... 87s Calculating upgrade... 87s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 87s Reading package lists... 88s Building dependency tree... 88s Reading state information... 88s Solving dependencies... 88s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 88s autopkgtest [04:48:51]: rebooting testbed after setup commands that affected boot 130s autopkgtest [04:49:33]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:45:54 UTC 2026 133s autopkgtest [04:49:36]: @@@@@@@@@@@@@@@@@@@@ apt-source guile-fibers 135s Get:1 http://ftpmaster.internal/ubuntu resolute/universe guile-fibers 1.3.1-3 (dsc) [2405 B] 135s Get:2 http://ftpmaster.internal/ubuntu resolute/universe guile-fibers 1.3.1-3 (tar) [581 kB] 135s Get:3 http://ftpmaster.internal/ubuntu resolute/universe guile-fibers 1.3.1-3 (diff) [4372 B] 135s gpgv: Signature made Tue Feb 18 08:17:57 2025 UTC 135s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 135s gpgv: issuer "simon@josefsson.org" 135s gpgv: Can't check signature: No public key 135s dpkg-source: warning: cannot verify inline signature for ./guile-fibers_1.3.1-3.dsc: no acceptable signature found 135s autopkgtest [04:49:38]: testing package guile-fibers version 1.3.1-3 135s autopkgtest [04:49:38]: build not needed 135s autopkgtest [04:49:38]: test guile-tests-basic: preparing testbed 136s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s Solving dependencies... 136s The following NEW packages will be installed: 136s guile-3.0 guile-3.0-libs guile-fibers libgc1 136s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 136s Need to get 8465 kB of archives. 136s After this operation, 60.0 MB of additional disk space will be used. 136s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el libgc1 ppc64el 1:8.2.10-1 [113 kB] 136s Get:2 http://ftpmaster.internal/ubuntu resolute/universe ppc64el guile-3.0-libs ppc64el 3.0.11-1 [8002 kB] 136s Get:3 http://ftpmaster.internal/ubuntu resolute/universe ppc64el guile-3.0 ppc64el 3.0.11-1 [17.5 kB] 136s Get:4 http://ftpmaster.internal/ubuntu resolute/universe ppc64el guile-fibers ppc64el 1.3.1-3 [332 kB] 137s Fetched 8465 kB in 0s (27.4 MB/s) 137s Selecting previously unselected package libgc1:ppc64el. 137s (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 ... 82008 files and directories currently installed.) 137s Preparing to unpack .../libgc1_1%3a8.2.10-1_ppc64el.deb ... 137s Unpacking libgc1:ppc64el (1:8.2.10-1) ... 137s Selecting previously unselected package guile-3.0-libs:ppc64el. 137s Preparing to unpack .../guile-3.0-libs_3.0.11-1_ppc64el.deb ... 137s Unpacking guile-3.0-libs:ppc64el (3.0.11-1) ... 137s Selecting previously unselected package guile-3.0. 137s Preparing to unpack .../guile-3.0_3.0.11-1_ppc64el.deb ... 137s Unpacking guile-3.0 (3.0.11-1) ... 137s Selecting previously unselected package guile-fibers. 137s Preparing to unpack .../guile-fibers_1.3.1-3_ppc64el.deb ... 137s Unpacking guile-fibers (1.3.1-3) ... 137s Setting up libgc1:ppc64el (1:8.2.10-1) ... 137s Setting up guile-3.0-libs:ppc64el (3.0.11-1) ... 137s Setting up guile-3.0 (3.0.11-1) ... 137s update-alternatives: using /usr/lib/powerpc64le-linux-gnu/guile/3.0/bin/guile to provide /usr/bin/guile (guile) in auto mode 137s Setting up guile-fibers (1.3.1-3) ... 137s Processing triggers for libc-bin (2.42-2ubuntu5) ... 137s Processing triggers for man-db (2.13.1-1) ... 138s Processing triggers for install-info (7.2-5) ... 139s autopkgtest [04:49:42]: test guile-tests-basic: guile tests/basic.scm 139s autopkgtest [04:49:42]: test guile-tests-basic: [----------------------- 143s assert #f equal to #f: ok 143s assert #t terminates: ok 143s assert (false-if-exception (begin (run-fibers) #t)) equal to #f: ok 144s assert terminates: (run-fibers (lambda () (sleep 1)) #:drain? #t): ok (1.008211803 s) 144s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (9.45104e-4 s) 144s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (4.79766e-4 s) 144s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (6.61733e-4 s) 144s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.002481178 s) 144s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.025436865 s) 145s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.311203959 s) 145s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t))) #:drain? #t): ok (0.358890908 s) 167s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4))) #:drain? #t): ok (22.290167395 s) 178s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4 #:parallel? #t))) #:drain? #t): ok (11.109719943 s) 179s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001435006 s) 180s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001664549 s) 181s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.002715189 s) 182s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.008982512 s) 183s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.088895078 s) 185s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.888561824 s) 199s assert terminates: (run-fibers (lambda () (do-times 1000000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (13.832231252 s) 200s assert terminates: (run-fibers (lambda () (spawn-fiber-tree 7 (lambda () (sleep 1)))) #:drain? #t): ok (1.06594098 s) 200s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5)) #:drain? #t): ok (8.12539e-4 s) 200s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50)) #:drain? #t): ok (0.001435699 s) 200s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500)) #:drain? #t): ok (0.007693286 s) 200s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000)) #:drain? #t): ok (0.065010268 s) 201s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50000)) #:drain? #t): ok (0.53209058 s) 206s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500000)) #:drain? #t): ok (5.053577608 s) 255s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000000)) #:drain? #t): ok (49.273209943 s) 255s assert terminates: (run-fibers (lambda () (test-run-order 10)) #:parallelism 1 #:drain? #t): ok (0.012828011 s) 255s assert terminates: (run-fibers (lambda () (test-wakeup-order 10)) #:parallelism 1 #:drain? #t): ok (3.76385e-4 s) 255s assert terminates: (run-fibers (lambda () 1) #:drain? #t): ok (5.01088e-4 s) 255s assert run-fiber-return-vals equal to (1): ok 256s assert terminates: (run-fibers (lambda () (do-times 20 (check-sleep (random 1.0)))) #:drain? #t): assert sleep 0.020608996598312723s < actual 0.021140386s: #t (diff: 2.578434127796317%) 256s assert sleep 0.03456686196908243s < actual 0.034726813s: #t (diff: 0.46272939400931795%) 256s assert sleep 0.061024566720115414s < actual 0.061500367s: #t (diff: 0.7796864532718575%) 256s assert sleep 0.11746097407324547s < actual 0.117700924s: #t (diff: 0.20428055245387058%) 256s assert sleep 0.13706411233780896s < actual 0.137609625s: #t (diff: 0.3979981724512807%) 256s assert sleep 0.2275100133355504s < actual 0.227836846s: #t (diff: 0.14365638666090974%) 256s assert sleep 0.23514019199674957s < actual 0.23563525s: #t (diff: 0.21053738157076257%) 256s assert sleep 0.3507339219939192s < actual 0.350955037s: #t (diff: 0.06304351880873546%) 256s assert sleep 0.36970041583284435s < actual 0.369808495s: #t (diff: 0.02923425631323284%) 256s assert sleep 0.5002849968690339s < actual 0.500410881s: #t (diff: 0.02516248373504406%) 256s assert sleep 0.6727431540500131s < actual 0.672860291s: #t (diff: 0.01741183827464589%) 256s assert sleep 0.7000185262935479s < actual 0.700146521s: #t (diff: 0.01828447414524712%) 256s assert sleep 0.7148624304124437s < actual 0.715266875s: #t (diff: 0.05657656219574146%) 256s assert sleep 0.721997240644088s < actual 0.722118393s: #t (diff: 0.01678016882778614%) 256s assert sleep 0.7233843607837082s < actual 0.723614692s: #t (diff: 0.031840779090420414%) 256s assert sleep 0.7603254888022394s < actual 0.76040611s: #t (diff: 0.010603511120960684%) 256s assert sleep 0.7965046199252751s < actual 0.797105732s: #t (diff: 0.07546874929379589%) 256s assert sleep 0.8067622687831747s < actual 0.807044338s: #t (diff: 0.03496311462987924%) 256s assert sleep 0.9299942644443119s < actual 0.930252508s: #t (diff: 0.027768295521957722%) 256s assert sleep 0.999598330708636s < actual 1.000005443s: #t (diff: 0.04072758815787746%) 256s ok (1.000990802 s) 256s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 256s ;;; or pass the --no-auto-compile argument to disable. 256s ;;; compiling /tmp/autopkgtest.q1ILsc/build.aYf/src/tests/basic.scm 256s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 256s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-LE-8-4.7/tmp/autopkgtest.q1ILsc/build.aYf/src/tests/basic.scm.go 256s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 256s autopkgtest [04:51:39]: test guile-tests-basic: -----------------------] 257s autopkgtest [04:51:40]: test guile-tests-basic: - - - - - - - - - - results - - - - - - - - - - 257s guile-tests-basic PASS 257s autopkgtest [04:51:40]: @@@@@@@@@@@@@@@@@@@@ summary 257s guile-tests-basic PASS