0s autopkgtest [21:56:09]: starting date and time: 2024-03-20 21:56:09+0000 0s autopkgtest [21:56:09]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [21:56:09]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.y7e3pfxs/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:ruby-ffi --apt-upgrade ruby-magic --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=ruby-ffi/1.16.3+dfsg-1build2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos01-s390x-4.secgroup --name adt-noble-s390x-ruby-magic-20240320-215609-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://us.ports.ubuntu.com/ubuntu-ports/ 199s autopkgtest [21:59:28]: testbed dpkg architecture: s390x 200s autopkgtest [21:59:29]: testbed apt version: 2.7.12 200s autopkgtest [21:59:29]: @@@@@@@@@@@@@@@@@@@@ test bed setup 200s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 200s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [500 kB] 201s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 201s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3811 kB] 202s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 202s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [678 kB] 202s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 202s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 202s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 202s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4061 kB] 203s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 203s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.3 kB] 203s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 205s Fetched 9285 kB in 4s (2354 kB/s) 205s Reading package lists... 208s Reading package lists... 208s Building dependency tree... 208s Reading state information... 209s Calculating upgrade... 209s The following packages will be upgraded: 209s ubuntu-minimal ubuntu-standard 209s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 209s Need to get 21.5 kB of archives. 209s After this operation, 0 B of additional disk space will be used. 209s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x ubuntu-minimal s390x 1.536build1 [10.7 kB] 209s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x ubuntu-standard s390x 1.536build1 [10.7 kB] 210s Fetched 21.5 kB in 0s (71.7 kB/s) 210s (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 ... 51778 files and directories currently installed.) 210s Preparing to unpack .../ubuntu-minimal_1.536build1_s390x.deb ... 210s Unpacking ubuntu-minimal (1.536build1) over (1.536) ... 210s Preparing to unpack .../ubuntu-standard_1.536build1_s390x.deb ... 210s Unpacking ubuntu-standard (1.536build1) over (1.536) ... 210s Setting up ubuntu-minimal (1.536build1) ... 210s Setting up ubuntu-standard (1.536build1) ... 210s Reading package lists... 211s Building dependency tree... 211s Reading state information... 211s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 211s Unknown architecture, assuming PC-style ttyS0 211s sh: Attempting to set up Debian/Ubuntu apt sources automatically 211s sh: Distribution appears to be Ubuntu 212s Reading package lists... 212s Building dependency tree... 212s Reading state information... 213s eatmydata is already the newest version (131-1). 213s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 213s Reading package lists... 213s Building dependency tree... 213s Reading state information... 213s dbus is already the newest version (1.14.10-4ubuntu1). 213s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 213s Reading package lists... 213s Building dependency tree... 213s Reading state information... 214s rng-tools-debian is already the newest version (2.4). 214s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 214s Reading package lists... 214s Building dependency tree... 214s Reading state information... 214s The following packages will be REMOVED: 214s cloud-init* python3-configobj* python3-debconf* 215s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 215s After this operation, 3252 kB disk space will be freed. 215s (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 ... 51778 files and directories currently installed.) 215s Removing cloud-init (24.1.1-0ubuntu1) ... 216s Removing python3-configobj (5.0.8-3) ... 216s Removing python3-debconf (1.5.86) ... 216s Processing triggers for man-db (2.12.0-3) ... 216s (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 ... 51389 files and directories currently installed.) 216s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 217s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 217s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 217s invoke-rc.d: policy-rc.d denied execution of try-restart. 217s Reading package lists... 218s Building dependency tree... 218s Reading state information... 218s linux-generic is already the newest version (6.8.0-11.11+1). 218s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 218s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 218s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 219s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 221s Reading package lists... 221s Reading package lists... 221s Building dependency tree... 221s Reading state information... 221s Calculating upgrade... 221s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 221s Reading package lists... 222s Building dependency tree... 222s Reading state information... 222s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 222s autopkgtest [21:59:51]: rebooting testbed after setup commands that affected boot 256s autopkgtest [22:00:25]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 259s autopkgtest [22:00:28]: @@@@@@@@@@@@@@@@@@@@ apt-source ruby-magic 261s Get:1 http://ftpmaster.internal/ubuntu noble/universe ruby-magic 0.2.9-3 (dsc) [2072 B] 261s Get:2 http://ftpmaster.internal/ubuntu noble/universe ruby-magic 0.2.9-3 (tar) [12.2 kB] 261s Get:3 http://ftpmaster.internal/ubuntu noble/universe ruby-magic 0.2.9-3 (diff) [3948 B] 261s gpgv: Signature made Mon Feb 26 15:59:10 2024 UTC 261s gpgv: using RSA key 8ED6C3F8BAC9DB7FC130A870F823A2729883C97C 261s gpgv: issuer "kanashiro@debian.org" 261s gpgv: Can't check signature: No public key 261s dpkg-source: warning: cannot verify inline signature for ./ruby-magic_0.2.9-3.dsc: no acceptable signature found 261s autopkgtest [22:00:30]: testing package ruby-magic version 0.2.9-3 261s autopkgtest [22:00:30]: build not needed 262s autopkgtest [22:00:31]: test gem2deb-test-runner: preparing testbed 269s Reading package lists... 269s Building dependency tree... 269s Reading state information... 269s Starting pkgProblemResolver with broken count: 0 269s Starting 2 pkgProblemResolver with broken count: 0 269s Done 270s The following additional packages will be installed: 270s gem2deb-test-runner libruby libruby3.1 rake ruby ruby-ffi ruby-magic 270s ruby-net-telnet ruby-rubygems ruby-sdbm ruby-test-declarative ruby-webrick 270s ruby-xmlrpc ruby3.1 rubygems-integration 270s Suggested packages: 270s ri ruby-dev bundler 270s Recommended packages: 270s zip fonts-lato libjs-jquery 270s The following NEW packages will be installed: 270s autopkgtest-satdep gem2deb-test-runner libruby libruby3.1 rake ruby ruby-ffi 270s ruby-magic ruby-net-telnet ruby-rubygems ruby-sdbm ruby-test-declarative 270s ruby-webrick ruby-xmlrpc ruby3.1 rubygems-integration 270s 0 upgraded, 16 newly installed, 0 to remove and 0 not upgraded. 270s Need to get 5818 kB/5819 kB of archives. 270s After this operation, 28.8 MB of additional disk space will be used. 270s Get:1 /tmp/autopkgtest.zfviBW/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [748 B] 270s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x rubygems-integration all 1.18 [5336 B] 270s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x ruby-net-telnet all 0.2.0-1 [13.3 kB] 270s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x ruby-webrick all 1.8.1-1 [52.3 kB] 270s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x ruby-xmlrpc all 0.3.2-2 [24.8 kB] 270s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x libruby s390x 1:3.1+1 [4640 B] 270s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x ruby-sdbm s390x 1.0.0-5build3 [19.1 kB] 270s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x libruby3.1 s390x 3.1.2-7ubuntu4 [5197 kB] 272s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x ruby3.1 s390x 3.1.2-7ubuntu4 [49.1 kB] 273s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x ruby-rubygems all 3.4.20-1 [238 kB] 273s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x ruby s390x 1:3.1+1 [3466 B] 273s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x rake all 13.0.6-3 [61.6 kB] 273s Get:13 http://ftpmaster.internal/ubuntu noble/universe s390x gem2deb-test-runner s390x 2.2.2 [18.2 kB] 273s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x ruby-ffi s390x 1.16.3+dfsg-1build1 [121 kB] 273s Get:15 http://ftpmaster.internal/ubuntu noble/universe s390x ruby-magic all 0.2.9-3 [6796 B] 273s Get:16 http://ftpmaster.internal/ubuntu noble/universe s390x ruby-test-declarative all 0.0.6-3 [3694 B] 273s Fetched 5818 kB in 3s (2133 kB/s) 273s Selecting previously unselected package rubygems-integration. 273s (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 ... 51332 files and directories currently installed.) 273s Preparing to unpack .../00-rubygems-integration_1.18_all.deb ... 273s Unpacking rubygems-integration (1.18) ... 273s Selecting previously unselected package ruby-net-telnet. 273s Preparing to unpack .../01-ruby-net-telnet_0.2.0-1_all.deb ... 273s Unpacking ruby-net-telnet (0.2.0-1) ... 273s Selecting previously unselected package ruby-webrick. 273s Preparing to unpack .../02-ruby-webrick_1.8.1-1_all.deb ... 273s Unpacking ruby-webrick (1.8.1-1) ... 273s Selecting previously unselected package ruby-xmlrpc. 273s Preparing to unpack .../03-ruby-xmlrpc_0.3.2-2_all.deb ... 273s Unpacking ruby-xmlrpc (0.3.2-2) ... 273s Selecting previously unselected package libruby:s390x. 273s Preparing to unpack .../04-libruby_1%3a3.1+1_s390x.deb ... 273s Unpacking libruby:s390x (1:3.1+1) ... 273s Selecting previously unselected package ruby-sdbm:s390x. 273s Preparing to unpack .../05-ruby-sdbm_1.0.0-5build3_s390x.deb ... 273s Unpacking ruby-sdbm:s390x (1.0.0-5build3) ... 273s Selecting previously unselected package libruby3.1:s390x. 273s Preparing to unpack .../06-libruby3.1_3.1.2-7ubuntu4_s390x.deb ... 273s Unpacking libruby3.1:s390x (3.1.2-7ubuntu4) ... 274s Selecting previously unselected package ruby3.1. 274s Preparing to unpack .../07-ruby3.1_3.1.2-7ubuntu4_s390x.deb ... 274s Unpacking ruby3.1 (3.1.2-7ubuntu4) ... 274s Selecting previously unselected package ruby-rubygems. 274s Preparing to unpack .../08-ruby-rubygems_3.4.20-1_all.deb ... 274s Unpacking ruby-rubygems (3.4.20-1) ... 274s Selecting previously unselected package ruby. 274s Preparing to unpack .../09-ruby_1%3a3.1+1_s390x.deb ... 274s Unpacking ruby (1:3.1+1) ... 274s Selecting previously unselected package rake. 274s Preparing to unpack .../10-rake_13.0.6-3_all.deb ... 274s Unpacking rake (13.0.6-3) ... 274s Selecting previously unselected package gem2deb-test-runner. 274s Preparing to unpack .../11-gem2deb-test-runner_2.2.2_s390x.deb ... 274s Unpacking gem2deb-test-runner (2.2.2) ... 274s Selecting previously unselected package ruby-ffi:s390x. 274s Preparing to unpack .../12-ruby-ffi_1.16.3+dfsg-1build1_s390x.deb ... 274s Unpacking ruby-ffi:s390x (1.16.3+dfsg-1build1) ... 274s Selecting previously unselected package ruby-magic. 274s Preparing to unpack .../13-ruby-magic_0.2.9-3_all.deb ... 274s Unpacking ruby-magic (0.2.9-3) ... 274s Selecting previously unselected package ruby-test-declarative. 274s Preparing to unpack .../14-ruby-test-declarative_0.0.6-3_all.deb ... 274s Unpacking ruby-test-declarative (0.0.6-3) ... 274s Selecting previously unselected package autopkgtest-satdep. 274s Preparing to unpack .../15-1-autopkgtest-satdep.deb ... 274s Unpacking autopkgtest-satdep (0) ... 274s Setting up rubygems-integration (1.18) ... 274s Setting up ruby-test-declarative (0.0.6-3) ... 274s Setting up ruby-net-telnet (0.2.0-1) ... 274s Setting up ruby-webrick (1.8.1-1) ... 274s Setting up ruby-xmlrpc (0.3.2-2) ... 274s Setting up libruby:s390x (1:3.1+1) ... 274s Setting up ruby-rubygems (3.4.20-1) ... 274s Setting up ruby3.1 (3.1.2-7ubuntu4) ... 274s Setting up ruby-sdbm:s390x (1.0.0-5build3) ... 274s Setting up ruby (1:3.1+1) ... 274s Setting up rake (13.0.6-3) ... 274s Setting up libruby3.1:s390x (3.1.2-7ubuntu4) ... 274s Setting up gem2deb-test-runner (2.2.2) ... 274s Setting up ruby-ffi:s390x (1.16.3+dfsg-1build1) ... 274s Setting up ruby-magic (0.2.9-3) ... 274s Setting up autopkgtest-satdep (0) ... 274s Processing triggers for libc-bin (2.39-0ubuntu2) ... 274s Processing triggers for man-db (2.12.0-3) ... 277s (Reading database ... 54606 files and directories currently installed.) 277s Removing autopkgtest-satdep (0) ... 278s autopkgtest [22:00:47]: test gem2deb-test-runner: gem2deb-test-runner --autopkgtest --check-dependencies 2>&1 278s autopkgtest [22:00:47]: test gem2deb-test-runner: [----------------------- 278s 278s ┌──────────────────────────────────────────────────────────────────────────────┐ 278s │ Checking Rubygems dependency resolution on ruby3.1 │ 278s └──────────────────────────────────────────────────────────────────────────────┘ 278s 278s RUBYLIB=. GEM_PATH= ruby3.1 -e gem\ \"magic\" 278s mv lib ./.gem2deb.lib 278s mv ./.gem2deb.lib lib 278s 278s ┌──────────────────────────────────────────────────────────────────────────────┐ 278s │ Run tests for ruby3.1 from debian/ruby-tests.rake │ 278s └──────────────────────────────────────────────────────────────────────────────┘ 278s 278s RUBYLIB=. GEM_PATH= ruby3.1 -S rake --rakelibdir /gem2deb-nonexistent -f debian/ruby-tests.rake 278s mv lib ./.gem2deb.lib 278s /usr/bin/ruby3.1 -w -I"test" /usr/lib/ruby/gems/3.1.0/gems/rake-13.0.6/lib/rake/rake_test_loader.rb "test/test_magic.rb" -v 278s test_declarative is deprecated for Test::Unit::TestCase 278s Loaded suite /usr/lib/ruby/gems/3.1.0/gems/rake-13.0.6/lib/rake/rake_test_loader 278s Started 278s TestMagic: 278s test: guess encoding from string: .: (0.016506) 278s test: guess filelogo.jpg mime: .: (0.000378) 278s test: guess filelogo.jpg mime encoding: .: (0.002412) 278s test: guess filelogo.jpg mime type: .: (0.000958) 278s test: guess filelogo.jpg mime with empty database: .: (0.000138) 278s test: guess filelogo.jpg mime with magic_jpeg database: .: (0.000101) 278s test: guess magic.txt mime: .: (0.004706) 278s test: guess magic.txt mime encoding: .: (0.004836) 278s test: guess magic.txt mime type: .: (0.004169) 278s test: guess non-existing file mime: .: (0.000232) 278s test: guess with block: .: (0.000368) 278s 278s Finished in 0.036961417 seconds. 278s ------------------------------------------------------------------------------- 278s 11 tests, 11 assertions, 0 failures, 0 errors, 0 pendings, 0 omissions, 0 notifications 278s 100% passed 278s ------------------------------------------------------------------------------- 278s 297.61 tests/s, 297.61 assertions/s 278s mv ./.gem2deb.lib lib 279s autopkgtest [22:00:48]: test gem2deb-test-runner: -----------------------] 279s gem2deb-test-runner PASS 279s autopkgtest [22:00:48]: test gem2deb-test-runner: - - - - - - - - - - results - - - - - - - - - - 280s autopkgtest [22:00:49]: @@@@@@@@@@@@@@@@@@@@ summary 280s gem2deb-test-runner PASS 299s Creating nova instance adt-noble-s390x-ruby-magic-20240320-215609-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-s390x-server-20240319.img (UUID fdb88291-221e-4465-b557-6bcc22878017)...