0s autopkgtest [16:54:08]: starting date and time: 2024-11-08 16:54:08+0000 0s autopkgtest [16:54:08]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [16:54:08]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.44cia5xg/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:ell,src:mptcpd --apt-upgrade bluez --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ell/0.70-1 mptcpd/0.12-5' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-7.secgroup --name adt-plucky-s390x-bluez-20241108-165408-juju-7f2275-prod-proposed-migration-environment-2-c0972a26-d692-4fa0-9bc6-60b386735f43 --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'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 114s autopkgtest [16:56:02]: testbed dpkg architecture: s390x 114s autopkgtest [16:56:02]: testbed apt version: 2.9.8 114s autopkgtest [16:56:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup 115s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 115s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [170 kB] 115s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [22.7 kB] 115s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1293 kB] 115s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 115s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [218 kB] 115s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [968 kB] 115s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [18.6 kB] 115s Fetched 2771 kB in 1s (3068 kB/s) 115s Reading package lists... 118s Reading package lists... 118s Building dependency tree... 118s Reading state information... 118s Calculating upgrade... 118s The following packages will be upgraded: 118s libblockdev-crypto3 libblockdev-fs3 libblockdev-loop3 libblockdev-mdraid3 118s libblockdev-nvme3 libblockdev-part3 libblockdev-swap3 libblockdev-utils3 118s libblockdev3 tzdata 118s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 118s Need to get 468 kB of archives. 118s After this operation, 4096 B disk space will be freed. 118s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x tzdata all 2024b-1ubuntu2 [274 kB] 119s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev-utils3 s390x 3.2.1-1 [19.3 kB] 119s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev-crypto3 s390x 3.2.1-1 [23.7 kB] 119s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev-fs3 s390x 3.2.1-1 [36.1 kB] 119s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev-loop3 s390x 3.2.1-1 [7090 B] 119s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev-mdraid3 s390x 3.2.1-1 [12.8 kB] 119s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev-nvme3 s390x 3.2.1-1 [18.1 kB] 119s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev-part3 s390x 3.2.1-1 [15.3 kB] 119s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev-swap3 s390x 3.2.1-1 [7706 B] 119s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libblockdev3 s390x 3.2.1-1 [53.8 kB] 119s Preconfiguring packages ... 119s Fetched 468 kB in 1s (910 kB/s) 119s (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 ... 55473 files and directories currently installed.) 119s Preparing to unpack .../0-tzdata_2024b-1ubuntu2_all.deb ... 119s Unpacking tzdata (2024b-1ubuntu2) over (2024a-4ubuntu1) ... 119s Preparing to unpack .../1-libblockdev-utils3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev-utils3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Preparing to unpack .../2-libblockdev-crypto3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev-crypto3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Preparing to unpack .../3-libblockdev-fs3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev-fs3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Preparing to unpack .../4-libblockdev-loop3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev-loop3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Preparing to unpack .../5-libblockdev-mdraid3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev-mdraid3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Preparing to unpack .../6-libblockdev-nvme3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev-nvme3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Preparing to unpack .../7-libblockdev-part3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev-part3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Preparing to unpack .../8-libblockdev-swap3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev-swap3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Preparing to unpack .../9-libblockdev3_3.2.1-1_s390x.deb ... 119s Unpacking libblockdev3:s390x (3.2.1-1) over (3.2.0-2) ... 119s Setting up tzdata (2024b-1ubuntu2) ... 119s 119s Current default time zone: 'Etc/UTC' 119s Local time is now: Fri Nov 8 16:56:07 UTC 2024. 119s Universal Time is now: Fri Nov 8 16:56:07 UTC 2024. 119s Run 'dpkg-reconfigure tzdata' if you wish to change it. 119s 119s Setting up libblockdev-utils3:s390x (3.2.1-1) ... 119s Setting up libblockdev-nvme3:s390x (3.2.1-1) ... 119s Setting up libblockdev-fs3:s390x (3.2.1-1) ... 119s Setting up libblockdev-mdraid3:s390x (3.2.1-1) ... 119s Setting up libblockdev-crypto3:s390x (3.2.1-1) ... 119s Setting up libblockdev-swap3:s390x (3.2.1-1) ... 119s Setting up libblockdev-loop3:s390x (3.2.1-1) ... 119s Setting up libblockdev3:s390x (3.2.1-1) ... 119s Setting up libblockdev-part3:s390x (3.2.1-1) ... 119s Processing triggers for libc-bin (2.40-1ubuntu3) ... 119s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 120s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 120s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 120s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 121s Reading package lists... 121s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s Calculating upgrade... 121s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 121s Reading package lists... 121s Building dependency tree... 121s Reading state information... 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s autopkgtest [16:56:12]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 124s autopkgtest [16:56:12]: @@@@@@@@@@@@@@@@@@@@ apt-source bluez 131s Get:1 http://ftpmaster.internal/ubuntu plucky/main bluez 5.78-0ubuntu2 (dsc) [3006 B] 131s Get:2 http://ftpmaster.internal/ubuntu plucky/main bluez 5.78-0ubuntu2 (tar) [2442 kB] 131s Get:3 http://ftpmaster.internal/ubuntu plucky/main bluez 5.78-0ubuntu2 (diff) [56.6 kB] 131s gpgv: Signature made Mon Oct 21 09:06:17 2024 UTC 131s gpgv: using RSA key 7B7E8201BD8E52916C47E775CAAAEEC8ACB66C1D 131s gpgv: issuer "daniel.van.vugt@canonical.com" 131s gpgv: Can't check signature: No public key 131s dpkg-source: warning: cannot verify inline signature for ./bluez_5.78-0ubuntu2.dsc: no acceptable signature found 131s autopkgtest [16:56:19]: testing package bluez version 5.78-0ubuntu2 132s autopkgtest [16:56:20]: build not needed 135s autopkgtest [16:56:23]: test bluez-response: preparing testbed 137s Reading package lists... 137s Building dependency tree... 137s Reading state information... 137s Starting pkgProblemResolver with broken count: 0 137s Starting 2 pkgProblemResolver with broken count: 0 137s Done 137s The following additional packages will be installed: 137s bluez bluez-test-scripts libasound2-data libasound2t64 137s Suggested packages: 137s pulseaudio-module-bluetooth alsa-utils libasound2-plugins 137s Recommended packages: 137s alsa-ucm-conf alsa-topology-conf 137s The following NEW packages will be installed: 137s autopkgtest-satdep bluez bluez-test-scripts libasound2-data libasound2t64 137s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 137s Need to get 1884 kB/1885 kB of archives. 137s After this operation, 6625 kB of additional disk space will be used. 137s Get:1 /tmp/autopkgtest.JktejX/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [720 B] 137s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libasound2-data all 1.2.12-1 [21.0 kB] 137s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libasound2t64 s390x 1.2.12-1 [408 kB] 138s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x bluez s390x 5.78-0ubuntu2 [1411 kB] 139s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x bluez-test-scripts all 5.78-0ubuntu2 [44.4 kB] 139s Fetched 1884 kB in 2s (1168 kB/s) 139s Selecting previously unselected package libasound2-data. 139s (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 ... 55472 files and directories currently installed.) 139s Preparing to unpack .../libasound2-data_1.2.12-1_all.deb ... 139s Unpacking libasound2-data (1.2.12-1) ... 139s Selecting previously unselected package libasound2t64:s390x. 139s Preparing to unpack .../libasound2t64_1.2.12-1_s390x.deb ... 139s Unpacking libasound2t64:s390x (1.2.12-1) ... 139s Selecting previously unselected package bluez. 139s Preparing to unpack .../bluez_5.78-0ubuntu2_s390x.deb ... 139s Unpacking bluez (5.78-0ubuntu2) ... 139s Selecting previously unselected package bluez-test-scripts. 139s Preparing to unpack .../bluez-test-scripts_5.78-0ubuntu2_all.deb ... 139s Unpacking bluez-test-scripts (5.78-0ubuntu2) ... 139s Selecting previously unselected package autopkgtest-satdep. 139s Preparing to unpack .../1-autopkgtest-satdep.deb ... 139s Unpacking autopkgtest-satdep (0) ... 139s Setting up libasound2-data (1.2.12-1) ... 139s Setting up libasound2t64:s390x (1.2.12-1) ... 139s Setting up bluez-test-scripts (5.78-0ubuntu2) ... 139s Setting up bluez (5.78-0ubuntu2) ... 139s Created symlink '/etc/systemd/system/dbus-org.bluez.service' → '/usr/lib/systemd/system/bluetooth.service'. 139s Created symlink '/etc/systemd/system/bluetooth.target.wants/bluetooth.service' → '/usr/lib/systemd/system/bluetooth.service'. 140s Setting up autopkgtest-satdep (0) ... 140s Processing triggers for man-db (2.12.1-3) ... 140s Processing triggers for dbus (1.14.10-4ubuntu5) ... 140s Processing triggers for libc-bin (2.40-1ubuntu3) ... 142s (Reading database ... 55716 files and directories currently installed.) 142s Removing autopkgtest-satdep (0) ... 143s autopkgtest [16:56:31]: test bluez-response: [----------------------- 144s testAdapter (__main__.TestBluezResponse.testAdapter) ... skipped 'No bluetooth devices available for testing' 144s testDevice (__main__.TestBluezResponse.testDevice) ... Can't open HCI socket.: Address family not supported by protocol 144s Can't open HCI socket.: Address family not supported by protocol 144s skipped 'No bluetooth devices available for testing' 144s 144s ---------------------------------------------------------------------- 144s Ran 2 tests in 0.757s 144s 144s OK (skipped=2) 144s autopkgtest [16:56:32]: test bluez-response: -----------------------] 145s autopkgtest [16:56:33]: test bluez-response: - - - - - - - - - - results - - - - - - - - - - 145s bluez-response FAIL stderr: Can't open HCI socket.: Address family not supported by protocol 145s autopkgtest [16:56:33]: test bluez-response: - - - - - - - - - - stderr - - - - - - - - - - 145s Can't open HCI socket.: Address family not supported by protocol 145s Can't open HCI socket.: Address family not supported by protocol 145s autopkgtest [16:56:33]: @@@@@@@@@@@@@@@@@@@@ summary 145s bluez-response FAIL stderr: Can't open HCI socket.: Address family not supported by protocol 158s virt: nova [W] Using flock in prodstack6-s390x 158s virt: Creating nova instance adt-plucky-s390x-bluez-20241108-165408-juju-7f2275-prod-proposed-migration-environment-2-c0972a26-d692-4fa0-9bc6-60b386735f43 from image adt/ubuntu-plucky-s390x-server-20241108.img (UUID 12750869-75e1-4ea7-b0ce-05f4f939e90e)...