0s autopkgtest [19:17:06]: starting date and time: 2024-11-08 19:17:06+0000 0s autopkgtest [19:17:06]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [19:17:06]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.q3_bvwht/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-21.secgroup --name adt-plucky-ppc64el-bluez-20241108-191705-juju-7f2275-prod-proposed-migration-environment-2-d0717820-d595-4f77-9397-3886ea1351f0 --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-ppc64el -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/ 116s autopkgtest [19:19:02]: testbed dpkg architecture: ppc64el 116s autopkgtest [19:19:02]: testbed apt version: 2.9.8 116s autopkgtest [19:19:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup 117s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 117s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 118s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1122 kB] 118s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [142 kB] 118s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [21.9 kB] 118s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [194 kB] 118s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [875 kB] 118s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [30.8 kB] 118s Fetched 2467 kB in 1s (1879 kB/s) 119s Reading package lists... 122s Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s Calculating upgrade... 123s The following packages will be upgraded: 123s libblockdev-crypto3 libblockdev-fs3 libblockdev-loop3 libblockdev-mdraid3 123s libblockdev-nvme3 libblockdev-part3 libblockdev-swap3 libblockdev-utils3 123s libblockdev3 python3-urllib3 tzdata 123s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s Need to get 576 kB of archives. 123s After this operation, 4096 B disk space will be freed. 123s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el tzdata all 2024b-1ubuntu2 [274 kB] 123s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-utils3 ppc64el 3.2.1-1 [20.6 kB] 123s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-crypto3 ppc64el 3.2.1-1 [24.8 kB] 123s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-fs3 ppc64el 3.2.1-1 [40.6 kB] 123s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-loop3 ppc64el 3.2.1-1 [7704 B] 123s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-mdraid3 ppc64el 3.2.1-1 [14.2 kB] 123s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-nvme3 ppc64el 3.2.1-1 [20.2 kB] 123s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-part3 ppc64el 3.2.1-1 [17.5 kB] 123s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev-swap3 ppc64el 3.2.1-1 [8538 B] 123s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el libblockdev3 ppc64el 3.2.1-1 [54.1 kB] 123s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-urllib3 all 2.0.7-2ubuntu0.1 [93.1 kB] 124s Preconfiguring packages ... 124s Fetched 576 kB in 1s (1046 kB/s) 124s (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 ... 73539 files and directories currently installed.) 124s Preparing to unpack .../00-tzdata_2024b-1ubuntu2_all.deb ... 124s Unpacking tzdata (2024b-1ubuntu2) over (2024a-4ubuntu1) ... 125s Preparing to unpack .../01-libblockdev-utils3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev-utils3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../02-libblockdev-crypto3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev-crypto3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../03-libblockdev-fs3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev-fs3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../04-libblockdev-loop3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev-loop3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../05-libblockdev-mdraid3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev-mdraid3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../06-libblockdev-nvme3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev-nvme3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../07-libblockdev-part3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev-part3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../08-libblockdev-swap3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev-swap3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../09-libblockdev3_3.2.1-1_ppc64el.deb ... 125s Unpacking libblockdev3:ppc64el (3.2.1-1) over (3.2.0-2) ... 125s Preparing to unpack .../10-python3-urllib3_2.0.7-2ubuntu0.1_all.deb ... 125s Unpacking python3-urllib3 (2.0.7-2ubuntu0.1) over (2.0.7-2) ... 125s Setting up tzdata (2024b-1ubuntu2) ... 125s 125s Current default time zone: 'Etc/UTC' 125s Local time is now: Fri Nov 8 19:19:11 UTC 2024. 125s Universal Time is now: Fri Nov 8 19:19:11 UTC 2024. 125s Run 'dpkg-reconfigure tzdata' if you wish to change it. 125s 125s Setting up libblockdev-utils3:ppc64el (3.2.1-1) ... 125s Setting up python3-urllib3 (2.0.7-2ubuntu0.1) ... 126s Setting up libblockdev-nvme3:ppc64el (3.2.1-1) ... 126s Setting up libblockdev-fs3:ppc64el (3.2.1-1) ... 126s Setting up libblockdev-mdraid3:ppc64el (3.2.1-1) ... 126s Setting up libblockdev-crypto3:ppc64el (3.2.1-1) ... 126s Setting up libblockdev-swap3:ppc64el (3.2.1-1) ... 126s Setting up libblockdev-loop3:ppc64el (3.2.1-1) ... 126s Setting up libblockdev3:ppc64el (3.2.1-1) ... 126s Setting up libblockdev-part3:ppc64el (3.2.1-1) ... 126s Processing triggers for libc-bin (2.40-1ubuntu3) ... 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 127s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 127s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 127s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 128s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 129s Reading package lists... 129s Reading package lists... 129s Building dependency tree... 129s Reading state information... 130s Calculating upgrade... 130s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 130s Reading package lists... 130s Building dependency tree... 130s Reading state information... 131s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 134s autopkgtest [19:19:20]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 134s autopkgtest [19:19:20]: @@@@@@@@@@@@@@@@@@@@ apt-source bluez 142s Get:1 http://ftpmaster.internal/ubuntu plucky/main bluez 5.78-0ubuntu2 (dsc) [3006 B] 142s Get:2 http://ftpmaster.internal/ubuntu plucky/main bluez 5.78-0ubuntu2 (tar) [2442 kB] 142s Get:3 http://ftpmaster.internal/ubuntu plucky/main bluez 5.78-0ubuntu2 (diff) [56.6 kB] 142s gpgv: Signature made Mon Oct 21 09:06:17 2024 UTC 142s gpgv: using RSA key 7B7E8201BD8E52916C47E775CAAAEEC8ACB66C1D 142s gpgv: issuer "daniel.van.vugt@canonical.com" 142s gpgv: Can't check signature: No public key 142s dpkg-source: warning: cannot verify inline signature for ./bluez_5.78-0ubuntu2.dsc: no acceptable signature found 143s autopkgtest [19:19:29]: testing package bluez version 5.78-0ubuntu2 143s autopkgtest [19:19:29]: build not needed 144s autopkgtest [19:19:30]: test bluez-response: 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 146s The following additional packages will be installed: 146s bluez bluez-test-scripts libasound2-data libasound2t64 146s Suggested packages: 146s pulseaudio-module-bluetooth alsa-utils libasound2-plugins 146s Recommended packages: 146s alsa-ucm-conf alsa-topology-conf 146s The following NEW packages will be installed: 146s autopkgtest-satdep bluez bluez-test-scripts libasound2-data libasound2t64 147s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 147s Need to get 2236 kB/2237 kB of archives. 147s After this operation, 9232 kB of additional disk space will be used. 147s Get:1 /tmp/autopkgtest.jPFj4h/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [728 B] 147s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libasound2-data all 1.2.12-1 [21.0 kB] 147s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libasound2t64 ppc64el 1.2.12-1 [493 kB] 147s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el bluez ppc64el 5.78-0ubuntu2 [1678 kB] 147s Get:5 http://ftpmaster.internal/ubuntu plucky/universe ppc64el bluez-test-scripts all 5.78-0ubuntu2 [44.4 kB] 147s Fetched 2236 kB in 1s (3359 kB/s) 148s Selecting previously unselected package libasound2-data. 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 ... 73538 files and directories currently installed.) 148s Preparing to unpack .../libasound2-data_1.2.12-1_all.deb ... 148s Unpacking libasound2-data (1.2.12-1) ... 148s Selecting previously unselected package libasound2t64:ppc64el. 148s Preparing to unpack .../libasound2t64_1.2.12-1_ppc64el.deb ... 148s Unpacking libasound2t64:ppc64el (1.2.12-1) ... 148s Selecting previously unselected package bluez. 148s Preparing to unpack .../bluez_5.78-0ubuntu2_ppc64el.deb ... 148s Unpacking bluez (5.78-0ubuntu2) ... 148s Selecting previously unselected package bluez-test-scripts. 148s Preparing to unpack .../bluez-test-scripts_5.78-0ubuntu2_all.deb ... 148s Unpacking bluez-test-scripts (5.78-0ubuntu2) ... 148s Selecting previously unselected package autopkgtest-satdep. 148s Preparing to unpack .../1-autopkgtest-satdep.deb ... 148s Unpacking autopkgtest-satdep (0) ... 148s Setting up libasound2-data (1.2.12-1) ... 148s Setting up libasound2t64:ppc64el (1.2.12-1) ... 148s Setting up bluez-test-scripts (5.78-0ubuntu2) ... 148s Setting up bluez (5.78-0ubuntu2) ... 149s Created symlink '/etc/systemd/system/dbus-org.bluez.service' → '/usr/lib/systemd/system/bluetooth.service'. 149s Created symlink '/etc/systemd/system/bluetooth.target.wants/bluetooth.service' → '/usr/lib/systemd/system/bluetooth.service'. 149s Setting up autopkgtest-satdep (0) ... 149s Processing triggers for man-db (2.12.1-3) ... 152s Processing triggers for dbus (1.14.10-4ubuntu5) ... 152s Processing triggers for libc-bin (2.40-1ubuntu3) ... 155s (Reading database ... 73782 files and directories currently installed.) 155s Removing autopkgtest-satdep (0) ... 156s autopkgtest [19:19:42]: test bluez-response: [----------------------- 157s testAdapter (__main__.TestBluezResponse.testAdapter) ... skipped 'No bluetooth devices available for testing' 158s testDevice (__main__.TestBluezResponse.testDevice) ... skipped 'No bluetooth devices available for testing' 158s 158s ---------------------------------------------------------------------- 158s Ran 2 tests in 1.677s 158s 158s OK (skipped=2) 158s autopkgtest [19:19:44]: test bluez-response: -----------------------] 159s bluez-response PASS 159s autopkgtest [19:19:45]: test bluez-response: - - - - - - - - - - results - - - - - - - - - - 159s autopkgtest [19:19:45]: @@@@@@@@@@@@@@@@@@@@ summary 159s bluez-response PASS 164s virt: nova [W] Using flock in prodstack6-ppc64el 164s virt: Creating nova instance adt-plucky-ppc64el-bluez-20241108-191705-juju-7f2275-prod-proposed-migration-environment-2-d0717820-d595-4f77-9397-3886ea1351f0 from image adt/ubuntu-plucky-ppc64el-server-20241108.img (UUID 211c30ab-5e06-4166-a7df-df534accb012)...