0s autopkgtest [21:23:55]: starting date and time: 2024-03-20 21:23:55+0000 0s autopkgtest [21:23:55]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [21:23:55]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.de7ndrls/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@bos02-ppc64el-10.secgroup --name adt-noble-ppc64el-ruby-magic-20240320-212354-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-ppc64el-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://ftpmaster.internal/ubuntu/ 159s autopkgtest [21:26:34]: testbed dpkg architecture: ppc64el 159s autopkgtest [21:26:34]: testbed apt version: 2.7.12 159s autopkgtest [21:26:34]: @@@@@@@@@@@@@@@@@@@@ test bed setup 161s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 161s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3811 kB] 162s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 162s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [500 kB] 162s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 162s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [684 kB] 163s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 163s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 163s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 163s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4161 kB] 163s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 163s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [58.6 kB] 163s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 166s Fetched 9406 kB in 4s (2249 kB/s) 167s Reading package lists... 169s Reading package lists... 170s Building dependency tree... 170s Reading state information... 170s Calculating upgrade... 170s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 170s Reading package lists... 170s Building dependency tree... 170s Reading state information... 170s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 171s sh: Attempting to set up Debian/Ubuntu apt sources automatically 171s sh: Distribution appears to be Ubuntu 172s Reading package lists... 172s Building dependency tree... 172s Reading state information... 172s eatmydata is already the newest version (131-1). 172s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 172s Reading package lists... 172s Building dependency tree... 172s Reading state information... 173s dbus is already the newest version (1.14.10-4ubuntu1). 173s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 173s Reading package lists... 173s Building dependency tree... 173s Reading state information... 173s rng-tools-debian is already the newest version (2.4). 173s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 173s Reading package lists... 173s Building dependency tree... 173s Reading state information... 173s The following packages will be REMOVED: 173s cloud-init* python3-configobj* python3-debconf* 174s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 174s After this operation, 3252 kB disk space will be freed. 174s (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 ... 70095 files and directories currently installed.) 174s Removing cloud-init (24.1.1-0ubuntu1) ... 174s Removing python3-configobj (5.0.8-3) ... 174s Removing python3-debconf (1.5.86) ... 174s Processing triggers for man-db (2.12.0-3) ... 175s (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 ... 69706 files and directories currently installed.) 175s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 175s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 175s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 176s invoke-rc.d: policy-rc.d denied execution of try-restart. 176s Reading package lists... 176s Building dependency tree... 176s Reading state information... 176s linux-generic is already the newest version (6.8.0-11.11+1). 176s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 176s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 176s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 177s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 179s Reading package lists... 179s Reading package lists... 179s Building dependency tree... 179s Reading state information... 180s Calculating upgrade... 180s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 180s Reading package lists... 180s Building dependency tree... 180s Reading state information... 180s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 180s autopkgtest [21:26:55]: rebooting testbed after setup commands that affected boot 348s autopkgtest [21:29:43]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024 354s autopkgtest [21:29:49]: @@@@@@@@@@@@@@@@@@@@ apt-source ruby-magic 356s Get:1 http://ftpmaster.internal/ubuntu noble/universe ruby-magic 0.2.9-3 (dsc) [2072 B] 356s Get:2 http://ftpmaster.internal/ubuntu noble/universe ruby-magic 0.2.9-3 (tar) [12.2 kB] 356s Get:3 http://ftpmaster.internal/ubuntu noble/universe ruby-magic 0.2.9-3 (diff) [3948 B] 356s gpgv: Signature made Mon Feb 26 15:59:10 2024 UTC 356s gpgv: using RSA key 8ED6C3F8BAC9DB7FC130A870F823A2729883C97C 356s gpgv: issuer "kanashiro@debian.org" 356s gpgv: Can't check signature: No public key 356s dpkg-source: warning: cannot verify inline signature for ./ruby-magic_0.2.9-3.dsc: no acceptable signature found 356s autopkgtest [21:29:51]: testing package ruby-magic version 0.2.9-3 359s autopkgtest [21:29:54]: build not needed 360s autopkgtest [21:29:55]: test gem2deb-test-runner: preparing testbed 365s Reading package lists... 365s Building dependency tree... 365s Reading state information... 365s Starting pkgProblemResolver with broken count: 0 365s Starting 2 pkgProblemResolver with broken count: 0 365s Done 366s The following additional packages will be installed: 366s gem2deb-test-runner libruby libruby3.1 rake ruby ruby-ffi ruby-magic 366s ruby-net-telnet ruby-rubygems ruby-sdbm ruby-test-declarative ruby-webrick 366s ruby-xmlrpc ruby3.1 rubygems-integration 366s Suggested packages: 366s ri ruby-dev bundler 366s Recommended packages: 366s zip fonts-lato libjs-jquery 366s The following NEW packages will be installed: 366s autopkgtest-satdep gem2deb-test-runner libruby libruby3.1 rake ruby ruby-ffi 366s ruby-magic ruby-net-telnet ruby-rubygems ruby-sdbm ruby-test-declarative 366s ruby-webrick ruby-xmlrpc ruby3.1 rubygems-integration 366s 0 upgraded, 16 newly installed, 0 to remove and 0 not upgraded. 366s Need to get 6201 kB/6202 kB of archives. 366s After this operation, 35.1 MB of additional disk space will be used. 366s Get:1 /tmp/autopkgtest.O4qYNz/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [748 B] 366s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el rubygems-integration all 1.18 [5336 B] 366s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el ruby-net-telnet all 0.2.0-1 [13.3 kB] 366s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el ruby-webrick all 1.8.1-1 [52.3 kB] 366s Get:5 http://ftpmaster.internal/ubuntu noble/main ppc64el ruby-xmlrpc all 0.3.2-2 [24.8 kB] 366s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el libruby ppc64el 1:3.1+1 [4638 B] 366s Get:7 http://ftpmaster.internal/ubuntu noble/main ppc64el ruby-sdbm ppc64el 1.0.0-5build3 [22.9 kB] 366s Get:8 http://ftpmaster.internal/ubuntu noble/main ppc64el libruby3.1 ppc64el 3.1.2-7ubuntu4 [5560 kB] 366s Get:9 http://ftpmaster.internal/ubuntu noble/main ppc64el ruby3.1 ppc64el 3.1.2-7ubuntu4 [49.0 kB] 366s Get:10 http://ftpmaster.internal/ubuntu noble/main ppc64el ruby-rubygems all 3.4.20-1 [238 kB] 366s Get:11 http://ftpmaster.internal/ubuntu noble/main ppc64el ruby ppc64el 1:3.1+1 [3464 B] 366s Get:12 http://ftpmaster.internal/ubuntu noble/main ppc64el rake all 13.0.6-3 [61.6 kB] 366s Get:13 http://ftpmaster.internal/ubuntu noble/universe ppc64el gem2deb-test-runner ppc64el 2.2.2 [18.2 kB] 366s Get:14 http://ftpmaster.internal/ubuntu noble/main ppc64el ruby-ffi ppc64el 1.16.3+dfsg-1build1 [136 kB] 366s Get:15 http://ftpmaster.internal/ubuntu noble/universe ppc64el ruby-magic all 0.2.9-3 [6796 B] 366s Get:16 http://ftpmaster.internal/ubuntu noble/universe ppc64el ruby-test-declarative all 0.0.6-3 [3694 B] 367s Fetched 6201 kB in 1s (7536 kB/s) 367s Selecting previously unselected package rubygems-integration. 367s (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 ... 69651 files and directories currently installed.) 367s Preparing to unpack .../00-rubygems-integration_1.18_all.deb ... 367s Unpacking rubygems-integration (1.18) ... 367s Selecting previously unselected package ruby-net-telnet. 367s Preparing to unpack .../01-ruby-net-telnet_0.2.0-1_all.deb ... 367s Unpacking ruby-net-telnet (0.2.0-1) ... 367s Selecting previously unselected package ruby-webrick. 367s Preparing to unpack .../02-ruby-webrick_1.8.1-1_all.deb ... 367s Unpacking ruby-webrick (1.8.1-1) ... 367s Selecting previously unselected package ruby-xmlrpc. 367s Preparing to unpack .../03-ruby-xmlrpc_0.3.2-2_all.deb ... 367s Unpacking ruby-xmlrpc (0.3.2-2) ... 367s Selecting previously unselected package libruby:ppc64el. 367s Preparing to unpack .../04-libruby_1%3a3.1+1_ppc64el.deb ... 367s Unpacking libruby:ppc64el (1:3.1+1) ... 367s Selecting previously unselected package ruby-sdbm:ppc64el. 367s Preparing to unpack .../05-ruby-sdbm_1.0.0-5build3_ppc64el.deb ... 367s Unpacking ruby-sdbm:ppc64el (1.0.0-5build3) ... 367s Selecting previously unselected package libruby3.1:ppc64el. 367s Preparing to unpack .../06-libruby3.1_3.1.2-7ubuntu4_ppc64el.deb ... 367s Unpacking libruby3.1:ppc64el (3.1.2-7ubuntu4) ... 368s Selecting previously unselected package ruby3.1. 368s Preparing to unpack .../07-ruby3.1_3.1.2-7ubuntu4_ppc64el.deb ... 368s Unpacking ruby3.1 (3.1.2-7ubuntu4) ... 368s Selecting previously unselected package ruby-rubygems. 368s Preparing to unpack .../08-ruby-rubygems_3.4.20-1_all.deb ... 368s Unpacking ruby-rubygems (3.4.20-1) ... 368s Selecting previously unselected package ruby. 368s Preparing to unpack .../09-ruby_1%3a3.1+1_ppc64el.deb ... 368s Unpacking ruby (1:3.1+1) ... 368s Selecting previously unselected package rake. 368s Preparing to unpack .../10-rake_13.0.6-3_all.deb ... 368s Unpacking rake (13.0.6-3) ... 368s Selecting previously unselected package gem2deb-test-runner. 368s Preparing to unpack .../11-gem2deb-test-runner_2.2.2_ppc64el.deb ... 368s Unpacking gem2deb-test-runner (2.2.2) ... 368s Selecting previously unselected package ruby-ffi:ppc64el. 368s Preparing to unpack .../12-ruby-ffi_1.16.3+dfsg-1build1_ppc64el.deb ... 368s Unpacking ruby-ffi:ppc64el (1.16.3+dfsg-1build1) ... 368s Selecting previously unselected package ruby-magic. 368s Preparing to unpack .../13-ruby-magic_0.2.9-3_all.deb ... 368s Unpacking ruby-magic (0.2.9-3) ... 368s Selecting previously unselected package ruby-test-declarative. 368s Preparing to unpack .../14-ruby-test-declarative_0.0.6-3_all.deb ... 368s Unpacking ruby-test-declarative (0.0.6-3) ... 368s Selecting previously unselected package autopkgtest-satdep. 368s Preparing to unpack .../15-1-autopkgtest-satdep.deb ... 368s Unpacking autopkgtest-satdep (0) ... 368s Setting up rubygems-integration (1.18) ... 368s Setting up ruby-test-declarative (0.0.6-3) ... 368s Setting up ruby-net-telnet (0.2.0-1) ... 368s Setting up ruby-webrick (1.8.1-1) ... 368s Setting up ruby-xmlrpc (0.3.2-2) ... 368s Setting up libruby:ppc64el (1:3.1+1) ... 368s Setting up ruby-rubygems (3.4.20-1) ... 368s Setting up ruby3.1 (3.1.2-7ubuntu4) ... 368s Setting up ruby-sdbm:ppc64el (1.0.0-5build3) ... 368s Setting up ruby (1:3.1+1) ... 368s Setting up rake (13.0.6-3) ... 368s Setting up libruby3.1:ppc64el (3.1.2-7ubuntu4) ... 368s Setting up gem2deb-test-runner (2.2.2) ... 368s Setting up ruby-ffi:ppc64el (1.16.3+dfsg-1build1) ... 368s Setting up ruby-magic (0.2.9-3) ... 368s Setting up autopkgtest-satdep (0) ... 368s Processing triggers for libc-bin (2.39-0ubuntu2) ... 368s Processing triggers for man-db (2.12.0-3) ... 371s (Reading database ... 72925 files and directories currently installed.) 371s Removing autopkgtest-satdep (0) ... 372s autopkgtest [21:30:07]: test gem2deb-test-runner: gem2deb-test-runner --autopkgtest --check-dependencies 2>&1 372s autopkgtest [21:30:07]: test gem2deb-test-runner: [----------------------- 372s 372s ┌──────────────────────────────────────────────────────────────────────────────┐ 372s │ Checking Rubygems dependency resolution on ruby3.1 │ 372s └──────────────────────────────────────────────────────────────────────────────┘ 372s 372s RUBYLIB=. GEM_PATH= ruby3.1 -e gem\ \"magic\" 372s mv lib ./.gem2deb.lib 372s mv ./.gem2deb.lib lib 372s 372s ┌──────────────────────────────────────────────────────────────────────────────┐ 372s │ Run tests for ruby3.1 from debian/ruby-tests.rake │ 372s └──────────────────────────────────────────────────────────────────────────────┘ 372s 372s RUBYLIB=. GEM_PATH= ruby3.1 -S rake --rakelibdir /gem2deb-nonexistent -f debian/ruby-tests.rake 372s mv lib ./.gem2deb.lib 372s /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 372s test_declarative is deprecated for Test::Unit::TestCase 373s Loaded suite /usr/lib/ruby/gems/3.1.0/gems/rake-13.0.6/lib/rake/rake_test_loader 373s Started 373s TestMagic: 373s test: guess encoding from string: .: (0.019974) 373s test: guess filelogo.jpg mime: .: (0.000380) 373s test: guess filelogo.jpg mime encoding: .: (0.003227) 373s test: guess filelogo.jpg mime type: .: (0.000219) 373s test: guess filelogo.jpg mime with empty database: .: (0.000156) 373s test: guess filelogo.jpg mime with magic_jpeg database: .: (0.000154) 373s test: guess magic.txt mime: .: (0.003598) 373s test: guess magic.txt mime encoding: .: (0.003366) 373s test: guess magic.txt mime type: .: (0.003490) 373s test: guess non-existing file mime: .: (0.000343) 373s test: guess with block: .: (0.000294) 373s 373s Finished in 0.036515052 seconds. 373s ------------------------------------------------------------------------------- 373s 11 tests, 11 assertions, 0 failures, 0 errors, 0 pendings, 0 omissions, 0 notifications 373s 100% passed 373s ------------------------------------------------------------------------------- 373s 301.25 tests/s, 301.25 assertions/s 373s mv ./.gem2deb.lib lib 373s autopkgtest [21:30:08]: test gem2deb-test-runner: -----------------------] 373s gem2deb-test-runner PASS 373s autopkgtest [21:30:08]: test gem2deb-test-runner: - - - - - - - - - - results - - - - - - - - - - 374s autopkgtest [21:30:09]: @@@@@@@@@@@@@@@@@@@@ summary 374s gem2deb-test-runner PASS 386s Creating nova instance adt-noble-ppc64el-ruby-magic-20240320-212354-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-ppc64el-server-20240320.img (UUID 1c7ceb83-4842-4144-a3c9-88510cdc9534)...