0s autopkgtest [10:47:52]: starting date and time: 2025-03-13 10:47:52+0000 0s autopkgtest [10:47:52]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [10:47:52]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.hs_lccmh/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-4.secgroup --name adt-plucky-s390x-guile-fibers-20250313-104752-juju-7f2275-prod-proposed-migration-environment-2-c955e496-b4a9-4952-b57d-6ed0b3b1bd18 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -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/ 109s autopkgtest [10:49:41]: testbed dpkg architecture: s390x 109s autopkgtest [10:49:41]: testbed apt version: 2.9.32ubuntu1 110s autopkgtest [10:49:42]: @@@@@@@@@@@@@@@@@@@@ test bed setup 110s autopkgtest [10:49:42]: testbed release detected to be: None 111s autopkgtest [10:49:43]: updating testbed package index (apt update) 111s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 111s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 111s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 111s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 111s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [43.9 kB] 111s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [396 kB] 112s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [11.5 kB] 112s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [57.9 kB] 112s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [313 kB] 112s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [2512 B] 112s Fetched 936 kB in 1s (1087 kB/s) 112s Reading package lists... 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s Calculating upgrade... 113s Calculating upgrade... 113s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s Solving dependencies... 114s The following packages will be REMOVED: 114s linux-image-6.11.0-8-generic* 114s 0 upgraded, 0 newly installed, 1 to remove and 0 not upgraded. 114s After this operation, 10.5 MB disk space will be freed. 114s (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 ... 81495 files and directories currently installed.) 114s Removing linux-image-6.11.0-8-generic (6.11.0-8.8) ... 114s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-7-generic 114s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-7-generic 114s /etc/kernel/postrm.d/initramfs-tools: 114s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 114s /etc/kernel/postrm.d/zz-zipl: 114s Using config file '/etc/zipl.conf' 114s Building bootmap in '/boot' 114s Adding IPL section 'ubuntu' (default) 114s Preparing boot device for LD-IPL: vda (0000). 114s Done. 114s (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 ... 81491 files and directories currently installed.) 114s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8) ... 114s rmdir: failed to remove '/lib/modules/6.11.0-8-generic': Directory not empty 114s autopkgtest [10:49:46]: upgrading testbed (apt dist-upgrade and autopurge) 114s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 115s Starting 2 pkgProblemResolver with broken count: 0 115s Done 115s Entering ResolveByKeep 115s 115s Calculating upgrade... 115s The following packages were automatically installed and are no longer required: 115s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 115s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 115s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 115s linux-tools-6.11.0-8-generic 115s Use 'sudo apt autoremove' to remove them. 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Reading package lists... 116s Building dependency tree... 116s Reading state information... 116s Starting pkgProblemResolver with broken count: 0 116s Starting 2 pkgProblemResolver with broken count: 0 116s Done 116s Solving dependencies... 116s The following packages will be REMOVED: 116s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 116s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 116s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 116s linux-tools-6.11.0-8-generic* 116s 0 upgraded, 0 newly installed, 9 to remove and 0 not upgraded. 116s After this operation, 167 MB disk space will be freed. 116s (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 ... 81491 files and directories currently installed.) 116s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 116s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 116s Removing libpython3.12t64:s390x (3.12.9-1) ... 116s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 116s Removing libnsl2:s390x (1.3.0-3build3) ... 116s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 116s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 116s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 117s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 117s Processing triggers for libc-bin (2.41-1ubuntu1) ... 117s (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 ... 56391 files and directories currently installed.) 117s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 117s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 118s autopkgtest [10:49:50]: rebooting testbed after setup commands that affected boot 138s autopkgtest [10:50:10]: testbed running kernel: Linux 6.14.0-7-generic #7-Ubuntu SMP Fri Feb 28 10:57:32 UTC 2025 141s autopkgtest [10:50:13]: @@@@@@@@@@@@@@@@@@@@ apt-source guile-fibers 143s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (dsc) [2405 B] 143s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (tar) [581 kB] 143s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (diff) [4516 B] 143s gpgv: Signature made Thu Feb 20 07:20:23 2025 UTC 143s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 143s gpgv: issuer "simon@josefsson.org" 143s gpgv: Can't check signature: No public key 143s dpkg-source: warning: cannot verify inline signature for ./guile-fibers_1.3.1-4.dsc: no acceptable signature found 143s autopkgtest [10:50:15]: testing package guile-fibers version 1.3.1-4 144s autopkgtest [10:50:16]: build not needed 146s autopkgtest [10:50:18]: test guile-tests-basic: preparing testbed 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 146s Starting pkgProblemResolver with broken count: 0 146s Starting 2 pkgProblemResolver with broken count: 0 146s Done 147s The following NEW packages will be installed: 147s guile-3.0 guile-3.0-libs guile-fibers libgc1 147s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 147s Need to get 8331 kB of archives. 147s After this operation, 59.6 MB of additional disk space will be used. 147s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libgc1 s390x 1:8.2.8-1 [93.7 kB] 147s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x guile-3.0-libs s390x 3.0.10+really3.0.10-4 [7889 kB] 148s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x guile-3.0 s390x 3.0.10+really3.0.10-4 [16.4 kB] 148s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x guile-fibers s390x 1.3.1-4 [332 kB] 148s Fetched 8331 kB in 1s (7149 kB/s) 148s Selecting previously unselected package libgc1:s390x. 148s (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 ... 56389 files and directories currently installed.) 148s Preparing to unpack .../libgc1_1%3a8.2.8-1_s390x.deb ... 148s Unpacking libgc1:s390x (1:8.2.8-1) ... 148s Selecting previously unselected package guile-3.0-libs:s390x. 148s Preparing to unpack .../guile-3.0-libs_3.0.10+really3.0.10-4_s390x.deb ... 148s Unpacking guile-3.0-libs:s390x (3.0.10+really3.0.10-4) ... 148s Selecting previously unselected package guile-3.0. 148s Preparing to unpack .../guile-3.0_3.0.10+really3.0.10-4_s390x.deb ... 148s Unpacking guile-3.0 (3.0.10+really3.0.10-4) ... 148s Selecting previously unselected package guile-fibers. 148s Preparing to unpack .../guile-fibers_1.3.1-4_s390x.deb ... 148s Unpacking guile-fibers (1.3.1-4) ... 148s Setting up libgc1:s390x (1:8.2.8-1) ... 148s Setting up guile-3.0-libs:s390x (3.0.10+really3.0.10-4) ... 148s Setting up guile-3.0 (3.0.10+really3.0.10-4) ... 148s update-alternatives: using /usr/lib/s390x-linux-gnu/guile/3.0/bin/guile to provide /usr/bin/guile (guile) in auto mode 148s Setting up guile-fibers (1.3.1-4) ... 148s Processing triggers for libc-bin (2.41-1ubuntu1) ... 148s Processing triggers for man-db (2.13.0-1) ... 149s Processing triggers for install-info (7.1.1-1) ... 150s autopkgtest [10:50:22]: test guile-tests-basic: guile tests/basic.scm 150s autopkgtest [10:50:22]: test guile-tests-basic: [----------------------- 152s assert #f equal to #f: ok 152s assert #t terminates: ok 152s assert (false-if-exception (begin (run-fibers) #t)) equal to #f: ok 153s assert terminates: (run-fibers (lambda () (sleep 1)) #:drain? #t): ok (1.001297957 s) 153s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (5.54865e-4 s) 153s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (3.29368e-4 s) 153s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (3.98556e-4 s) 153s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.001421885 s) 153s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.011375689 s) 153s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.169449584 s) 154s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t))) #:drain? #t): ok (0.140639567 s) 159s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4))) #:drain? #t): ok (5.384789784 s) 162s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4 #:parallel? #t))) #:drain? #t): ok (2.717844924 s) 163s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001147113 s) 164s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001302015 s) 165s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001756668 s) 251s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.088403698 s) 252s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.04007222 s) 254s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.407589124 s) 261s assert terminates: (run-fibers (lambda () (do-times 1000000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (6.997408684 s) 262s assert terminates: (run-fibers (lambda () (spawn-fiber-tree 7 (lambda () (sleep 1)))) #:drain? #t): ok (1.042737442 s) 262s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5)) #:drain? #t): ok (0.008923852 s) 262s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50)) #:drain? #t): ok (4.36403e-4 s) 262s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500)) #:drain? #t): ok (0.002389248 s) 262s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000)) #:drain? #t): ok (0.020750227 s) 262s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50000)) #:drain? #t): ok (0.205408655 s) 264s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500000)) #:drain? #t): ok (2.05291784 s) 284s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000000)) #:drain? #t): ok (19.964555506 s) 284s assert terminates: (run-fibers (lambda () (test-run-order 10)) #:parallelism 1 #:drain? #t): ok (0.014325427 s) 284s assert terminates: (run-fibers (lambda () (test-wakeup-order 10)) #:parallelism 1 #:drain? #t): ok (1.48845e-4 s) 284s assert terminates: (run-fibers (lambda () 1) #:drain? #t): ok (2.38566e-4 s) 284s assert run-fiber-return-vals equal to (1): ok 285s assert terminates: (run-fibers (lambda () (do-times 20 (check-sleep (random 1.0)))) #:drain? #t): assert sleep 0.020608996598312723s < actual 0.021039213s: #t (diff: 2.0875174569270394%) 285s assert sleep 0.03456686196908243s < actual 0.034651683s: #t (diff: 0.24538250244826731%) 285s assert sleep 0.061024566720115414s < actual 0.061405022s: #t (diff: 0.6234460977486528%) 285s assert sleep 0.11746097407324547s < actual 0.117534231s: #t (diff: 0.0623670349514103%) 285s assert sleep 0.13706411233780896s < actual 0.137468545s: #t (diff: 0.29506823871902177%) 285s assert sleep 0.2275100133355504s < actual 0.227671473s: #t (diff: 0.07096815743730593%) 285s assert sleep 0.23514019199674957s < actual 0.235502989s: #t (diff: 0.15428966021063759%) 285s assert sleep 0.3507339219939192s < actual 0.35081709s: #t (diff: 0.023712564102159127%) 285s assert sleep 0.36970041583284435s < actual 0.3702299s: #t (diff: 0.1432197921559945%) 285s assert sleep 0.5002849968690339s < actual 0.500808752s: #t (diff: 0.10469135277769294%) 285s assert sleep 0.6727431540500131s < actual 0.67326998s: #t (diff: 0.07831011684257455%) 285s assert sleep 0.7000185262935479s < actual 0.700534372s: #t (diff: 0.07369029348172101%) 285s assert sleep 0.7148624304124437s < actual 0.715181495s: #t (diff: 0.0446330054542348%) 285s assert sleep 0.721997240644088s < actual 0.722011342s: #t (diff: 0.001953103851113815%) 285s assert sleep 0.7233843607837082s < actual 0.723571612s: #t (diff: 0.02588543884041824%) 285s assert sleep 0.7603254888022394s < actual 0.76081476s: #t (diff: 0.0643502296011957%) 285s assert sleep 0.7965046199252751s < actual 0.796963386s: #t (diff: 0.057597415413357854%) 285s assert sleep 0.8067622687831747s < actual 0.806948749s: #t (diff: 0.023114642818706576%) 285s assert sleep 0.9299942644443119s < actual 0.930302586s: #t (diff: 0.033153059914018936%) 285s assert sleep 0.999598330708636s < actual 0.99991096s: #t (diff: 0.03127549154092402%) 285s ok (1.000451835 s) 285s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 285s ;;; or pass the --no-auto-compile argument to disable. 285s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/basic.scm 285s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 285s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/basic.scm.go 285s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 285s autopkgtest [10:52:37]: test guile-tests-basic: -----------------------] 286s autopkgtest [10:52:38]: test guile-tests-basic: - - - - - - - - - - results - - - - - - - - - - 286s guile-tests-basic PASS 286s autopkgtest [10:52:38]: test guile-tests-conditions: preparing testbed 287s Reading package lists... 287s Building dependency tree... 287s Reading state information... 287s Starting pkgProblemResolver with broken count: 0 287s Starting 2 pkgProblemResolver with broken count: 0 287s Done 287s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 288s autopkgtest [10:52:40]: test guile-tests-conditions: guile tests/conditions.scm 288s autopkgtest [10:52:40]: test guile-tests-conditions: [----------------------- 289s assert (condition? cv) equal to #t: ok 289s assert run-fibers on (wait/timeout cv) terminates: ok (0.051540029 s) 289s assert run-fiber-return-vals equal to (#f): ok 289s assert run-fibers on (wait/timeout cv) terminates: ok (0.050752453 s) 289s assert run-fiber-return-vals equal to (#f): ok 289s assert (signal-condition! cv) equal to #t: ok 289s assert (signal-condition! cv) equal to #f: ok 289s assert run-fibers on (wait/timeout cv) terminates: ok (2.66185e-4 s) 289s assert run-fiber-return-vals equal to (#t): ok 289s assert run-fibers on (wait/timeout cv) terminates: ok (2.66324e-4 s) 289s assert run-fiber-return-vals equal to (#t): ok 289s assert run-fibers on (let ((cv (make-condition))) (spawn-fiber (lambda () (signal-condition! cv))) (wait cv) #t) terminates: ok (2.76501e-4 s) 289s assert run-fiber-return-vals equal to (#t): ok 289s 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 289s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 289s ;;; or pass the --no-auto-compile argument to disable. 289s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/conditions.scm 289s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/conditions.scm.go 290s autopkgtest [10:52:42]: test guile-tests-conditions: -----------------------] 290s autopkgtest [10:52:42]: test guile-tests-conditions: - - - - - - - - - - results - - - - - - - - - - 290s guile-tests-conditions PASS 291s autopkgtest [10:52:43]: test guile-tests-channels: preparing testbed 291s Reading package lists... 291s Building dependency tree... 291s Reading state information... 291s Starting pkgProblemResolver with broken count: 0 291s Starting 2 pkgProblemResolver with broken count: 0 291s Done 292s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 292s autopkgtest [10:52:44]: test guile-tests-channels: guile tests/channels.scm 292s autopkgtest [10:52:44]: test guile-tests-channels: [----------------------- 293s assert run-fibers on (rpc 1) terminates: ok (8.38447e-4 s) 293s assert run-fiber-return-vals equal to (1): ok 295s assert run-fibers on (rpc-fib 24) terminates: ok (1.890724634 s) 295s assert run-fiber-return-vals equal to (75025): ok 295s assert run-fibers on (pingpong (current-processor-count) 1000) terminates: ok (0.02066838 s) 295s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 295s ;;; or pass the --no-auto-compile argument to disable. 295s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/channels.scm 295s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/channels.scm.go 296s autopkgtest [10:52:48]: test guile-tests-channels: -----------------------] 296s guile-tests-channels PASS 296s autopkgtest [10:52:48]: test guile-tests-channels: - - - - - - - - - - results - - - - - - - - - - 296s autopkgtest [10:52:48]: test guile-tests-foreign: preparing testbed 297s Reading package lists... 297s Building dependency tree... 297s Reading state information... 297s Starting pkgProblemResolver with broken count: 0 297s Starting 2 pkgProblemResolver with broken count: 0 297s Done 297s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 298s autopkgtest [10:52:50]: test guile-tests-foreign: guile tests/foreign.scm 298s autopkgtest [10:52:50]: test guile-tests-foreign: [----------------------- 298s assert #f equal to #f: ok 298s assert #t terminates: ok 299s assert (sleep 1) terminates: ok 300s assert (perform-operation (sleep-operation 1)) terminates: ok 300s assert (receive-from-fiber 42) equal to 42: ok 300s assert (send-to-fiber 42) equal to 42: ok 300s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 300s ;;; or pass the --no-auto-compile argument to disable. 300s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/foreign.scm 300s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 300s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/foreign.scm.go 300s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 301s autopkgtest [10:52:53]: test guile-tests-foreign: -----------------------] 301s autopkgtest [10:52:53]: test guile-tests-foreign: - - - - - - - - - - results - - - - - - - - - - 301s guile-tests-foreign PASS 302s autopkgtest [10:52:54]: test guile-tests-io-wakeup: preparing testbed 302s Reading package lists... 302s Building dependency tree... 302s Reading state information... 302s Starting pkgProblemResolver with broken count: 0 302s Starting 2 pkgProblemResolver with broken count: 0 302s Done 302s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 303s autopkgtest [10:52:55]: test guile-tests-io-wakeup: guile tests/io-wakeup.scm 303s autopkgtest [10:52:55]: test guile-tests-io-wakeup: [----------------------- 304s assert run-fibers on (readable/timeout? s) terminates: ok (0.050876757 s) 304s assert run-fiber-return-vals equal to (#t): ok 304s assert (readable/timeout? s) equal to #t: ok 304s assert run-fibers on (readable/timeout? A) terminates: ok (0.050824979 s) 304s assert run-fiber-return-vals equal to (#t): ok 304s assert (readable/timeout? A) equal to #t: ok 304s assert run-fibers on (writable/timeout? B) terminates: ok (5.69195e-4 s) 304s assert run-fiber-return-vals equal to (#f): ok 304s assert (writable/timeout? B) equal to #f: ok 304s assert run-fibers on (writable/timeout? B) terminates: ok (0.050766609 s) 304s assert run-fiber-return-vals equal to (#t): ok 304s assert run-fibers on (readable/timeout? A) terminates: ok (5.33856e-4 s) 304s assert run-fiber-return-vals equal to (#f): ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 304s ;;; or pass the --no-auto-compile argument to disable. 304s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/io-wakeup.scm 304s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/io-wakeup.scm.go 304s ok 305s autopkgtest [10:52:57]: test guile-tests-io-wakeup: -----------------------] 305s guile-tests-io-wakeup PASS 305s autopkgtest [10:52:57]: test guile-tests-io-wakeup: - - - - - - - - - - results - - - - - - - - - - 306s autopkgtest [10:52:58]: test guile-tests-parameters: preparing testbed 306s Reading package lists... 306s Building dependency tree... 306s Reading state information... 306s Starting pkgProblemResolver with broken count: 0 306s Starting 2 pkgProblemResolver with broken count: 0 306s Done 306s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 307s autopkgtest [10:52:59]: test guile-tests-parameters: guile tests/parameters.scm 307s autopkgtest [10:52:59]: test guile-tests-parameters: [----------------------- 10307s assert run-fibers on (my-param) terminates: autopkgtest [13:39:39]: ERROR: timed out on command "su -s /bin/bash ubuntu -c set -e; exec /tmp/autopkgtest.vD2RU8/wrapper.sh --artifacts=/tmp/autopkgtest.vD2RU8/guile-tests-parameters-artifacts --chdir=/tmp/autopkgtest.vD2RU8/build.O4C/src --env=AUTOPKGTEST_TESTBED_ARCH=s390x --env=AUTOPKGTEST_TEST_ARCH=s390x --env=DEB_BUILD_OPTIONS=parallel=2 --env=DEBIAN_FRONTEND=noninteractive --env=LANG=C.UTF-8 --unset-env=LANGUAGE --unset-env=LC_ADDRESS --unset-env=LC_ALL --unset-env=LC_COLLATE --unset-env=LC_CTYPE --unset-env=LC_IDENTIFICATION --unset-env=LC_MEASUREMENT --unset-env=LC_MESSAGES --unset-env=LC_MONETARY --unset-env=LC_NAME --unset-env=LC_NUMERIC --unset-env=LC_PAPER --unset-env=LC_TELEPHONE --unset-env=LC_TIME --script-pid-file=/tmp/autopkgtest_script_pid --source-profile --stderr=/tmp/autopkgtest.vD2RU8/guile-tests-parameters-stderr --stdout=/tmp/autopkgtest.vD2RU8/guile-tests-parameters-stdout --tmp=/tmp/autopkgtest.vD2RU8/autopkgtest_tmp --env=ADT_TEST_TRIGGERS=guile-fibers/1.3.1-4 -- bash -ec 'guile tests/parameters.scm'" (kind: test) 10307s autopkgtest [13:39:39]: test guile-tests-parameters: -----------------------] 10308s autopkgtest [13:39:40]: test guile-tests-parameters: - - - - - - - - - - results - - - - - - - - - - 10308s guile-tests-parameters FAIL timed out 10308s autopkgtest [13:39:40]: test guile-tests-preemption: preparing testbed 10308s Reading package lists... 10309s Building dependency tree... 10309s Reading state information... 10309s Starting pkgProblemResolver with broken count: 0 10309s Starting 2 pkgProblemResolver with broken count: 0 10309s Done 10309s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10310s autopkgtest [13:39:42]: test guile-tests-preemption: guile tests/preemption.scm 10310s autopkgtest [13:39:42]: test guile-tests-preemption: [----------------------- 10311s assert run-fibers on (let lp ((n 0)) (when (< n 100000000) (lp (#{1+}# n)))) terminates: ok (0.487516945 s) 10314s assert run-fibers on (race-until 100) terminates: ok (2.983741149 s) 10314s assert run-fiber-return-vals equal to (100): ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 10314s ;;; or pass the --no-auto-compile argument to disable. 10314s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/preemption.scm 10314s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/preemption.scm.go 10314s ok 10314s autopkgtest [13:39:46]: test guile-tests-preemption: -----------------------] 10314s autopkgtest [13:39:46]: test guile-tests-preemption: - - - - - - - - - - results - - - - - - - - - - 10314s guile-tests-preemption PASS 10315s autopkgtest [13:39:47]: test guile-tests-speedup: preparing testbed 10315s Reading package lists... 10315s Building dependency tree... 10315s Reading state information... 10315s Starting pkgProblemResolver with broken count: 0 10315s Starting 2 pkgProblemResolver with broken count: 0 10315s Done 10315s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10316s autopkgtest [13:39:48]: test guile-tests-speedup: guile tests/speedup.scm 10316s autopkgtest [13:39:48]: test guile-tests-speedup: [----------------------- 10317s speedup for (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t)): 0.178491126 s / 0.146915815 s = 1.214921116559167x (2 cpus) 10320s speedup for (do-times 40000 (spawn-fiber (lambda () (sleep 1)) #:parallel? #t)): 1.155799122 s / 1.106462877 s = 1.0445891552491733x (2 cpus) 10333s speedup for (do-times 100000 (spawn-fiber (lambda () (loop-to 10000)) #:parallel? #t)): 8.589424856 s / 4.643080544 s = 1.8499409550626138x (2 cpus) 10346s speedup for (do-times 10000 (spawn-fiber (lambda () (loop-to 100000)) #:parallel? #t)): 8.523895788 s / 4.425246612 s = 1.9261967829963735x (2 cpus) 10359s speedup for (do-times 1000 (spawn-fiber (lambda () (loop-to 1000000)) #:parallel? #t)): 8.633833061 s / 4.263996087 s = 2.02482199440161x (2 cpus) 10369s speedup for (do-times 100000 (spawn-fiber (lambda () (alloc-to 4 1000)) #:parallel? #t)): 6.237485014 s / 3.860290691 s = 1.615807076016908x (2 cpus) 10378s speedup for (do-times 10000 (spawn-fiber (lambda () (alloc-to 4 10000)) #:parallel? #t)): 5.778911671 s / 3.619451081 s = 1.5966265441010945x (2 cpus) 10388s speedup for (do-times 1000 (spawn-fiber (lambda () (alloc-to 4 100000)) #:parallel? #t)): 6.093028814 s / 3.522522121 s = 1.72973471981214x (2 cpus) 10388s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 10388s ;;; or pass the --no-auto-compile argument to disable. 10388s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/speedup.scm 10388s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 10388s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/speedup.scm.go 10388s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 10388s autopkgtest [13:41:00]: test guile-tests-speedup: -----------------------] 10389s guile-tests-speedup PASS 10389s autopkgtest [13:41:01]: test guile-tests-speedup: - - - - - - - - - - results - - - - - - - - - - 10389s autopkgtest [13:41:01]: test guile-tests-timer-wheel: preparing testbed 10389s Reading package lists... 10389s Building dependency tree... 10389s Reading state information... 10389s Starting pkgProblemResolver with broken count: 0 10390s Starting 2 pkgProblemResolver with broken count: 0 10390s Done 10390s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10390s autopkgtest [13:41:02]: test guile-tests-timer-wheel: guile tests/timer-wheel.scm 10390s autopkgtest [13:41:02]: test guile-tests-timer-wheel: [----------------------- 10392s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 10392s ;;; or pass the --no-auto-compile argument to disable. 10392s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/timer-wheel.scm 10392s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/timer-wheel.scm.go 10393s autopkgtest [13:41:05]: test guile-tests-timer-wheel: -----------------------] 10393s guile-tests-timer-wheel PASS 10393s autopkgtest [13:41:05]: test guile-tests-timer-wheel: - - - - - - - - - - results - - - - - - - - - - 10393s autopkgtest [13:41:05]: test guile-tests-ports: preparing testbed 10394s Reading package lists... 10394s Building dependency tree... 10394s Reading state information... 10394s Starting pkgProblemResolver with broken count: 0 10394s Starting 2 pkgProblemResolver with broken count: 0 10394s Done 10394s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10395s autopkgtest [13:41:07]: test guile-tests-ports: guile tests/ports.scm 10395s autopkgtest [13:41:07]: test guile-tests-ports: [----------------------- 10400s 10400s ;;; (listening-socket #) 10400s 10400s ;;; (listening-socket #) 10400s 10400s ;;; (listening-socket #) 10400s 10400s ;;; (listening-socket #) 10400s 10400s ;;; (listening-socket #) 10400s 10400s ;;; (listening-socket #) 10400s 10400s ;;; (accepted-connection (# . #(2 2130706433 39258))) 10400s 10400s ;;; (connected #(2 2130706433 5556)) 10400s 10400s ;;; (received "hello!") 10400s 10400s ;;; (echo "hello!") 10400s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 10400s ;;; or pass the --no-auto-compile argument to disable. 10400s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/ports.scm 10400s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 10400s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/ports.scm.go 10400s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 10400s success 10401s autopkgtest [13:41:13]: test guile-tests-ports: -----------------------] 10401s guile-tests-ports PASS 10401s autopkgtest [13:41:13]: test guile-tests-ports: - - - - - - - - - - results - - - - - - - - - - 10402s autopkgtest [13:41:14]: test guile-tests-concurrent-web-server: preparing testbed 10402s Reading package lists... 10402s Building dependency tree... 10402s Reading state information... 10402s Starting pkgProblemResolver with broken count: 0 10402s Starting 2 pkgProblemResolver with broken count: 0 10402s Done 10402s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 10403s autopkgtest [13:41:15]: test guile-tests-concurrent-web-server: guile tests/concurrent-web-server.scm 10403s autopkgtest [13:41:15]: test guile-tests-concurrent-web-server: [----------------------- 10409s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 10409s ;;; or pass the --no-auto-compile argument to disable. 10409s ;;; compiling /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/concurrent-web-server.scm 10409s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.vD2RU8/build.O4C/src/tests/concurrent-web-server.scm.go 10409s Backtrace: 10409s In ice-9/boot-9.scm: 10409s 1755:12 7 (with-exception-handler _ _ #:unwind? _ #:unwind-for-type _) 10409s In unknown file: 10409s 6 (apply-smob/0 #) 10409s In ice-9/boot-9.scm: 10409s 724:2 5 (call-with-prompt _ _ #) 10409s In ice-9/eval.scm: 10409s 619:8 4 (_ #(#(#))) 10409s In ice-9/boot-9.scm: 10409s 2858:4 3 (save-module-excursion _) 10409s 4408:12 2 (_) 10409s In /tmp/autopkgtest.vD2RU8/build.O4C/src/tests/concurrent-web-server.scm: 10409s 83:11 1 (_) 10409s In unknown file: 10409s 0 (bytevector->uint-list #vu8(60 33 68 79 67 84 89 80 69 32 104 116 109 108 32 80 85 66 76 73 67 32 34 45 47 47 87 51 67 47 47 68 84 68 32 72 84 77 76 32 52 46 48 49 47 47 69 78 34 32 34 104 116 116 112 58 47 47 119 119 119 46 119 51 46 111 114 103 47 84 82 47 104 116 109 108 52 47 115 116 114 105 99 116 46 100 116 100 34 62 10 60 104 116 109 108 62 60 104 101 97 100 62 10 60 109 101 116 97 32 116 121 112 101 61 34 99 111 112 121 114 105 103 104 116 34 32 99 111 110 116 101 110 …) …) 10409s 10409s ERROR: In procedure bytevector->uint-list: 10409s In procedure bytevector->uint-list: Wrong type (expecting an exact positive integer that divides the bytevector length): 4 10409s autopkgtest [13:41:21]: test guile-tests-concurrent-web-server: -----------------------] 10410s autopkgtest [13:41:22]: test guile-tests-concurrent-web-server: - - - - - - - - - - results - - - - - - - - - - 10410s guile-tests-concurrent-web-server FAIL non-zero exit status 1 10410s autopkgtest [13:41:22]: @@@@@@@@@@@@@@@@@@@@ summary 10410s guile-tests-basic PASS 10410s guile-tests-conditions PASS 10410s guile-tests-channels PASS 10410s guile-tests-foreign PASS 10410s guile-tests-io-wakeup PASS 10410s guile-tests-parameters FAIL timed out 10410s guile-tests-preemption PASS 10410s guile-tests-speedup PASS 10410s guile-tests-timer-wheel PASS 10410s guile-tests-ports PASS 10410s guile-tests-concurrent-web-server FAIL non-zero exit status 1 10428s nova [W] Using flock in prodstack6-s390x 10428s flock: timeout while waiting to get lock 10428s Creating nova instance adt-plucky-s390x-guile-fibers-20250313-104752-juju-7f2275-prod-proposed-migration-environment-2-c955e496-b4a9-4952-b57d-6ed0b3b1bd18 from image adt/ubuntu-plucky-s390x-server-20250313.img (UUID 54e7e81b-c545-49a7-82a9-0db67474751a)... 10428s nova [W] Timed out waiting for 0de1888c-c1af-4ff3-b7fc-9b3259f410c1 to get deleted.