0s autopkgtest [09:27:16]: starting date and time: 2025-04-14 09:27:16+0000 0s autopkgtest [09:27:16]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [09:27:16]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.x1ycl_pv/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-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-s390x-6.secgroup --name adt-plucky-s390x-guile-fibers-20250414-092715-juju-7f2275-prod-proposed-migration-environment-2-0ec48952-ccfd-43f5-acb2-351c31e29432 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 64s autopkgtest [09:28:20]: testbed dpkg architecture: s390x 64s autopkgtest [09:28:20]: testbed apt version: 3.0.0 64s autopkgtest [09:28:20]: @@@@@@@@@@@@@@@@@@@@ test bed setup 65s autopkgtest [09:28:21]: testbed release detected to be: None 66s autopkgtest [09:28:22]: updating testbed package index (apt update) 67s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [265 kB] 67s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 68s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 68s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 68s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [9948 B] 68s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [5192 B] 68s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [204 kB] 68s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [3452 B] 68s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [288 B] 68s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 68s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [119 kB] 68s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [7952 B] 68s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [1460 B] 68s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [172 B] 71s Fetched 616 kB in 1s (568 kB/s) 74s Reading package lists... 75s autopkgtest [09:28:31]: upgrading testbed (apt dist-upgrade and autopurge) 75s Reading package lists... 75s Building dependency tree... 75s Reading state information... 75s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 75s Starting 2 pkgProblemResolver with broken count: 0 75s Done 76s Entering ResolveByKeep 76s 76s Calculating upgrade... 76s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 77s Reading package lists... 77s Building dependency tree... 77s Reading state information... 77s Starting pkgProblemResolver with broken count: 0 77s Starting 2 pkgProblemResolver with broken count: 0 77s Done 77s Solving dependencies... 77s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 80s autopkgtest [09:28:36]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 80s autopkgtest [09:28:36]: @@@@@@@@@@@@@@@@@@@@ apt-source guile-fibers 81s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (dsc) [2405 B] 81s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (tar) [581 kB] 81s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe guile-fibers 1.3.1-4 (diff) [4516 B] 81s gpgv: Signature made Thu Feb 20 07:20:23 2025 UTC 81s gpgv: using EDDSA key A3CC9C870B9D310ABAD4CF2F51722B08FE4745A2 81s gpgv: issuer "simon@josefsson.org" 81s gpgv: Can't check signature: No public key 81s dpkg-source: warning: cannot verify inline signature for ./guile-fibers_1.3.1-4.dsc: no acceptable signature found 82s autopkgtest [09:28:38]: testing package guile-fibers version 1.3.1-4 82s autopkgtest [09:28:38]: build not needed 83s autopkgtest [09:28:39]: test guile-tests-basic: preparing testbed 83s Reading package lists... 83s Building dependency tree... 83s Reading state information... 84s Starting pkgProblemResolver with broken count: 0 84s Starting 2 pkgProblemResolver with broken count: 0 84s Done 84s The following NEW packages will be installed: 84s guile-3.0 guile-3.0-libs guile-fibers libgc1 84s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 84s Need to get 8331 kB of archives. 84s After this operation, 59.6 MB of additional disk space will be used. 84s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libgc1 s390x 1:8.2.8-1 [93.7 kB] 84s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x guile-3.0-libs s390x 3.0.10+really3.0.10-4 [7889 kB] 85s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x guile-3.0 s390x 3.0.10+really3.0.10-4 [16.4 kB] 85s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x guile-fibers s390x 1.3.1-4 [332 kB] 85s Fetched 8331 kB in 1s (12.0 MB/s) 86s Selecting previously unselected package libgc1:s390x. 86s (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 ... 81472 files and directories currently installed.) 86s Preparing to unpack .../libgc1_1%3a8.2.8-1_s390x.deb ... 86s Unpacking libgc1:s390x (1:8.2.8-1) ... 86s Selecting previously unselected package guile-3.0-libs:s390x. 86s Preparing to unpack .../guile-3.0-libs_3.0.10+really3.0.10-4_s390x.deb ... 86s Unpacking guile-3.0-libs:s390x (3.0.10+really3.0.10-4) ... 86s Selecting previously unselected package guile-3.0. 86s Preparing to unpack .../guile-3.0_3.0.10+really3.0.10-4_s390x.deb ... 86s Unpacking guile-3.0 (3.0.10+really3.0.10-4) ... 86s Selecting previously unselected package guile-fibers. 86s Preparing to unpack .../guile-fibers_1.3.1-4_s390x.deb ... 86s Unpacking guile-fibers (1.3.1-4) ... 86s Setting up libgc1:s390x (1:8.2.8-1) ... 86s Setting up guile-3.0-libs:s390x (3.0.10+really3.0.10-4) ... 86s Setting up guile-3.0 (3.0.10+really3.0.10-4) ... 86s update-alternatives: using /usr/lib/s390x-linux-gnu/guile/3.0/bin/guile to provide /usr/bin/guile (guile) in auto mode 86s Setting up guile-fibers (1.3.1-4) ... 86s Processing triggers for libc-bin (2.41-6ubuntu1) ... 86s Processing triggers for man-db (2.13.0-1) ... 89s Processing triggers for install-info (7.1.1-1) ... 91s autopkgtest [09:28:47]: test guile-tests-basic: guile tests/basic.scm 91s autopkgtest [09:28:47]: test guile-tests-basic: [----------------------- 94s assert #f equal to #f: ok 94s assert #t terminates: ok 94s assert (false-if-exception (begin (run-fibers) #t)) equal to #f: ok 95s assert terminates: (run-fibers (lambda () (sleep 1)) #:drain? #t): ok (1.0011494 s) 95s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (4.36862e-4 s) 95s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (2.82082e-4 s) 95s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (3.53794e-4 s) 95s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.001409016 s) 95s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.010845772 s) 96s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t)))) #:drain? #t): ok (0.566003905 s) 96s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t))) #:drain? #t): ok (0.138293316 s) 103s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4))) #:drain? #t): ok (7.666888888 s) 107s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber loop-to-1e4 #:parallel? #t))) #:drain? #t): ok (3.522414333 s) 108s assert terminates: (run-fibers (lambda () (do-times 1 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.000891556 s) 109s assert terminates: (run-fibers (lambda () (do-times 10 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.001237915 s) 110s assert terminates: (run-fibers (lambda () (do-times 100 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.014184153 s) 111s assert terminates: (run-fibers (lambda () (do-times 1000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.003895595 s) 112s assert terminates: (run-fibers (lambda () (do-times 10000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.02928938 s) 113s assert terminates: (run-fibers (lambda () (do-times 100000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (1.575601843 s) 133s assert terminates: (run-fibers (lambda () (do-times 1000000 (spawn-fiber (lambda () (sleep 1))))) #:drain? #t): ok (19.535176631 s) 134s assert terminates: (run-fibers (lambda () (spawn-fiber-tree 7 (lambda () (sleep 1)))) #:drain? #t): ok (1.05535237 s) 134s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5)) #:drain? #t): ok (0.008467075 s) 134s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50)) #:drain? #t): ok (4.57237e-4 s) 134s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500)) #:drain? #t): ok (0.002049721 s) 134s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000)) #:drain? #t): ok (0.018430362 s) 134s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 50000)) #:drain? #t): ok (0.245763146 s) 138s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 500000)) #:drain? #t): ok (3.873607462 s) 164s assert terminates: (run-fibers (lambda () (spawn-fiber-chain 5000000)) #:drain? #t): ok (25.341427887 s) 164s assert terminates: (run-fibers (lambda () (test-run-order 10)) #:parallelism 1 #:drain? #t): ok (0.017157503 s) 164s assert terminates: (run-fibers (lambda () (test-wakeup-order 10)) #:parallelism 1 #:drain? #t): ok (2.75621e-4 s) 164s assert terminates: (run-fibers (lambda () 1) #:drain? #t): ok (7.57924e-4 s) 164s assert run-fiber-return-vals equal to (1): ok 165s assert terminates: (run-fibers (lambda () (do-times 20 (check-sleep (random 1.0)))) #:drain? #t): assert sleep 0.020608996598312723s < actual 0.020735644s: #t (diff: 0.6145248318282827%) 165s assert sleep 0.03456686196908243s < actual 0.034872549s: #t (diff: 0.8843354979430532%) 165s assert sleep 0.061024566720115414s < actual 0.062404654s: #t (diff: 2.261527371778401%) 165s assert sleep 0.13706411233780896s < actual 0.137164797s: #t (diff: 0.07345807773729553%) 165s assert sleep 0.2275100133355504s < actual 0.245842215s: #t (diff: 8.05775596233286%) 165s assert sleep 0.23514019199674957s < actual 0.245869314s: #t (diff: 4.56286180262996%) 165s assert sleep 0.3256129036589292s < actual 0.32595131s: #t (diff: 0.10392903268516276%) 165s assert sleep 0.3507339219939192s < actual 0.351032938s: #t (diff: 0.0852543729961847%) 165s assert sleep 0.36970041583284435s < actual 0.369921344s: #t (diff: 0.05975870128734771%) 165s assert sleep 0.5002849968690339s < actual 0.500490294s: #t (diff: 0.04103603590970924%) 165s assert sleep 0.6727431540500131s < actual 0.672960998s: #t (diff: 0.032381444341048296%) 165s assert sleep 0.7000185262935479s < actual 0.700230126s: #t (diff: 0.030227729482032728%) 165s assert sleep 0.7148624304124437s < actual 0.714881713s: #t (diff: 0.0026973843827855204%) 165s assert sleep 0.721997240644088s < actual 0.722230861s: #t (diff: 0.032357513680190964%) 165s assert sleep 0.7233843607837082s < actual 0.72379607s: #t (diff: 0.056914309820827964%) 165s assert sleep 0.7603254888022394s < actual 0.76051161s: #t (diff: 0.024479147483773868%) 165s assert sleep 0.7965046199252751s < actual 0.796660137s: #t (diff: 0.019524943212448957%) 165s assert sleep 0.8067622687831747s < actual 0.810416506s: #t (diff: 0.4529509321670387%) 165s assert sleep 0.9299942644443119s < actual 0.930366738s: #t (diff: 0.04005116697259483%) 165s assert sleep 0.999598330708636s < actual 1.000117711s: #t (diff: 0.051958899430703503%) 165s ok (1.001150329 s) 165s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 165s ;;; or pass the --no-auto-compile argument to disable. 165s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/basic.scm 165s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 165s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/basic.scm.go 165s WARNING: (tests basic): imported module (fibers) overrides core binding `sleep' 165s autopkgtest [09:30:01]: test guile-tests-basic: -----------------------] 165s autopkgtest [09:30:01]: test guile-tests-basic: - - - - - - - - - - results - - - - - - - - - - 165s guile-tests-basic PASS 166s autopkgtest [09:30:02]: test guile-tests-conditions: preparing testbed 167s Reading package lists... 167s Building dependency tree... 167s Reading state information... 167s Starting pkgProblemResolver with broken count: 0 167s Starting 2 pkgProblemResolver with broken count: 0 167s Done 169s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 169s autopkgtest [09:30:05]: test guile-tests-conditions: guile tests/conditions.scm 169s autopkgtest [09:30:05]: test guile-tests-conditions: [----------------------- 171s assert (condition? cv) equal to #t: ok 172s assert run-fibers on (wait/timeout cv) terminates: ok (0.25391241 s) 172s assert run-fiber-return-vals equal to (#f): ok 172s assert run-fibers on (wait/timeout cv) terminates: ok (0.050470611 s) 172s assert run-fiber-return-vals equal to (#f): ok 172s assert (signal-condition! cv) equal to #t: ok 172s assert (signal-condition! cv) equal to #f: ok 172s assert run-fibers on (wait/timeout cv) terminates: ok (3.06543e-4 s) 172s assert run-fiber-return-vals equal to (#t): ok 172s assert run-fibers on (wait/timeout cv) terminates: ok (2.40051e-4 s) 172s assert run-fiber-return-vals equal to (#t): ok 172s assert run-fibers on (let ((cv (make-condition))) (spawn-fiber (lambda () (signal-condition! cv))) (wait cv) #t) terminates: ok (2.66092e-4 s) 172s assert run-fiber-return-vals equal to (#t): ok 172s 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 172s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 172s ;;; or pass the --no-auto-compile argument to disable. 172s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/conditions.scm 172s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/conditions.scm.go 172s autopkgtest [09:30:08]: test guile-tests-conditions: -----------------------] 172s guile-tests-conditions PASS 172s autopkgtest [09:30:08]: test guile-tests-conditions: - - - - - - - - - - results - - - - - - - - - - 172s autopkgtest [09:30:08]: test guile-tests-channels: preparing testbed 173s Reading package lists... 173s Building dependency tree... 173s Reading state information... 173s Starting pkgProblemResolver with broken count: 0 173s Starting 2 pkgProblemResolver with broken count: 0 173s Done 173s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 174s autopkgtest [09:30:10]: test guile-tests-channels: guile tests/channels.scm 174s autopkgtest [09:30:10]: test guile-tests-channels: [----------------------- 175s assert run-fibers on (rpc 1) terminates: ok (6.70216e-4 s) 175s assert run-fiber-return-vals equal to (1): ok 177s assert run-fibers on (rpc-fib 24) terminates: ok (1.751675736 s) 177s assert run-fiber-return-vals equal to (75025): ok 177s assert run-fibers on (pingpong (current-processor-count) 1000) terminates: ok (0.018161694 s) 177s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 177s ;;; or pass the --no-auto-compile argument to disable. 177s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/channels.scm 177s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/channels.scm.go 177s autopkgtest [09:30:13]: test guile-tests-channels: -----------------------] 177s autopkgtest [09:30:13]: test guile-tests-channels: - - - - - - - - - - results - - - - - - - - - - 177s guile-tests-channels PASS 178s autopkgtest [09:30:14]: test guile-tests-foreign: preparing testbed 178s Reading package lists... 178s Building dependency tree... 178s Reading state information... 178s Starting pkgProblemResolver with broken count: 0 178s Starting 2 pkgProblemResolver with broken count: 0 178s Done 178s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 179s autopkgtest [09:30:15]: test guile-tests-foreign: guile tests/foreign.scm 179s autopkgtest [09:30:15]: test guile-tests-foreign: [----------------------- 180s assert #f equal to #f: ok 180s assert #t terminates: ok 181s assert (sleep 1) terminates: ok 182s assert (perform-operation (sleep-operation 1)) terminates: ok 182s assert (receive-from-fiber 42) equal to 42: ok 182s assert (send-to-fiber 42) equal to 42: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 182s ;;; or pass the --no-auto-compile argument to disable. 182s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/foreign.scm 182s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 182s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/foreign.scm.go 182s WARNING: (tests foreign): imported module (fibers) overrides core binding `sleep' 182s ok 182s autopkgtest [09:30:18]: test guile-tests-foreign: -----------------------] 182s autopkgtest [09:30:18]: test guile-tests-foreign: - - - - - - - - - - results - - - - - - - - - - 182s guile-tests-foreign PASS 183s autopkgtest [09:30:19]: test guile-tests-io-wakeup: preparing testbed 183s Reading package lists... 183s Building dependency tree... 183s Reading state information... 183s Starting pkgProblemResolver with broken count: 0 183s Starting 2 pkgProblemResolver with broken count: 0 183s Done 183s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 184s autopkgtest [09:30:20]: test guile-tests-io-wakeup: guile tests/io-wakeup.scm 184s autopkgtest [09:30:20]: test guile-tests-io-wakeup: [----------------------- 185s assert run-fibers on (readable/timeout? s) terminates: ok (0.050825702 s) 185s assert run-fiber-return-vals equal to (#t): ok 185s assert (readable/timeout? s) equal to #t: ok 185s assert run-fibers on (readable/timeout? A) terminates: ok (0.050875477 s) 185s assert run-fiber-return-vals equal to (#t): ok 185s assert (readable/timeout? A) equal to #t: ok 185s assert run-fibers on (writable/timeout? B) terminates: ok (4.39974e-4 s) 185s assert run-fiber-return-vals equal to (#f): ok 185s assert (writable/timeout? B) equal to #f: ok 185s assert run-fibers on (writable/timeout? B) terminates: ok (0.050735554 s) 185s assert run-fiber-return-vals equal to (#t): ok 185s assert run-fibers on (readable/timeout? A) terminates: ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 185s ;;; or pass the --no-auto-compile argument to disable. 185s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/io-wakeup.scm 185s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/io-wakeup.scm.go 185s ok (4.24553e-4 s) 185s assert run-fiber-return-vals equal to (#f): ok 186s autopkgtest [09:30:22]: test guile-tests-io-wakeup: -----------------------] 186s autopkgtest [09:30:22]: test guile-tests-io-wakeup: - - - - - - - - - - results - - - - - - - - - - 186s guile-tests-io-wakeup PASS 186s autopkgtest [09:30:22]: test guile-tests-parameters: preparing testbed 186s Reading package lists... 187s Building dependency tree... 187s Reading state information... 187s Starting pkgProblemResolver with broken count: 0 187s Starting 2 pkgProblemResolver with broken count: 0 187s Done 187s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 188s autopkgtest [09:30:24]: test guile-tests-parameters: guile tests/parameters.scm 188s autopkgtest [09:30:24]: test guile-tests-parameters: [----------------------- 189s assert run-fibers on (my-param) terminates: ok (7.96131e-4 s) 189s assert run-fiber-return-vals equal to (#f): ok 189s assert run-fibers on (rpc (my-param)) terminates: ok (3.26226e-4 s) 189s assert run-fiber-return-vals equal to (#f): ok 189s assert run-fibers on (rpc (begin (my-param 42) (my-param))) terminates: ok (3.20952e-4 s) 189s assert run-fiber-return-vals equal to (42): ok 189s assert run-fibers on (my-param) terminates: ok (2.11414e-4 s) 189s assert run-fiber-return-vals equal to (#f): ok 189s assert run-fibers on (begin (my-param 100) (rpc (my-param))) terminates: ok (2.49078e-4 s) 189s assert run-fiber-return-vals equal to (100): ok 189s assert run-fibers on (my-param) terminates: ok (2.3129e-4 s) 189s assert run-fiber-return-vals equal to (#f): ok 189s assert (my-param) equal to #f: ok 189s assert (begin (my-param (quote foo)) (my-param)) equal to foo: ok 189s assert run-fibers on (my-param) terminates: ok (2.34497e-4 s) 189s assert run-fiber-return-vals equal to (foo): ok 189s assert run-fibers on (rpc (my-param)) terminates: ok (2.70409e-4 s) 189s assert run-fiber-return-vals equal to (foo): ok 189s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 189s ;;; or pass the --no-auto-compile argument to disable. 189s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/parameters.scm 189s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/parameters.scm.go 189s autopkgtest [09:30:25]: test guile-tests-parameters: -----------------------] 189s guile-tests-parameters PASS 189s autopkgtest [09:30:25]: test guile-tests-parameters: - - - - - - - - - - results - - - - - - - - - - 190s autopkgtest [09:30:26]: test guile-tests-preemption: preparing testbed 190s Reading package lists... 190s Building dependency tree... 190s Reading state information... 190s Starting pkgProblemResolver with broken count: 0 190s Starting 2 pkgProblemResolver with broken count: 0 190s Done 191s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 191s autopkgtest [09:30:27]: test guile-tests-preemption: guile tests/preemption.scm 191s autopkgtest [09:30:27]: test guile-tests-preemption: [----------------------- 192s assert run-fibers on (let lp ((n 0)) (when (< n 100000000) (lp (#{1+}# n)))) terminates: ok (0.536104576 s) 196s assert run-fibers on (race-until 100) terminates: ok (3.201290149 s) 196s assert run-fiber-return-vals equal to (100): ok 196s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 196s ;;; or pass the --no-auto-compile argument to disable. 196s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/preemption.scm 196s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/preemption.scm.go 196s autopkgtest [09:30:32]: test guile-tests-preemption: -----------------------] 196s autopkgtest [09:30:32]: test guile-tests-preemption: - - - - - - - - - - results - - - - - - - - - - 196s guile-tests-preemption PASS 197s autopkgtest [09:30:33]: test guile-tests-speedup: preparing testbed 197s Reading package lists... 197s Building dependency tree... 197s Reading state information... 197s Starting pkgProblemResolver with broken count: 0 197s Starting 2 pkgProblemResolver with broken count: 0 197s Done 197s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 198s autopkgtest [09:30:34]: test guile-tests-speedup: guile tests/speedup.scm 198s autopkgtest [09:30:34]: test guile-tests-speedup: [----------------------- 203s speedup for (do-times 100000 (spawn-fiber (lambda () #t) #:parallel? #t)): 1.074792117 s / 0.322774284 s = 3.3298567149791896x (2 cpus) 206s speedup for (do-times 40000 (spawn-fiber (lambda () (sleep 1)) #:parallel? #t)): 1.657671777 s / 1.208951336 s = 1.371165015197932x (2 cpus) 226s speedup for (do-times 100000 (spawn-fiber (lambda () (loop-to 10000)) #:parallel? #t)): 13.503156938 s / 5.939088422 s = 2.2736076614014755x (2 cpus) 250s speedup for (do-times 10000 (spawn-fiber (lambda () (loop-to 100000)) #:parallel? #t)): 10.303480141 s / 14.2083912 s = 0.7251686694127623x (2 cpus) 266s speedup for (do-times 1000 (spawn-fiber (lambda () (loop-to 1000000)) #:parallel? #t)): 11.495318981 s / 5.117720776 s = 2.2461793998039727x (2 cpus) 278s speedup for (do-times 100000 (spawn-fiber (lambda () (alloc-to 4 1000)) #:parallel? #t)): 6.948084749 s / 4.570396999 s = 1.5202365900643287x (2 cpus) 297s speedup for (do-times 10000 (spawn-fiber (lambda () (alloc-to 4 10000)) #:parallel? #t)): 14.292429733 s / 5.007935664 s = 2.8539563388847884x (2 cpus) 308s speedup for (do-times 1000 (spawn-fiber (lambda () (alloc-to 4 100000)) #:parallel? #t)): 6.428324788 s / 3.869952812 s = 1.6610860907830625x (2 cpus) 308s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 308s ;;; or pass the --no-auto-compile argument to disable. 308s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/speedup.scm 308s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 308s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/speedup.scm.go 308s WARNING: (tests speedup): imported module (fibers) overrides core binding `sleep' 308s autopkgtest [09:32:24]: test guile-tests-speedup: -----------------------] 308s guile-tests-speedup PASS 308s autopkgtest [09:32:24]: test guile-tests-speedup: - - - - - - - - - - results - - - - - - - - - - 308s autopkgtest [09:32:24]: test guile-tests-timer-wheel: preparing testbed 309s Reading package lists... 309s Building dependency tree... 309s Reading state information... 309s Starting pkgProblemResolver with broken count: 0 309s Starting 2 pkgProblemResolver with broken count: 0 309s Done 309s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 310s autopkgtest [09:32:26]: test guile-tests-timer-wheel: guile tests/timer-wheel.scm 310s autopkgtest [09:32:26]: test guile-tests-timer-wheel: [----------------------- 312s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 312s ;;; or pass the --no-auto-compile argument to disable. 312s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/timer-wheel.scm 312s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/timer-wheel.scm.go 312s autopkgtest [09:32:28]: test guile-tests-timer-wheel: -----------------------] 313s guile-tests-timer-wheel PASS 313s autopkgtest [09:32:29]: test guile-tests-timer-wheel: - - - - - - - - - - results - - - - - - - - - - 313s autopkgtest [09:32:29]: test guile-tests-ports: preparing testbed 313s Reading package lists... 313s Building dependency tree... 313s Reading state information... 313s Starting pkgProblemResolver with broken count: 0 313s Starting 2 pkgProblemResolver with broken count: 0 313s Done 313s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 314s autopkgtest [09:32:30]: test guile-tests-ports: guile tests/ports.scm 314s autopkgtest [09:32:30]: test guile-tests-ports: [----------------------- 320s 320s ;;; (listening-socket #) 320s 320s ;;; (listening-socket #) 320s 320s ;;; (listening-socket #) 320s 320s ;;; (listening-socket #) 320s 320s ;;; (listening-socket #) 320s 320s ;;; (listening-socket #) 320s 320s ;;; (accepted-connection (# . #(2 2130706433 35852))) 320s 320s ;;; (connected #(2 2130706433 5556)) 320s 320s ;;; (received "hello!") 320s 320s ;;; (echo "hello!") 320s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 320s ;;; or pass the --no-auto-compile argument to disable. 320s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/ports.scm 320s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 320s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/ports.scm.go 320s WARNING: (tests ports): imported module (fibers) overrides core binding `sleep' 320s success 320s autopkgtest [09:32:36]: test guile-tests-ports: -----------------------] 320s guile-tests-ports PASS 320s autopkgtest [09:32:36]: test guile-tests-ports: - - - - - - - - - - results - - - - - - - - - - 321s autopkgtest [09:32:37]: test guile-tests-concurrent-web-server: preparing testbed 321s Reading package lists... 321s Building dependency tree... 321s Reading state information... 321s Starting pkgProblemResolver with broken count: 0 321s Starting 2 pkgProblemResolver with broken count: 0 321s Done 321s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 322s autopkgtest [09:32:38]: test guile-tests-concurrent-web-server: guile tests/concurrent-web-server.scm 322s autopkgtest [09:32:38]: test guile-tests-concurrent-web-server: [----------------------- 329s ;;; note: auto-compilation is enabled, set GUILE_AUTO_COMPILE=0 329s ;;; or pass the --no-auto-compile argument to disable. 329s ;;; compiling /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/concurrent-web-server.scm 329s ;;; compiled /home/ubuntu/.cache/guile/ccache/3.0-BE-8-4.7/tmp/autopkgtest.dKyGH2/build.r3L/src/tests/concurrent-web-server.scm.go 329s Backtrace: 329s In ice-9/boot-9.scm: 329s 1755:12 7 (with-exception-handler _ _ #:unwind? _ #:unwind-for-type _) 329s In unknown file: 329s 6 (apply-smob/0 #) 329s In ice-9/boot-9.scm: 329s 724:2 5 (call-with-prompt _ _ #) 329s In ice-9/eval.scm: 329s 619:8 4 (_ #(#(#))) 329s In ice-9/boot-9.scm: 329s 2858:4 3 (save-module-excursion _) 329s 4408:12 2 (_) 329s In /tmp/autopkgtest.dKyGH2/build.r3L/src/tests/concurrent-web-server.scm: 329s 83:11 1 (_) 329s In unknown file: 329s 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 …) …) 329s 329s ERROR: In procedure bytevector->uint-list: 329s In procedure bytevector->uint-list: Wrong type (expecting an exact positive integer that divides the bytevector length): 4 329s autopkgtest [09:32:45]: test guile-tests-concurrent-web-server: -----------------------] 330s guile-tests-concurrent-web-server FAIL non-zero exit status 1 330s autopkgtest [09:32:46]: test guile-tests-concurrent-web-server: - - - - - - - - - - results - - - - - - - - - - 330s autopkgtest [09:32:46]: @@@@@@@@@@@@@@@@@@@@ summary 330s guile-tests-basic PASS 330s guile-tests-conditions PASS 330s guile-tests-channels PASS 330s guile-tests-foreign PASS 330s guile-tests-io-wakeup PASS 330s guile-tests-parameters PASS 330s guile-tests-preemption PASS 330s guile-tests-speedup PASS 330s guile-tests-timer-wheel PASS 330s guile-tests-ports PASS 330s guile-tests-concurrent-web-server FAIL non-zero exit status 1 346s nova [W] Using flock in prodstack7-s390x 346s Creating nova instance adt-plucky-s390x-guile-fibers-20250414-092715-juju-7f2275-prod-proposed-migration-environment-2-0ec48952-ccfd-43f5-acb2-351c31e29432 from image adt/ubuntu-plucky-s390x-server-20250413.img (UUID 0f91ffef-5635-4915-8647-23d7a5fffc22)... 346s nova [W] Timed out waiting for 26a55692-152b-4358-bb9f-ba1a84d80b1d to get deleted.