0s autopkgtest [00:21:28]: starting date and time: 2025-12-04 00:21:28+0000 0s autopkgtest [00:21:28]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [00:21:28]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ftnc9dul/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade habluetooth --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.13.9-2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-1.secgroup --name adt-resolute-amd64-habluetooth-20251204-002127-juju-7f2275-prod-proposed-migration-environment-2-3da346c1-0f53-4ed8-b6b1-ad1a06172fdb --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 5s Creating nova instance adt-resolute-amd64-habluetooth-20251204-002127-juju-7f2275-prod-proposed-migration-environment-2-3da346c1-0f53-4ed8-b6b1-ad1a06172fdb from image adt/ubuntu-resolute-amd64-server-20251203.img (UUID 32968f47-375b-4278-ba47-0724b7286e54)... 74s autopkgtest [00:22:42]: testbed dpkg architecture: amd64 75s autopkgtest [00:22:43]: testbed apt version: 3.1.12 75s autopkgtest [00:22:43]: @@@@@@@@@@@@@@@@@@@@ test bed setup 75s autopkgtest [00:22:43]: testbed release detected to be: None 77s autopkgtest [00:22:45]: updating testbed package index (apt update) 78s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 78s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 78s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 78s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 78s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [153 kB] 78s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [913 kB] 79s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.1 kB] 79s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [218 kB] 79s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [162 kB] 79s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [6752 B] 79s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [120 B] 79s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [334 kB] 79s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [736 kB] 79s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [23.5 kB] 79s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4040 B] 79s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [9636 B] 79s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [748 B] 83s Fetched 2707 kB in 1s (2204 kB/s) 85s Reading package lists... 87s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 87s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 88s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 88s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 89s Reading package lists... 89s Reading package lists... 89s Building dependency tree... 89s Reading state information... 90s Calculating upgrade... 90s The following packages will be upgraded: 90s cloud-guest-utils fwupd libflashrom1 libfwupd3 libinih1 libpython3-stdlib 90s python3 python3-gdbm python3-minimal 90s 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s Need to get 6223 kB of archives. 90s After this operation, 111 kB of additional disk space will be used. 90s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.13.9-2 [28.1 kB] 91s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.13.9-2 [23.0 kB] 91s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.13.9-2 [10.8 kB] 91s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.13.9-2 [11.0 kB] 91s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libflashrom1 amd64 1.6.0-2ubuntu1 [227 kB] 91s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 fwupd amd64 2.0.18-1 [5758 kB] 94s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 libfwupd3 amd64 2.0.18-1 [139 kB] 94s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libinih1 amd64 61-1ubuntu1 [7726 B] 94s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 cloud-guest-utils all 0.33-1build1 [18.7 kB] 94s dpkg-preconfigure: unable to re-open stdin: No such file or directory 94s Fetched 6223 kB in 4s (1717 kB/s) 95s (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 ... 123179 files and directories currently installed.) 95s Preparing to unpack .../python3-minimal_3.13.9-2_amd64.deb ... 95s Unpacking python3-minimal (3.13.9-2) over (3.13.7-1) ... 95s Setting up python3-minimal (3.13.9-2) ... 95s (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 ... 123179 files and directories currently installed.) 95s Preparing to unpack .../0-python3_3.13.9-2_amd64.deb ... 95s running python pre-rtupdate hooks for python3.13... 95s Unpacking python3 (3.13.9-2) over (3.13.7-1) ... 95s Preparing to unpack .../1-libpython3-stdlib_3.13.9-2_amd64.deb ... 95s Unpacking libpython3-stdlib:amd64 (3.13.9-2) over (3.13.7-1) ... 95s Preparing to unpack .../2-python3-gdbm_3.13.9-2_amd64.deb ... 95s Unpacking python3-gdbm (3.13.9-2) over (3.13.9-1) ... 95s Preparing to unpack .../3-libflashrom1_1.6.0-2ubuntu1_amd64.deb ... 95s Unpacking libflashrom1:amd64 (1.6.0-2ubuntu1) over (1.4.0-3ubuntu1) ... 96s Preparing to unpack .../4-fwupd_2.0.18-1_amd64.deb ... 96s Unpacking fwupd (2.0.18-1) over (2.0.17-6) ... 96s Preparing to unpack .../5-libfwupd3_2.0.18-1_amd64.deb ... 96s Unpacking libfwupd3:amd64 (2.0.18-1) over (2.0.17-6) ... 96s Preparing to unpack .../6-libinih1_61-1ubuntu1_amd64.deb ... 96s Unpacking libinih1:amd64 (61-1ubuntu1) over (59-1ubuntu1) ... 96s Preparing to unpack .../7-cloud-guest-utils_0.33-1build1_all.deb ... 96s Unpacking cloud-guest-utils (0.33-1build1) over (0.33-1) ... 96s Setting up python3-gdbm (3.13.9-2) ... 97s Setting up libinih1:amd64 (61-1ubuntu1) ... 97s Setting up libfwupd3:amd64 (2.0.18-1) ... 97s Setting up libflashrom1:amd64 (1.6.0-2ubuntu1) ... 97s Setting up fwupd (2.0.18-1) ... 97s fwupd-refresh.service is a disabled or a static unit not running, not starting it. 97s fwupd.service is a disabled or a static unit not running, not starting it. 97s Setting up libpython3-stdlib:amd64 (3.13.9-2) ... 97s Setting up python3 (3.13.9-2) ... 97s running python rtupdate hooks for python3.13... 97s running python post-rtupdate hooks for python3.13... 98s Setting up cloud-guest-utils (0.33-1build1) ... 98s Processing triggers for man-db (2.13.1-1) ... 99s Processing triggers for dbus (1.16.2-2ubuntu2) ... 99s Processing triggers for libc-bin (2.42-2ubuntu2) ... 100s autopkgtest [00:23:08]: upgrading testbed (apt dist-upgrade and autopurge) 100s Reading package lists... 100s Building dependency tree... 100s Reading state information... 101s Calculating upgrade... 101s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 101s Reading package lists... 102s Building dependency tree... 102s Reading state information... 102s Solving dependencies... 102s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 102s autopkgtest [00:23:10]: rebooting testbed after setup commands that affected boot 133s autopkgtest [00:23:41]: testbed running kernel: Linux 6.17.0-6-generic #6-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 7 13:34:17 UTC 2025 139s autopkgtest [00:23:47]: @@@@@@@@@@@@@@@@@@@@ apt-source habluetooth 141s Get:1 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (dsc) [2584 B] 141s Get:2 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (tar) [133 kB] 141s Get:3 http://ftpmaster.internal/ubuntu resolute/universe habluetooth 4.0.1-1 (diff) [4508 B] 141s gpgv: Signature made Mon Jul 7 19:49:22 2025 UTC 141s gpgv: using RSA key FB8ACFA78C726089C38AD0269605A1098C63B92A 141s gpgv: Can't check signature: No public key 141s dpkg-source: warning: cannot verify inline signature for ./habluetooth_4.0.1-1.dsc: no acceptable signature found 141s autopkgtest [00:23:49]: testing package habluetooth version 4.0.1-1 142s autopkgtest [00:23:50]: build not needed 145s autopkgtest [00:23:53]: test pybuild-autopkgtest: preparing testbed 145s Reading package lists... 146s Building dependency tree... 146s Reading state information... 146s Solving dependencies... 146s The following NEW packages will be installed: 146s autoconf automake autopoint autotools-dev bluez build-essential cpp cpp-15 146s cpp-15-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit 146s dh-autoreconf dh-python dh-strip-nondeterminism docutils-common dwz 146s fonts-font-awesome fonts-lato furo g++ g++-15 g++-15-x86-64-linux-gnu 146s g++-x86-64-linux-gnu gcc gcc-15 gcc-15-x86-64-linux-gnu gcc-x86-64-linux-gnu 146s gettext intltool-debian libarchive-zip-perl libasan8 libasound2-data 146s libasound2t64 libcc1-0 libdebhelper-perl libfile-stripnondeterminism-perl 146s libgcc-15-dev libgomp1 libhwasan0 libisl23 libitm1 libjs-jquery 146s libjs-jquery-hotkeys libjs-jquery-isonscreen libjs-jquery-metadata 146s libjs-jquery-tablesorter libjs-jquery-throttle-debounce libjs-sphinxdoc 146s libjs-underscore libjson-perl liblsan0 libmpc3 libpython3.14-minimal 146s libpython3.14-stdlib libquadmath0 libstdc++-15-dev libtool libtsan2 146s libubsan1 m4 po-debconf pybuild-plugin-autopkgtest pybuild-plugin-pyproject 146s python3-accessible-pygments python3-aiooui python3-alabaster python3-all 146s python3-async-generator python3-async-interrupt python3-bleak 146s python3-bleak-retry-connector python3-bluetooth-adapters 146s python3-bluetooth-auto-recovery python3-bluetooth-data-tools python3-bs4 146s python3-btsocket python3-build python3-coverage python3-dbus-fast 146s python3-defusedxml python3-docutils python3-freezegun python3-habluetooth 146s python3-imagesize python3-iniconfig python3-installer 146s python3-mdit-py-plugins python3-myst-parser python3-pluggy 146s python3-poetry-core python3-pyproject-hooks python3-pyric python3-pytest 146s python3-pytest-asyncio python3-pytest-cov python3-roman 146s python3-snowballstemmer python3-soupsieve python3-sphinx 146s python3-uart-devices python3-usb-devices python3-wheel python3.14 146s python3.14-minimal sgml-base sphinx-basic-ng sphinx-common 146s sphinx-rtd-theme-common xml-core 147s 0 upgraded, 110 newly installed, 0 to remove and 0 not upgraded. 147s Need to get 93.9 MB of archives. 147s After this operation, 326 MB of additional disk space will be used. 147s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 fonts-lato all 2.015-1build1 [2779 kB] 150s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.0-4 [906 kB] 151s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.0-4 [2559 kB] 152s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 m4 amd64 1.4.20-2 [217 kB] 152s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 autoconf all 2.72-3.1ubuntu1 [384 kB] 152s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 autotools-dev all 20240727.1 [43.4 kB] 152s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 automake all 1:1.18.1-3 [582 kB] 153s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 autopoint all 0.23.2-1 [620 kB] 153s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 libasound2-data all 1.2.14-2ubuntu1 [21.3 kB] 153s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 libasound2t64 amd64 1.2.14-2ubuntu1 [409 kB] 153s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 bluez amd64 5.84-1 [1555 kB] 154s Get:12 http://ftpmaster.internal/ubuntu resolute/main amd64 libisl23 amd64 0.27-1 [685 kB] 154s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 libmpc3 amd64 1.3.1-2 [54.8 kB] 154s Get:14 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-15-x86-64-linux-gnu amd64 15.2.0-9ubuntu1 [12.9 MB] 157s Get:15 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-15 amd64 15.2.0-9ubuntu1 [1030 B] 157s Get:16 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [5746 B] 157s Get:17 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp amd64 4:15.2.0-4ubuntu1 [22.4 kB] 157s Get:18 http://ftpmaster.internal/ubuntu resolute/main amd64 libcc1-0 amd64 15.2.0-9ubuntu1 [47.4 kB] 157s Get:19 http://ftpmaster.internal/ubuntu resolute/main amd64 libgomp1 amd64 15.2.0-9ubuntu1 [151 kB] 157s Get:20 http://ftpmaster.internal/ubuntu resolute/main amd64 libitm1 amd64 15.2.0-9ubuntu1 [29.6 kB] 157s Get:21 http://ftpmaster.internal/ubuntu resolute/main amd64 libasan8 amd64 15.2.0-9ubuntu1 [3071 kB] 158s Get:22 http://ftpmaster.internal/ubuntu resolute/main amd64 liblsan0 amd64 15.2.0-9ubuntu1 [1360 kB] 158s Get:23 http://ftpmaster.internal/ubuntu resolute/main amd64 libtsan2 amd64 15.2.0-9ubuntu1 [2757 kB] 159s Get:24 http://ftpmaster.internal/ubuntu resolute/main amd64 libubsan1 amd64 15.2.0-9ubuntu1 [1210 kB] 159s Get:25 http://ftpmaster.internal/ubuntu resolute/main amd64 libhwasan0 amd64 15.2.0-9ubuntu1 [1685 kB] 159s Get:26 http://ftpmaster.internal/ubuntu resolute/main amd64 libquadmath0 amd64 15.2.0-9ubuntu1 [153 kB] 159s Get:27 http://ftpmaster.internal/ubuntu resolute/main amd64 libgcc-15-dev amd64 15.2.0-9ubuntu1 [2863 kB] 160s Get:28 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-15-x86-64-linux-gnu amd64 15.2.0-9ubuntu1 [25.4 MB] 167s Get:29 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-15 amd64 15.2.0-9ubuntu1 [527 kB] 167s Get:30 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [1208 B] 167s Get:31 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc amd64 4:15.2.0-4ubuntu1 [5024 B] 167s Get:32 http://ftpmaster.internal/ubuntu resolute/main amd64 libstdc++-15-dev amd64 15.2.0-9ubuntu1 [2574 kB] 167s Get:33 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-15-x86-64-linux-gnu amd64 15.2.0-9ubuntu1 [14.4 MB] 172s Get:34 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-15 amd64 15.2.0-9ubuntu1 [24.3 kB] 172s Get:35 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [966 B] 172s Get:36 http://ftpmaster.internal/ubuntu resolute/main amd64 g++ amd64 4:15.2.0-4ubuntu1 [1100 B] 172s Get:37 http://ftpmaster.internal/ubuntu resolute/main amd64 build-essential amd64 12.12ubuntu2 [5256 B] 172s Get:38 http://ftpmaster.internal/ubuntu resolute/main amd64 libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 172s Get:39 http://ftpmaster.internal/ubuntu resolute/main amd64 libtool all 2.5.4-7 [169 kB] 172s Get:40 http://ftpmaster.internal/ubuntu resolute/main amd64 dh-autoreconf all 21 [12.5 kB] 172s Get:41 http://ftpmaster.internal/ubuntu resolute/main amd64 libarchive-zip-perl all 1.68-1 [90.2 kB] 172s Get:42 http://ftpmaster.internal/ubuntu resolute/main amd64 libfile-stripnondeterminism-perl all 1.15.0-1 [20.5 kB] 172s Get:43 http://ftpmaster.internal/ubuntu resolute/main amd64 dh-strip-nondeterminism all 1.15.0-1 [5090 B] 172s Get:44 http://ftpmaster.internal/ubuntu resolute/main amd64 debugedit amd64 1:5.2-3 [49.9 kB] 172s Get:45 http://ftpmaster.internal/ubuntu resolute/main amd64 dwz amd64 0.16-2 [115 kB] 172s Get:46 http://ftpmaster.internal/ubuntu resolute/main amd64 gettext amd64 0.23.2-1 [1019 kB] 172s Get:47 http://ftpmaster.internal/ubuntu resolute/main amd64 intltool-debian all 0.35.0+20060710.6build1 [24.1 kB] 172s Get:48 http://ftpmaster.internal/ubuntu resolute/main amd64 po-debconf all 1.0.21+nmu1 [233 kB] 172s Get:49 http://ftpmaster.internal/ubuntu resolute/main amd64 debhelper all 13.24.2ubuntu1 [896 kB] 172s Get:50 http://ftpmaster.internal/ubuntu resolute/universe amd64 dh-python all 6.20251029 [120 kB] 172s Get:51 http://ftpmaster.internal/ubuntu resolute/main amd64 sgml-base all 1.31+nmu1 [11.0 kB] 172s Get:52 http://ftpmaster.internal/ubuntu resolute/main amd64 xml-core all 0.19 [20.3 kB] 172s Get:53 http://ftpmaster.internal/ubuntu resolute/main amd64 docutils-common all 0.21.2+dfsg-2 [131 kB] 172s Get:54 http://ftpmaster.internal/ubuntu resolute/main amd64 fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1build1 [531 kB] 173s Get:55 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-accessible-pygments all 0.0.5-2 [790 kB] 173s Get:56 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-soupsieve all 2.7-2 [33.6 kB] 173s Get:57 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-bs4 all 4.14.2-1 [82.8 kB] 173s Get:58 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-defusedxml all 0.7.1-3 [42.2 kB] 173s Get:59 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-jquery all 3.7.1+dfsg+~3.5.33-1build1 [321 kB] 173s Get:60 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 173s Get:61 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 173s Get:62 http://ftpmaster.internal/ubuntu resolute/main amd64 libjson-perl all 4.10000-1 [81.9 kB] 173s Get:63 http://ftpmaster.internal/ubuntu resolute/main amd64 sphinx-common all 8.2.3-1ubuntu2 [656 kB] 173s Get:64 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-alabaster all 0.7.16-0.1 [18.5 kB] 173s Get:65 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-roman all 5.1-1 [10.6 kB] 173s Get:66 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-docutils all 0.21.2+dfsg-2 [409 kB] 173s Get:67 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-imagesize all 1.4.1-1build1 [6900 B] 173s Get:68 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-snowballstemmer all 3.0.1-1 [65.9 kB] 173s Get:69 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-sphinx all 8.2.3-1ubuntu2 [482 kB] 173s Get:70 http://ftpmaster.internal/ubuntu resolute/universe amd64 sphinx-basic-ng all 1.0.0~beta2-1 [12.0 kB] 173s Get:71 http://ftpmaster.internal/ubuntu resolute/universe amd64 furo all 2025.09.25+dfsg-2 [53.7 kB] 173s Get:72 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-metadata all 12-4 [6582 B] 173s Get:73 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-tablesorter all 1:2.31.3+dfsg1-4 [192 kB] 173s Get:74 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-throttle-debounce all 1.1+dfsg.1-2 [12.5 kB] 173s Get:75 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.0-4 [2397 kB] 174s Get:76 http://ftpmaster.internal/ubuntu resolute/universe amd64 pybuild-plugin-autopkgtest all 6.20251029 [1746 B] 174s Get:77 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pyproject-hooks all 1.2.0-1 [10.2 kB] 174s Get:78 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-wheel all 0.46.1-2 [22.1 kB] 174s Get:79 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-build all 1.2.2-4 [31.0 kB] 174s Get:80 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-installer all 0.7.0+dfsg1-3 [17.4 kB] 174s Get:81 http://ftpmaster.internal/ubuntu resolute/universe amd64 pybuild-plugin-pyproject all 6.20251029 [1732 B] 174s Get:82 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-aiooui all 0.1.9-1 [312 kB] 174s Get:83 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.0-4 [805 kB] 174s Get:84 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.13.9-2 [892 B] 174s Get:85 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-async-generator all 1.10-7 [18.1 kB] 174s Get:86 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-async-interrupt all 1.2.2-1 [6604 B] 174s Get:87 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-dbus-fast amd64 3.1.2-1 [992 kB] 174s Get:88 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-bleak all 1.1.1-1 [95.0 kB] 174s Get:89 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-uart-devices all 0.1.1-1 [6742 B] 174s Get:90 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-usb-devices all 0.4.5-2 [9014 B] 174s Get:91 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-bluetooth-adapters all 2.1.1-1 [13.7 kB] 174s Get:92 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-bleak-retry-connector all 4.4.4-1 [20.1 kB] 174s Get:93 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-btsocket all 0.3.0-1 [19.0 kB] 174s Get:94 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pyric all 0.1.6.4+git20170421.0562b17-1 [114 kB] 174s Get:95 http://ftpmaster.internal/ubuntu resolute/main amd64 sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 174s Get:96 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-bluetooth-auto-recovery all 1.5.3-1 [29.4 kB] 174s Get:97 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-bluetooth-data-tools amd64 1.28.4-1 [11.7 kB] 174s Get:98 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-coverage amd64 7.8.2+dfsg1-1 [157 kB] 174s Get:99 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-freezegun all 1.5.1-1.2 [15.9 kB] 174s Get:100 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-habluetooth amd64 4.0.1-1 [65.3 kB] 174s Get:101 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-iniconfig all 2.1.0-1 [6840 B] 175s Get:102 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-mdit-py-plugins all 0.5.0-1 [31.5 kB] 176s Get:103 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-myst-parser all 4.0.1-1 [64.9 kB] 176s Get:104 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pluggy all 1.6.0-1 [21.0 kB] 176s Get:105 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-poetry-core all 2.2.1-2 [226 kB] 176s Get:106 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest all 8.3.5-2 [252 kB] 176s Get:107 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-asyncio all 0.25.1-1 [17.0 kB] 176s Get:108 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-hotkeys all 0.2.0-1 [13.3 kB] 176s Get:109 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-isonscreen all 1.2.0-2 [3814 B] 176s Get:110 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-cov all 5.0.0-1 [21.3 kB] 177s Fetched 93.9 MB in 30s (3150 kB/s) 177s Selecting previously unselected package fonts-lato. 177s (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 ... 123178 files and directories currently installed.) 177s Preparing to unpack .../000-fonts-lato_2.015-1build1_all.deb ... 177s Unpacking fonts-lato (2.015-1build1) ... 177s Selecting previously unselected package libpython3.14-minimal:amd64. 177s Preparing to unpack .../001-libpython3.14-minimal_3.14.0-4_amd64.deb ... 177s Unpacking libpython3.14-minimal:amd64 (3.14.0-4) ... 177s Selecting previously unselected package python3.14-minimal. 177s Preparing to unpack .../002-python3.14-minimal_3.14.0-4_amd64.deb ... 177s Unpacking python3.14-minimal (3.14.0-4) ... 177s Selecting previously unselected package m4. 177s Preparing to unpack .../003-m4_1.4.20-2_amd64.deb ... 177s Unpacking m4 (1.4.20-2) ... 177s Selecting previously unselected package autoconf. 177s Preparing to unpack .../004-autoconf_2.72-3.1ubuntu1_all.deb ... 177s Unpacking autoconf (2.72-3.1ubuntu1) ... 177s Selecting previously unselected package autotools-dev. 177s Preparing to unpack .../005-autotools-dev_20240727.1_all.deb ... 177s Unpacking autotools-dev (20240727.1) ... 177s Selecting previously unselected package automake. 177s Preparing to unpack .../006-automake_1%3a1.18.1-3_all.deb ... 177s Unpacking automake (1:1.18.1-3) ... 177s Selecting previously unselected package autopoint. 178s Preparing to unpack .../007-autopoint_0.23.2-1_all.deb ... 178s Unpacking autopoint (0.23.2-1) ... 178s Selecting previously unselected package libasound2-data. 178s Preparing to unpack .../008-libasound2-data_1.2.14-2ubuntu1_all.deb ... 178s Unpacking libasound2-data (1.2.14-2ubuntu1) ... 178s Selecting previously unselected package libasound2t64:amd64. 178s Preparing to unpack .../009-libasound2t64_1.2.14-2ubuntu1_amd64.deb ... 178s Unpacking libasound2t64:amd64 (1.2.14-2ubuntu1) ... 178s Selecting previously unselected package bluez. 178s Preparing to unpack .../010-bluez_5.84-1_amd64.deb ... 178s Unpacking bluez (5.84-1) ... 178s Selecting previously unselected package libisl23:amd64. 178s Preparing to unpack .../011-libisl23_0.27-1_amd64.deb ... 178s Unpacking libisl23:amd64 (0.27-1) ... 178s Selecting previously unselected package libmpc3:amd64. 178s Preparing to unpack .../012-libmpc3_1.3.1-2_amd64.deb ... 178s Unpacking libmpc3:amd64 (1.3.1-2) ... 178s Selecting previously unselected package cpp-15-x86-64-linux-gnu. 178s Preparing to unpack .../013-cpp-15-x86-64-linux-gnu_15.2.0-9ubuntu1_amd64.deb ... 178s Unpacking cpp-15-x86-64-linux-gnu (15.2.0-9ubuntu1) ... 178s Selecting previously unselected package cpp-15. 178s Preparing to unpack .../014-cpp-15_15.2.0-9ubuntu1_amd64.deb ... 178s Unpacking cpp-15 (15.2.0-9ubuntu1) ... 178s Selecting previously unselected package cpp-x86-64-linux-gnu. 178s Preparing to unpack .../015-cpp-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 178s Unpacking cpp-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 178s Selecting previously unselected package cpp. 178s Preparing to unpack .../016-cpp_4%3a15.2.0-4ubuntu1_amd64.deb ... 178s Unpacking cpp (4:15.2.0-4ubuntu1) ... 178s Selecting previously unselected package libcc1-0:amd64. 178s Preparing to unpack .../017-libcc1-0_15.2.0-9ubuntu1_amd64.deb ... 178s Unpacking libcc1-0:amd64 (15.2.0-9ubuntu1) ... 178s Selecting previously unselected package libgomp1:amd64. 178s Preparing to unpack .../018-libgomp1_15.2.0-9ubuntu1_amd64.deb ... 178s Unpacking libgomp1:amd64 (15.2.0-9ubuntu1) ... 178s Selecting previously unselected package libitm1:amd64. 178s Preparing to unpack .../019-libitm1_15.2.0-9ubuntu1_amd64.deb ... 178s Unpacking libitm1:amd64 (15.2.0-9ubuntu1) ... 178s Selecting previously unselected package libasan8:amd64. 178s Preparing to unpack .../020-libasan8_15.2.0-9ubuntu1_amd64.deb ... 178s Unpacking libasan8:amd64 (15.2.0-9ubuntu1) ... 178s Selecting previously unselected package liblsan0:amd64. 178s Preparing to unpack .../021-liblsan0_15.2.0-9ubuntu1_amd64.deb ... 178s Unpacking liblsan0:amd64 (15.2.0-9ubuntu1) ... 178s Selecting previously unselected package libtsan2:amd64. 178s Preparing to unpack .../022-libtsan2_15.2.0-9ubuntu1_amd64.deb ... 178s Unpacking libtsan2:amd64 (15.2.0-9ubuntu1) ... 179s Selecting previously unselected package libubsan1:amd64. 179s Preparing to unpack .../023-libubsan1_15.2.0-9ubuntu1_amd64.deb ... 179s Unpacking libubsan1:amd64 (15.2.0-9ubuntu1) ... 179s Selecting previously unselected package libhwasan0:amd64. 179s Preparing to unpack .../024-libhwasan0_15.2.0-9ubuntu1_amd64.deb ... 179s Unpacking libhwasan0:amd64 (15.2.0-9ubuntu1) ... 179s Selecting previously unselected package libquadmath0:amd64. 179s Preparing to unpack .../025-libquadmath0_15.2.0-9ubuntu1_amd64.deb ... 179s Unpacking libquadmath0:amd64 (15.2.0-9ubuntu1) ... 179s Selecting previously unselected package libgcc-15-dev:amd64. 179s Preparing to unpack .../026-libgcc-15-dev_15.2.0-9ubuntu1_amd64.deb ... 179s Unpacking libgcc-15-dev:amd64 (15.2.0-9ubuntu1) ... 179s Selecting previously unselected package gcc-15-x86-64-linux-gnu. 179s Preparing to unpack .../027-gcc-15-x86-64-linux-gnu_15.2.0-9ubuntu1_amd64.deb ... 179s Unpacking gcc-15-x86-64-linux-gnu (15.2.0-9ubuntu1) ... 179s Selecting previously unselected package gcc-15. 179s Preparing to unpack .../028-gcc-15_15.2.0-9ubuntu1_amd64.deb ... 179s Unpacking gcc-15 (15.2.0-9ubuntu1) ... 179s Selecting previously unselected package gcc-x86-64-linux-gnu. 179s Preparing to unpack .../029-gcc-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 179s Unpacking gcc-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 179s Selecting previously unselected package gcc. 179s Preparing to unpack .../030-gcc_4%3a15.2.0-4ubuntu1_amd64.deb ... 179s Unpacking gcc (4:15.2.0-4ubuntu1) ... 179s Selecting previously unselected package libstdc++-15-dev:amd64. 179s Preparing to unpack .../031-libstdc++-15-dev_15.2.0-9ubuntu1_amd64.deb ... 179s Unpacking libstdc++-15-dev:amd64 (15.2.0-9ubuntu1) ... 180s Selecting previously unselected package g++-15-x86-64-linux-gnu. 180s Preparing to unpack .../032-g++-15-x86-64-linux-gnu_15.2.0-9ubuntu1_amd64.deb ... 180s Unpacking g++-15-x86-64-linux-gnu (15.2.0-9ubuntu1) ... 180s Selecting previously unselected package g++-15. 180s Preparing to unpack .../033-g++-15_15.2.0-9ubuntu1_amd64.deb ... 180s Unpacking g++-15 (15.2.0-9ubuntu1) ... 180s Selecting previously unselected package g++-x86-64-linux-gnu. 180s Preparing to unpack .../034-g++-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 180s Unpacking g++-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 180s Selecting previously unselected package g++. 180s Preparing to unpack .../035-g++_4%3a15.2.0-4ubuntu1_amd64.deb ... 180s Unpacking g++ (4:15.2.0-4ubuntu1) ... 180s Selecting previously unselected package build-essential. 180s Preparing to unpack .../036-build-essential_12.12ubuntu2_amd64.deb ... 180s Unpacking build-essential (12.12ubuntu2) ... 180s Selecting previously unselected package libdebhelper-perl. 180s Preparing to unpack .../037-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 180s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 180s Selecting previously unselected package libtool. 180s Preparing to unpack .../038-libtool_2.5.4-7_all.deb ... 180s Unpacking libtool (2.5.4-7) ... 180s Selecting previously unselected package dh-autoreconf. 180s Preparing to unpack .../039-dh-autoreconf_21_all.deb ... 180s Unpacking dh-autoreconf (21) ... 180s Selecting previously unselected package libarchive-zip-perl. 180s Preparing to unpack .../040-libarchive-zip-perl_1.68-1_all.deb ... 180s Unpacking libarchive-zip-perl (1.68-1) ... 180s Selecting previously unselected package libfile-stripnondeterminism-perl. 180s Preparing to unpack .../041-libfile-stripnondeterminism-perl_1.15.0-1_all.deb ... 180s Unpacking libfile-stripnondeterminism-perl (1.15.0-1) ... 180s Selecting previously unselected package dh-strip-nondeterminism. 180s Preparing to unpack .../042-dh-strip-nondeterminism_1.15.0-1_all.deb ... 180s Unpacking dh-strip-nondeterminism (1.15.0-1) ... 180s Selecting previously unselected package debugedit. 180s Preparing to unpack .../043-debugedit_1%3a5.2-3_amd64.deb ... 180s Unpacking debugedit (1:5.2-3) ... 180s Selecting previously unselected package dwz. 180s Preparing to unpack .../044-dwz_0.16-2_amd64.deb ... 180s Unpacking dwz (0.16-2) ... 180s Selecting previously unselected package gettext. 180s Preparing to unpack .../045-gettext_0.23.2-1_amd64.deb ... 180s Unpacking gettext (0.23.2-1) ... 180s Selecting previously unselected package intltool-debian. 180s Preparing to unpack .../046-intltool-debian_0.35.0+20060710.6build1_all.deb ... 180s Unpacking intltool-debian (0.35.0+20060710.6build1) ... 180s Selecting previously unselected package po-debconf. 180s Preparing to unpack .../047-po-debconf_1.0.21+nmu1_all.deb ... 180s Unpacking po-debconf (1.0.21+nmu1) ... 180s Selecting previously unselected package debhelper. 180s Preparing to unpack .../048-debhelper_13.24.2ubuntu1_all.deb ... 180s Unpacking debhelper (13.24.2ubuntu1) ... 180s Selecting previously unselected package dh-python. 180s Preparing to unpack .../049-dh-python_6.20251029_all.deb ... 180s Unpacking dh-python (6.20251029) ... 180s Selecting previously unselected package sgml-base. 180s Preparing to unpack .../050-sgml-base_1.31+nmu1_all.deb ... 180s Unpacking sgml-base (1.31+nmu1) ... 180s Selecting previously unselected package xml-core. 180s Preparing to unpack .../051-xml-core_0.19_all.deb ... 180s Unpacking xml-core (0.19) ... 180s Selecting previously unselected package docutils-common. 180s Preparing to unpack .../052-docutils-common_0.21.2+dfsg-2_all.deb ... 180s Unpacking docutils-common (0.21.2+dfsg-2) ... 180s Selecting previously unselected package fonts-font-awesome. 180s Preparing to unpack .../053-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1build1_all.deb ... 180s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1build1) ... 180s Selecting previously unselected package python3-accessible-pygments. 180s Preparing to unpack .../054-python3-accessible-pygments_0.0.5-2_all.deb ... 180s Unpacking python3-accessible-pygments (0.0.5-2) ... 180s Selecting previously unselected package python3-soupsieve. 181s Preparing to unpack .../055-python3-soupsieve_2.7-2_all.deb ... 181s Unpacking python3-soupsieve (2.7-2) ... 181s Selecting previously unselected package python3-bs4. 181s Preparing to unpack .../056-python3-bs4_4.14.2-1_all.deb ... 181s Unpacking python3-bs4 (4.14.2-1) ... 181s Selecting previously unselected package python3-defusedxml. 181s Preparing to unpack .../057-python3-defusedxml_0.7.1-3_all.deb ... 181s Unpacking python3-defusedxml (0.7.1-3) ... 181s Selecting previously unselected package libjs-jquery. 181s Preparing to unpack .../058-libjs-jquery_3.7.1+dfsg+~3.5.33-1build1_all.deb ... 181s Unpacking libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 181s Selecting previously unselected package libjs-underscore. 181s Preparing to unpack .../059-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 181s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 181s Selecting previously unselected package libjs-sphinxdoc. 181s Preparing to unpack .../060-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 181s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 181s Selecting previously unselected package libjson-perl. 181s Preparing to unpack .../061-libjson-perl_4.10000-1_all.deb ... 181s Unpacking libjson-perl (4.10000-1) ... 181s Selecting previously unselected package sphinx-common. 181s Preparing to unpack .../062-sphinx-common_8.2.3-1ubuntu2_all.deb ... 181s Unpacking sphinx-common (8.2.3-1ubuntu2) ... 181s Selecting previously unselected package python3-alabaster. 181s Preparing to unpack .../063-python3-alabaster_0.7.16-0.1_all.deb ... 181s Unpacking python3-alabaster (0.7.16-0.1) ... 181s Selecting previously unselected package python3-roman. 181s Preparing to unpack .../064-python3-roman_5.1-1_all.deb ... 181s Unpacking python3-roman (5.1-1) ... 181s Selecting previously unselected package python3-docutils. 181s Preparing to unpack .../065-python3-docutils_0.21.2+dfsg-2_all.deb ... 181s Unpacking python3-docutils (0.21.2+dfsg-2) ... 181s Selecting previously unselected package python3-imagesize. 181s Preparing to unpack .../066-python3-imagesize_1.4.1-1build1_all.deb ... 181s Unpacking python3-imagesize (1.4.1-1build1) ... 181s Selecting previously unselected package python3-snowballstemmer. 181s Preparing to unpack .../067-python3-snowballstemmer_3.0.1-1_all.deb ... 181s Unpacking python3-snowballstemmer (3.0.1-1) ... 181s Selecting previously unselected package python3-sphinx. 181s Preparing to unpack .../068-python3-sphinx_8.2.3-1ubuntu2_all.deb ... 181s Unpacking python3-sphinx (8.2.3-1ubuntu2) ... 181s Selecting previously unselected package sphinx-basic-ng. 181s Preparing to unpack .../069-sphinx-basic-ng_1.0.0~beta2-1_all.deb ... 181s Unpacking sphinx-basic-ng (1.0.0~beta2-1) ... 181s Selecting previously unselected package furo. 181s Preparing to unpack .../070-furo_2025.09.25+dfsg-2_all.deb ... 181s Unpacking furo (2025.09.25+dfsg-2) ... 181s Selecting previously unselected package libjs-jquery-metadata. 181s Preparing to unpack .../071-libjs-jquery-metadata_12-4_all.deb ... 181s Unpacking libjs-jquery-metadata (12-4) ... 181s Selecting previously unselected package libjs-jquery-tablesorter. 181s Preparing to unpack .../072-libjs-jquery-tablesorter_1%3a2.31.3+dfsg1-4_all.deb ... 181s Unpacking libjs-jquery-tablesorter (1:2.31.3+dfsg1-4) ... 181s Selecting previously unselected package libjs-jquery-throttle-debounce. 181s Preparing to unpack .../073-libjs-jquery-throttle-debounce_1.1+dfsg.1-2_all.deb ... 181s Unpacking libjs-jquery-throttle-debounce (1.1+dfsg.1-2) ... 181s Selecting previously unselected package libpython3.14-stdlib:amd64. 181s Preparing to unpack .../074-libpython3.14-stdlib_3.14.0-4_amd64.deb ... 181s Unpacking libpython3.14-stdlib:amd64 (3.14.0-4) ... 181s Selecting previously unselected package pybuild-plugin-autopkgtest. 181s Preparing to unpack .../075-pybuild-plugin-autopkgtest_6.20251029_all.deb ... 181s Unpacking pybuild-plugin-autopkgtest (6.20251029) ... 181s Selecting previously unselected package python3-pyproject-hooks. 181s Preparing to unpack .../076-python3-pyproject-hooks_1.2.0-1_all.deb ... 181s Unpacking python3-pyproject-hooks (1.2.0-1) ... 181s Selecting previously unselected package python3-wheel. 181s Preparing to unpack .../077-python3-wheel_0.46.1-2_all.deb ... 181s Unpacking python3-wheel (0.46.1-2) ... 181s Selecting previously unselected package python3-build. 181s Preparing to unpack .../078-python3-build_1.2.2-4_all.deb ... 181s Unpacking python3-build (1.2.2-4) ... 181s Selecting previously unselected package python3-installer. 181s Preparing to unpack .../079-python3-installer_0.7.0+dfsg1-3_all.deb ... 181s Unpacking python3-installer (0.7.0+dfsg1-3) ... 181s Selecting previously unselected package pybuild-plugin-pyproject. 182s Preparing to unpack .../080-pybuild-plugin-pyproject_6.20251029_all.deb ... 182s Unpacking pybuild-plugin-pyproject (6.20251029) ... 182s Selecting previously unselected package python3-aiooui. 182s Preparing to unpack .../081-python3-aiooui_0.1.9-1_all.deb ... 182s Unpacking python3-aiooui (0.1.9-1) ... 182s Selecting previously unselected package python3.14. 182s Preparing to unpack .../082-python3.14_3.14.0-4_amd64.deb ... 182s Unpacking python3.14 (3.14.0-4) ... 182s Selecting previously unselected package python3-all. 182s Preparing to unpack .../083-python3-all_3.13.9-2_amd64.deb ... 182s Unpacking python3-all (3.13.9-2) ... 182s Selecting previously unselected package python3-async-generator. 182s Preparing to unpack .../084-python3-async-generator_1.10-7_all.deb ... 182s Unpacking python3-async-generator (1.10-7) ... 182s Selecting previously unselected package python3-async-interrupt. 182s Preparing to unpack .../085-python3-async-interrupt_1.2.2-1_all.deb ... 182s Unpacking python3-async-interrupt (1.2.2-1) ... 182s Selecting previously unselected package python3-dbus-fast. 182s Preparing to unpack .../086-python3-dbus-fast_3.1.2-1_amd64.deb ... 182s Unpacking python3-dbus-fast (3.1.2-1) ... 182s Selecting previously unselected package python3-bleak. 182s Preparing to unpack .../087-python3-bleak_1.1.1-1_all.deb ... 182s Unpacking python3-bleak (1.1.1-1) ... 182s Selecting previously unselected package python3-uart-devices. 182s Preparing to unpack .../088-python3-uart-devices_0.1.1-1_all.deb ... 182s Unpacking python3-uart-devices (0.1.1-1) ... 182s Selecting previously unselected package python3-usb-devices. 182s Preparing to unpack .../089-python3-usb-devices_0.4.5-2_all.deb ... 182s Unpacking python3-usb-devices (0.4.5-2) ... 182s Selecting previously unselected package python3-bluetooth-adapters. 182s Preparing to unpack .../090-python3-bluetooth-adapters_2.1.1-1_all.deb ... 182s Unpacking python3-bluetooth-adapters (2.1.1-1) ... 182s Selecting previously unselected package python3-bleak-retry-connector. 182s Preparing to unpack .../091-python3-bleak-retry-connector_4.4.4-1_all.deb ... 182s Unpacking python3-bleak-retry-connector (4.4.4-1) ... 182s Selecting previously unselected package python3-btsocket. 182s Preparing to unpack .../092-python3-btsocket_0.3.0-1_all.deb ... 182s Unpacking python3-btsocket (0.3.0-1) ... 182s Selecting previously unselected package python3-pyric. 182s Preparing to unpack .../093-python3-pyric_0.1.6.4+git20170421.0562b17-1_all.deb ... 182s Unpacking python3-pyric (0.1.6.4+git20170421.0562b17-1) ... 182s Selecting previously unselected package sphinx-rtd-theme-common. 182s Preparing to unpack .../094-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 182s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 182s Selecting previously unselected package python3-bluetooth-auto-recovery. 182s Preparing to unpack .../095-python3-bluetooth-auto-recovery_1.5.3-1_all.deb ... 182s Unpacking python3-bluetooth-auto-recovery (1.5.3-1) ... 182s Selecting previously unselected package python3-bluetooth-data-tools. 182s Preparing to unpack .../096-python3-bluetooth-data-tools_1.28.4-1_amd64.deb ... 182s Unpacking python3-bluetooth-data-tools (1.28.4-1) ... 182s Selecting previously unselected package python3-coverage. 182s Preparing to unpack .../097-python3-coverage_7.8.2+dfsg1-1_amd64.deb ... 182s Unpacking python3-coverage (7.8.2+dfsg1-1) ... 182s Selecting previously unselected package python3-freezegun. 182s Preparing to unpack .../098-python3-freezegun_1.5.1-1.2_all.deb ... 182s Unpacking python3-freezegun (1.5.1-1.2) ... 182s Selecting previously unselected package python3-habluetooth. 182s Preparing to unpack .../099-python3-habluetooth_4.0.1-1_amd64.deb ... 182s Unpacking python3-habluetooth (4.0.1-1) ... 182s Selecting previously unselected package python3-iniconfig. 182s Preparing to unpack .../100-python3-iniconfig_2.1.0-1_all.deb ... 182s Unpacking python3-iniconfig (2.1.0-1) ... 182s Selecting previously unselected package python3-mdit-py-plugins. 182s Preparing to unpack .../101-python3-mdit-py-plugins_0.5.0-1_all.deb ... 182s Unpacking python3-mdit-py-plugins (0.5.0-1) ... 182s Selecting previously unselected package python3-myst-parser. 182s Preparing to unpack .../102-python3-myst-parser_4.0.1-1_all.deb ... 182s Unpacking python3-myst-parser (4.0.1-1) ... 182s Selecting previously unselected package python3-pluggy. 182s Preparing to unpack .../103-python3-pluggy_1.6.0-1_all.deb ... 182s Unpacking python3-pluggy (1.6.0-1) ... 182s Selecting previously unselected package python3-poetry-core. 182s Preparing to unpack .../104-python3-poetry-core_2.2.1-2_all.deb ... 182s Unpacking python3-poetry-core (2.2.1-2) ... 182s Selecting previously unselected package python3-pytest. 182s Preparing to unpack .../105-python3-pytest_8.3.5-2_all.deb ... 182s Unpacking python3-pytest (8.3.5-2) ... 182s Selecting previously unselected package python3-pytest-asyncio. 182s Preparing to unpack .../106-python3-pytest-asyncio_0.25.1-1_all.deb ... 182s Unpacking python3-pytest-asyncio (0.25.1-1) ... 182s Selecting previously unselected package libjs-jquery-hotkeys. 182s Preparing to unpack .../107-libjs-jquery-hotkeys_0.2.0-1_all.deb ... 182s Unpacking libjs-jquery-hotkeys (0.2.0-1) ... 183s Selecting previously unselected package libjs-jquery-isonscreen. 183s Preparing to unpack .../108-libjs-jquery-isonscreen_1.2.0-2_all.deb ... 183s Unpacking libjs-jquery-isonscreen (1.2.0-2) ... 183s Selecting previously unselected package python3-pytest-cov. 183s Preparing to unpack .../109-python3-pytest-cov_5.0.0-1_all.deb ... 183s Unpacking python3-pytest-cov (5.0.0-1) ... 183s Setting up dh-python (6.20251029) ... 183s Setting up python3-pyric (0.1.6.4+git20170421.0562b17-1) ... 183s Setting up python3-iniconfig (2.1.0-1) ... 183s Setting up python3-aiooui (0.1.9-1) ... 184s Setting up python3-uart-devices (0.1.1-1) ... 184s Setting up fonts-lato (2.015-1build1) ... 184s Setting up python3-usb-devices (0.4.5-2) ... 184s Setting up python3-async-generator (1.10-7) ... 184s Setting up python3-bluetooth-data-tools (1.28.4-1) ... 185s Setting up python3-defusedxml (0.7.1-3) ... 185s Setting up libarchive-zip-perl (1.68-1) ... 185s Setting up python3-alabaster (0.7.16-0.1) ... 185s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 185s Setting up libpython3.14-minimal:amd64 (3.14.0-4) ... 185s Setting up python3-mdit-py-plugins (0.5.0-1) ... 185s Setting up m4 (1.4.20-2) ... 185s Setting up python3-coverage (7.8.2+dfsg1-1) ... 186s Setting up libjs-jquery-throttle-debounce (1.1+dfsg.1-2) ... 186s Setting up libgomp1:amd64 (15.2.0-9ubuntu1) ... 186s Setting up python3-wheel (0.46.1-2) ... 186s Setting up python3-btsocket (0.3.0-1) ... 186s Setting up libasound2-data (1.2.14-2ubuntu1) ... 186s Setting up python3-roman (5.1-1) ... 187s Setting up autotools-dev (20240727.1) ... 187s Setting up libasound2t64:amd64 (1.2.14-2ubuntu1) ... 187s Setting up python3-pyproject-hooks (1.2.0-1) ... 187s Setting up python3-accessible-pygments (0.0.5-2) ... 187s Setting up python3-snowballstemmer (3.0.1-1) ... 188s Setting up python3-poetry-core (2.2.1-2) ... 189s Setting up libquadmath0:amd64 (15.2.0-9ubuntu1) ... 189s Setting up libmpc3:amd64 (1.3.1-2) ... 189s Setting up autopoint (0.23.2-1) ... 189s Setting up python3-installer (0.7.0+dfsg1-3) ... 189s Setting up autoconf (2.72-3.1ubuntu1) ... 189s Setting up python3-pluggy (1.6.0-1) ... 189s Setting up python3-async-interrupt (1.2.2-1) ... 190s Setting up libubsan1:amd64 (15.2.0-9ubuntu1) ... 190s Setting up dwz (0.16-2) ... 190s Setting up libhwasan0:amd64 (15.2.0-9ubuntu1) ... 190s Setting up libasan8:amd64 (15.2.0-9ubuntu1) ... 190s Setting up libjson-perl (4.10000-1) ... 190s Setting up debugedit (1:5.2-3) ... 190s Setting up sgml-base (1.31+nmu1) ... 190s Setting up libtsan2:amd64 (15.2.0-9ubuntu1) ... 190s Setting up libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 190s Setting up libjs-jquery-hotkeys (0.2.0-1) ... 190s Setting up libisl23:amd64 (0.27-1) ... 190s Setting up python3-build (1.2.2-4) ... 190s Setting up python3-soupsieve (2.7-2) ... 190s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1build1) ... 190s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 190s Setting up python3.14-minimal (3.14.0-4) ... 191s Setting up libcc1-0:amd64 (15.2.0-9ubuntu1) ... 191s Setting up python3-freezegun (1.5.1-1.2) ... 191s Setting up liblsan0:amd64 (15.2.0-9ubuntu1) ... 191s Setting up libitm1:amd64 (15.2.0-9ubuntu1) ... 191s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 191s Setting up python3-imagesize (1.4.1-1build1) ... 192s Setting up automake (1:1.18.1-3) ... 192s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 192s Setting up libfile-stripnondeterminism-perl (1.15.0-1) ... 192s Setting up gettext (0.23.2-1) ... 192s Setting up libgcc-15-dev:amd64 (15.2.0-9ubuntu1) ... 192s Setting up libpython3.14-stdlib:amd64 (3.14.0-4) ... 192s Setting up pybuild-plugin-pyproject (6.20251029) ... 192s Setting up python3-pytest (8.3.5-2) ... 192s Setting up cpp-15-x86-64-linux-gnu (15.2.0-9ubuntu1) ... 192s Setting up python3-bs4 (4.14.2-1) ... 193s Setting up intltool-debian (0.35.0+20060710.6build1) ... 193s Setting up gcc-15-x86-64-linux-gnu (15.2.0-9ubuntu1) ... 193s Setting up bluez (5.84-1) ... 193s Created symlink '/etc/systemd/user/default.target.wants/mpris-proxy.service' → '/usr/lib/systemd/user/mpris-proxy.service'. 194s Created symlink '/etc/systemd/system/dbus-org.bluez.service' → '/usr/lib/systemd/system/bluetooth.service'. 194s Created symlink '/etc/systemd/system/bluetooth.target.wants/bluetooth.service' → '/usr/lib/systemd/system/bluetooth.service'. 194s Setting up libstdc++-15-dev:amd64 (15.2.0-9ubuntu1) ... 194s Setting up libjs-jquery-metadata (12-4) ... 194s Setting up libjs-jquery-isonscreen (1.2.0-2) ... 194s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 194s Setting up python3-pytest-asyncio (0.25.1-1) ... 194s Setting up dh-strip-nondeterminism (1.15.0-1) ... 194s Setting up libjs-jquery-tablesorter (1:2.31.3+dfsg1-4) ... 194s Setting up cpp-15 (15.2.0-9ubuntu1) ... 194s Setting up xml-core (0.19) ... 195s Setting up python3.14 (3.14.0-4) ... 196s Setting up cpp-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 196s Setting up g++-15-x86-64-linux-gnu (15.2.0-9ubuntu1) ... 196s Setting up python3-dbus-fast (3.1.2-1) ... 196s Setting up gcc-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 196s Setting up po-debconf (1.0.21+nmu1) ... 196s Setting up python3-all (3.13.9-2) ... 196s Setting up python3-pytest-cov (5.0.0-1) ... 196s Setting up gcc-15 (15.2.0-9ubuntu1) ... 196s Setting up sphinx-common (8.2.3-1ubuntu2) ... 196s Setting up cpp (4:15.2.0-4ubuntu1) ... 196s Setting up g++-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 196s Setting up g++-15 (15.2.0-9ubuntu1) ... 196s Setting up python3-bleak (1.1.1-1) ... 197s Setting up python3-bluetooth-adapters (2.1.1-1) ... 197s Setting up libtool (2.5.4-7) ... 197s Setting up python3-bleak-retry-connector (4.4.4-1) ... 197s Setting up gcc (4:15.2.0-4ubuntu1) ... 197s Setting up dh-autoreconf (21) ... 197s Setting up python3-bluetooth-auto-recovery (1.5.3-1) ... 197s Setting up python3-habluetooth (4.0.1-1) ... 198s Setting up g++ (4:15.2.0-4ubuntu1) ... 198s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 198s Setting up build-essential (12.12ubuntu2) ... 198s Setting up debhelper (13.24.2ubuntu1) ... 198s Setting up pybuild-plugin-autopkgtest (6.20251029) ... 198s Processing triggers for install-info (7.2-5) ... 198s Processing triggers for libc-bin (2.42-2ubuntu2) ... 198s Processing triggers for systemd (257.9-0ubuntu2) ... 198s Processing triggers for man-db (2.13.1-1) ... 200s Processing triggers for dbus (1.16.2-2ubuntu2) ... 200s Processing triggers for sgml-base (1.31+nmu1) ... 200s Setting up docutils-common (0.21.2+dfsg-2) ... 200s Processing triggers for sgml-base (1.31+nmu1) ... 200s Setting up python3-docutils (0.21.2+dfsg-2) ... 201s Setting up python3-sphinx (8.2.3-1ubuntu2) ... 203s Setting up python3-myst-parser (4.0.1-1) ... 203s Setting up sphinx-basic-ng (1.0.0~beta2-1) ... 204s Setting up furo (2025.09.25+dfsg-2) ... 205s autopkgtest [00:24:53]: test pybuild-autopkgtest: pybuild-autopkgtest 205s autopkgtest [00:24:53]: test pybuild-autopkgtest: [----------------------- 205s pybuild-autopkgtest 206s I: pybuild base:317: cd /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build; python3.14 -m pytest --no-cov --ignore=tests/test_benchmark_base_scanner.py 206s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset. 206s The event loop scope for asynchronous fixtures will default to the fixture caching scope. Future versions of pytest-asyncio will default the loop scope for asynchronous fixtures to function scope. Set the default fixture loop scope explicitly in order to avoid unexpected behavior in the future. Valid fixture loop scopes are: "function", "class", "module", "package", "session" 206s 206s warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET)) 206s ============================= test session starts ============================== 206s platform linux -- Python 3.14.0, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.14 206s cachedir: .pytest_cache 206s rootdir: /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build 206s configfile: pyproject.toml 206s plugins: typeguard-4.4.2, asyncio-0.25.1, cov-5.0.0 206s asyncio: mode=Mode.STRICT, asyncio_default_fixture_loop_scope=None 207s collecting ... collected 90 items 207s 207s tests/test_base_scanner.py::test_remote_scanner[None] 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 1%] 207s tests/test_base_scanner.py::test_remote_scanner[w] 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 2%] 207s tests/test_base_scanner.py::test_remote_scanner_expires_connectable 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.00028798499997 207s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.00028798499997s, check logs on the scanner device for more information 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 3%] 207s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.00090388599997 207s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.00090388599997s, check logs on the scanner device for more information 207s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 901.000903886 207s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 901.000903886s, check logs on the scanner device for more information 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 4%] 207s tests/test_base_scanner.py::test_base_scanner_connecting_behavior 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 5%] 207s tests/test_base_scanner.py::test_scanner_stops_responding 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 120.00057162800002 207s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 120.00057162800002s, check logs on the scanner device for more information 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 6%] 207s tests/test_base_scanner.py::test_merge_manufacturer_data_history_existing 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 7%] 207s tests/test_base_scanner.py::test_merge_manufacturer_data_history_new 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 8%] 207s tests/test_base_scanner.py::test_filter_apple_data 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 207s PASSED [ 10%] 207s tests/test_base_scanner.py::test_connection_history_count_in_progress 207s -------------------------------- live log setup -------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s PASSED [ 11%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_base_scanner.py::test_connection_history_failure_count 207s -------------------------------- live log setup -------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s -------------------------------- live log call --------------------------------- 207s WARNING habluetooth.base_scanner:base_scanner.py:131 Removing a non-existing connecting hci0 (AA:BB:CC:DD:EE:00) 44:44:33:11:23:12 207s PASSED [ 12%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_init.py::test_create_scanner 207s -------------------------------- live log setup -------------------------------- 207s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 207s PASSED [ 13%] 207s tests/test_init.py::test_create_remote_scanner PASSED [ 14%] 207s tests/test_init.py::test__async_on_advertisement PASSED [ 15%] 207s tests/test_init.py::test__async_on_advertisement_first PASSED [ 16%] 207s tests/test_init.py::test__async_on_advertisement_prefers_longest_local_name PASSED [ 17%] 207s tests/test_init.py::test_create_ha_scanner PASSED [ 18%] 207s tests/test_manager.py::test_async_recover_failed_adapters 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 207s PASSED [ 20%] 207s tests/test_manager.py::test_create_manager 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 207s PASSED [ 21%] 207s tests/test_manager.py::test_async_register_disappeared_callback 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s -------------------------------- live log call --------------------------------- 207s ERROR habluetooth.manager:manager.py:425 Error in disappeared callback 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 423, in _async_check_unavailable 207s disappear_callback(address) 207s ~~~~~~~~~~~~~~~~~~^^^^^^^^^ 207s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 188, in _failing_callback 207s raise ValueError("This is a test") 207s ValueError: This is a test 207s PASSED [ 22%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_async_register_allocation_callback 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s -------------------------------- live log call --------------------------------- 207s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 207s callback_(ha_slot_allocations) 207s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 207s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 207s raise ValueError("This is a test") 207s ValueError: This is a test 207s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 207s callback_(ha_slot_allocations) 207s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 207s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 207s raise ValueError("This is a test") 207s ValueError: This is a test 207s PASSED [ 23%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_async_register_allocation_callback_non_connectable 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner non connectable (AA:BB:CC:DD:EE:FF) 207s PASSED [ 24%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner non connectable (AA:BB:CC:DD:EE:FF) 207s 207s tests/test_manager.py::test_async_register_scanner_registration_callback 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 207s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 207s callback_(HaScannerRegistration(event, scanner)) 207s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 207s raise ValueError("This is a test") 207s ValueError: This is a test 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 207s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 207s callback_(HaScannerRegistration(event, scanner)) 207s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 207s raise ValueError("This is a test") 207s ValueError: This is a test 207s PASSED [ 25%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_async_register_scanner_with_connection_slots 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 207s PASSED [ 26%] 207s tests/test_manager.py::test_diagnostics 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s PASSED [ 27%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_advertisements_do_not_switch_adapters_for_no_reason 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s PASSED [ 28%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_switching_adapters_based_on_rssi 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:480 wohand_good_signal (44:44:33:11:23:45): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 (AA:BB:CC:DD:EE:11) [connectable] (new rssi:-60 - threshold:16 > old rssi:-100) 207s PASSED [ 30%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_switching_adapters_based_on_zero_rssi 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:480 wohand_good_signal (44:44:33:11:23:45): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 (AA:BB:CC:DD:EE:11) [connectable] (new rssi:-60 - threshold:16 > old rssi:0) 207s PASSED [ 31%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_switching_adapters_based_on_stale 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:463 wohand_poor_signal_hci1 (44:44:33:11:23:41): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 [connectable] (time elapsed:901.0 > stale seconds:900) 207s PASSED [ 32%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_switching_adapters_based_on_stale_with_discovered_interval 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:463 wohand_poor_signal_hci1 (44:44:33:11:23:41): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 (AA:BB:CC:DD:EE:11) [connectable] (time elapsed:16.0 > stale seconds:15) 207s PASSED [ 33%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_switching_adapters_based_on_rssi_connectable_to_non_connectable 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:480 wohand_good_signal (44:44:33:11:23:45): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 (new rssi:-60 - threshold:16 > old rssi:-100) 207s PASSED [ 34%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_connectable_advertisement_can_be_retrieved_best_path_is_non_connectable 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 207s PASSED [ 35%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_switching_adapters_when_one_goes_away 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 207s PASSED [ 36%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_switching_adapters_when_one_stop_scanning 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 207s PASSED [ 37%] 207s ------------------------------ live log teardown ------------------------------- 207s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 207s 207s tests/test_manager.py::test_set_fallback_interval_small 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s PASSED [ 38%] 207s tests/test_manager.py::test_set_fallback_interval_big 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s PASSED [ 40%] 207s tests/test_manager.py::test_subclassing_bluetooth_manager 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s WARNING habluetooth.manager:manager.py:189 TestBluetoothManager2: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 207s PASSED [ 41%] 207s tests/test_models.py::test_model PASSED [ 42%] 207s tests/test_models.py::test_model_from_bleak PASSED [ 43%] 207s tests/test_models.py::test_model_from_scanner PASSED [ 44%] 207s tests/test_models.py::test_construct_service_info_bleak PASSED [ 45%] 207s tests/test_models.py::test_from_device_and_advertisement_data PASSED [ 46%] 207s tests/test_models.py::test_pyobjc_compat PASSED [ 47%] 207s tests/test_models.py::test_as_connectable PASSED [ 48%] 207s tests/test_scanner.py::test_empty_data_no_scanner 207s -------------------------------- live log setup -------------------------------- 207s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s PASSED [ 50%] 207s tests/test_scanner.py::test_dbus_socket_missing_in_container 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 207s await self.scanner.start() 207s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 207s raise effect 207s FileNotFoundError 207s PASSED [ 51%] 207s tests/test_scanner.py::test_dbus_socket_missing 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 207s await self.scanner.start() 207s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 207s raise effect 207s FileNotFoundError 207s PASSED [ 52%] 207s tests/test_scanner.py::test_handle_cancellation 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s PASSED [ 53%] 207s tests/test_scanner.py::test_handle_stop_while_starting 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s DEBUG habluetooth.scanner:scanner.py:452 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth scanner aborted: 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 207s await self.scanner.start() 207s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 149, in _start 207s await asyncio.sleep(1000) 207s File "/usr/lib/python3.14/asyncio/tasks.py", line 702, in sleep 207s return await future 207s ^^^^^^^^^^^^ 207s asyncio.exceptions.CancelledError: Interrupted by interrupt context manager 207s 207s The above exception was the direct cause of the following exception: 207s 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 342, in _async_start_attempt 207s async_interrupt.interrupt(self._start_future, _AbortStartError, None), 207s ~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s File "/usr/lib/python3/dist-packages/async_interrupt/__init__.py", line 90, in __aexit__ 207s raise self._exception from exc_val 207s habluetooth.scanner._AbortStartError 207s DEBUG habluetooth.scanner:scanner.py:571 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already stopped 207s PASSED [ 54%] 207s tests/test_scanner.py::test_dbus_broken_pipe_in_container 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s ERROR asyncio:base_events.py:1875 Future exception was never retrieved 207s future: 207s habluetooth.scanner._AbortStartError 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 207s await self.scanner.start() 207s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 207s raise effect 207s BrokenPipeError 207s PASSED [ 55%] 207s tests/test_scanner.py::test_dbus_broken_pipe 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 207s await self.scanner.start() 207s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 207s raise effect 207s BrokenPipeError 207s PASSED [ 56%] 207s tests/test_scanner.py::test_invalid_dbus_message 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s DEBUG habluetooth.scanner:scanner.py:495 hci0 (AA:BB:CC:DD:EE:FF): Invalid DBus message received: 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 207s await self.scanner.start() 207s File "/usr/lib/python3.14/unittest/mock.py", line 2326, in _execute_mock_call 207s raise effect 207s dbus_fast.errors.InvalidMessageError 207s PASSED [ 57%] 207s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.Failed] 207s -------------------------------- live log setup -------------------------------- 207s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 207s -------------------------------- live log call --------------------------------- 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.Failed 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 207s await self.scanner.start() 207s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 207s raise BleakError(error) 207s bleak.exc.BleakError: org.bluez.Error.Failed 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 207s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 207s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 207s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 207s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.Failed 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 207s await self.scanner.start() 207s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 207s raise BleakError(error) 207s bleak.exc.BleakError: org.bluez.Error.Failed 207s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 207s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 207s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 216s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:56 Timed out trying to connect to DBus; will not try again until next restart 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 58%] 216s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.InProgress] 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 216s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.InProgress 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 216s raise BleakError(error) 216s bleak.exc.BleakError: org.bluez.Error.InProgress 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 216s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 216s raise BleakError(error) 216s bleak.exc.BleakError: org.bluez.Error.InProgress 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 60%] 216s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.NotReady] 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.NotReady 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 216s raise BleakError(error) 216s bleak.exc.BleakError: org.bluez.Error.NotReady 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.NotReady 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 216s raise BleakError(error) 216s bleak.exc.BleakError: org.bluez.Error.NotReady 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 61%] 216s tests/test_scanner.py::test_adapter_needs_reset_at_start[not found] 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): not found 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 216s raise BleakError(error) 216s bleak.exc.BleakError: not found 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): not found 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 216s raise BleakError(error) 216s bleak.exc.BleakError: not found 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 62%] 216s tests/test_scanner.py::test_recovery_from_dbus_restart 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.000786321000007 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 110.00000000000001 216s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 110.00000000000001s, restarting 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 63%] 216s tests/test_scanner.py::test_adapter_recovery 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 216s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 64%] 216s tests/test_scanner.py::test_adapter_scanner_fails_to_start_first_time 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.00000000000001 216s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.00000000000001s, restarting 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): Failed to start 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 216s raise BleakError("Failed to start") 216s bleak.exc.BleakError: Failed to start 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): Failed to start 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 216s raise BleakError("Failed to start") 216s bleak.exc.BleakError: Failed to start 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 90.008080678 216s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 90.008080678s, restarting 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 65%] 216s tests/test_scanner.py::test_adapter_fails_to_start_and_takes_a_bit_to_init 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.freedesktop.DBus.Error.UnknownObject 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 599, in start 216s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 216s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 216s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 601, in start 216s raise BleakError("org.bluez.Error.InProgress") 216s bleak.exc.BleakError: org.bluez.Error.InProgress 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 216s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 603, in start 216s raise BleakError("org.bluez.Error.InProgress") 216s bleak.exc.BleakError: org.bluez.Error.InProgress 216s DEBUG habluetooth.scanner:scanner.py:324 hci0 (AA:BB:CC:DD:EE:FF): Falling back to passive scanning mode after active scanning failed (4/4) 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 216s WARNING habluetooth.scanner:scanner.py:411 hci0 (AA:BB:CC:DD:EE:FF): Successful fall-back to passive scanning mode after active scanning failed (4/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 66%] 216s tests/test_scanner.py::test_restart_takes_longer_than_watchdog_time 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 119.99999999999999 216s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 119.99999999999999s, restarting 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 119.99999999999999 216s DEBUG habluetooth.scanner:scanner.py:512 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already restarting, deferring restart 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:426 hci0 (AA:BB:CC:DD:EE:FF): TimeoutError while starting bluetooth; attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (2/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s ERROR asyncio:base_events.py:1875 Future exception was never retrieved 216s future: 216s habluetooth.scanner._AbortStartError 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 67%] 216s tests/test_scanner.py::test_setup_and_stop_macos SKIPPED (condition:...) [ 68%] 216s tests/test_scanner.py::test_adapter_init_fails_fallback_to_passive 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.freedesktop.DBus.Error.UnknownObject 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 780, in start 216s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 216s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 216s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 216s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 216s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 782, in start 216s raise BleakError("org.bluez.Error.InProgress") 216s bleak.exc.BleakError: org.bluez.Error.InProgress 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 216s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 216s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 216s await self.scanner.start() 216s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 784, in start 216s raise BleakError("org.bluez.Error.InProgress") 216s bleak.exc.BleakError: org.bluez.Error.InProgress 216s DEBUG habluetooth.scanner:scanner.py:324 hci0 (AA:BB:CC:DD:EE:FF): Falling back to passive scanning mode after active scanning failed (4/4) 216s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 216s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 216s WARNING habluetooth.scanner:scanner.py:411 hci0 (AA:BB:CC:DD:EE:FF): Successful fall-back to passive scanning mode after active scanning failed (4/4) 216s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 216s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 216s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 216s PASSED [ 70%] 216s tests/test_storage.py::test_discovered_device_advertisement_data_to_dict PASSED [ 71%] 216s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict PASSED [ 72%] 216s tests/test_storage.py::test_expire_stale_scanner_discovered_device_advertisement_data 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 216s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_expired 216s PASSED [ 73%] 216s tests/test_storage.py::test_expire_future_discovered_device_advertisement_data 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 216s WARNING habluetooth.storage:storage.py:107 Discarding timestamp 1765807904.1187983 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1764807904.1188085) 216s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_future 216s PASSED [ 74%] 216s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict_corrupt 216s -------------------------------- live log call --------------------------------- 216s ERROR habluetooth.storage:storage.py:147 Error deserializing discovered_device_advertisement_data, adapter startup will be slow: BLEDevice.__init__() missing 1 required positional argument: 'name' 216s Traceback (most recent call last): 216s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 138, in discovered_device_advertisement_data_from_dict 216s _deserialize_discovered_device_advertisement_datas( 216s ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 216s data[DISCOVERED_DEVICE_ADVERTISEMENT_DATAS] 216s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 216s ), 216s ^ 216s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 198, in _deserialize_discovered_device_advertisement_datas 216s _ble_device_from_dict(device_advertisement_data["device"]), 216s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 216s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 215, in _ble_device_from_dict 216s return BLEDevice(**device_data) 216s TypeError: BLEDevice.__init__() missing 1 required positional argument: 'name' 216s PASSED [ 75%] 216s tests/test_storage.py::test_backward_compatibility_rssi_in_device_dict PASSED [ 76%] 216s tests/test_wrappers.py::test_test_switch_adapters_when_out_of_slots 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:02: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:02 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:03: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:03 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:03 - any -> /org/bluez/hci1: Connecting via hci1 (00:00:00:00:00:02) (last rssi: -80) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:03 - any -> /org/bluez/hci1: Connected via hci1 (00:00:00:00:00:02) (last rssi: -80) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 77%] 216s tests/test_wrappers.py::test_release_slot_on_connect_failure 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 78%] 216s tests/test_wrappers.py::test_release_slot_on_connect_exception 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 80%] 216s tests/test_wrappers.py::test_switch_adapters_on_failure 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=1) (in_progress=0) (score=-60.0), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80), hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=2) (in_progress=0) (score=-60.0) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connecting via hci1 (00:00:00:00:00:02) (last rssi: -80) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connected via hci1 (00:00:00:00:00:02) (last rssi: -80) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80), hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=2) (in_progress=0) (score=-60.0) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connecting via hci1 (00:00:00:00:00:02) (last rssi: -80) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connected via hci1 (00:00:00:00:00:02) (last rssi: -80) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 81%] 216s tests/test_wrappers.py::test_switch_adapters_on_connecting 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80), hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=1) (score=-60.0) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connecting via hci1 (00:00:00:00:00:02) (last rssi: -80) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connected via hci1 (00:00:00:00:00:02) (last rssi: -80) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 82%] 216s tests/test_wrappers.py::test_single_adapter_connection_history 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:11: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:11 - any: Found 1 connection path(s), preferred order: hci0 (AA:BB:CC:DD:EE:00) (RSSI=-60) (failures=0) (in_progress=0) (score=-60) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:11 - any -> /org/bluez/hci0: Connecting via hci0 (AA:BB:CC:DD:EE:00) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:11 - any -> /org/bluez/hci0: Connected via hci0 (AA:BB:CC:DD:EE:00) (last rssi: -60) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 216s PASSED [ 83%] 216s tests/test_wrappers.py::test_passing_subclassed_str_as_address 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 84%] 216s tests/test_wrappers.py::test_find_device_by_address 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 85%] 216s tests/test_wrappers.py::test_discover 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 86%] 216s tests/test_wrappers.py::test_raise_after_shutdown 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 87%] 216s tests/test_wrappers.py::test_wrapped_instance_with_filter 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 216s PASSED [ 88%] 216s ------------------------------ live log teardown ------------------------------- 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 216s 216s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 216s PASSED [ 90%] 216s ------------------------------ live log teardown ------------------------------- 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 216s 216s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids_with_coro_callback 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 216s PASSED [ 91%] 216s ------------------------------ live log teardown ------------------------------- 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 216s 216s tests/test_wrappers.py::test_wrapped_instance_with_broken_callbacks 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 216s PASSED [ 92%] 216s ------------------------------ live log teardown ------------------------------- 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 216s 216s tests/test_wrappers.py::test_wrapped_instance_changes_uuids 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 216s PASSED [ 93%] 216s ------------------------------ live log teardown ------------------------------- 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 216s 216s tests/test_wrappers.py::test_wrapped_instance_changes_filters 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 216s PASSED [ 94%] 216s ------------------------------ live log teardown ------------------------------- 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 216s 216s tests/test_wrappers.py::test_wrapped_instance_unsupported_filter 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 216s -------------------------------- live log call --------------------------------- 216s WARNING habluetooth.wrappers:wrappers.py:120 Only UUIDs filters are supported 216s PASSED [ 95%] 216s ------------------------------ live log teardown ------------------------------- 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 216s 216s tests/test_wrappers.py::test_client_with_services_parameter 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 96%] 216s tests/test_wrappers.py::test_client_with_pair_parameter 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 97%] 216s tests/test_wrappers.py::test_client_services_normalization 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [ 98%] 216s tests/test_wrappers.py::test_client_with_none_services 216s -------------------------------- live log setup -------------------------------- 216s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 216s -------------------------------- live log call --------------------------------- 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 216s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 216s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 216s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 216s PASSED [100%] 216s ------------------------------ live log teardown ------------------------------- 216s DEBUG habluetooth.manager:manager.py:322 Stopping bluetooth manager 216s 216s 216s =============================== warnings summary =============================== 216s tests/test_base_scanner.py::test_remote_scanner[None] 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:1177: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s return asyncio.get_event_loop_policy() 216s 216s tests/test_base_scanner.py: 10 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 21 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:755: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s old_loop_policy = asyncio.get_event_loop_policy() 216s 216s tests/test_base_scanner.py: 10 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 21 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:760: DeprecationWarning: 'asyncio.set_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s asyncio.set_event_loop_policy(policy) 216s 216s tests/test_base_scanner.py: 9 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 21 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:856: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s _restore_event_loop_policy(asyncio.get_event_loop_policy()), 216s 216s tests/test_base_scanner.py: 9 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 21 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:1151: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s loop = asyncio.get_event_loop_policy().new_event_loop() 216s 216s tests/test_base_scanner.py: 9 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 21 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:871: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s policy = asyncio.get_event_loop_policy() 216s 216s tests/test_base_scanner.py: 9 warnings 216s tests/test_manager.py: 3 warnings 216s tests/test_wrappers.py: 14 warnings 216s /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/__init__.py:64: DeprecationWarning: Passing additional arguments for BLEDevice is deprecated and has no effect. 216s return BLEDevice(**new) 216s 216s tests/test_base_scanner.py: 9 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 22 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:777: DeprecationWarning: 'asyncio.set_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s asyncio.set_event_loop_policy(old_loop_policy) 216s 216s tests/test_base_scanner.py: 9 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 21 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:924: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s policy = asyncio.get_event_loop_policy() 216s 216s tests/test_base_scanner.py: 9 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 21 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:948: DeprecationWarning: 'asyncio.set_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s asyncio.set_event_loop_policy(previous_policy) 216s 216s tests/test_base_scanner.py: 9 warnings 216s tests/test_manager.py: 20 warnings 216s tests/test_scanner.py: 18 warnings 216s tests/test_wrappers.py: 21 warnings 216s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:960: DeprecationWarning: 'asyncio.get_event_loop_policy' is deprecated and slated for removal in Python 3.16 216s policy = asyncio.get_event_loop_policy() 216s 216s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 216s /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_base_scanner.py:326: FutureWarning: BaseHaRemoteScanner._discovered_device_timestamps is deprecated and will be removed in a future version of habluetooth, use BaseHaRemoteScanner.discovered_device_timestamps instead 216s assert len(scanner._discovered_device_timestamps) == 1 216s 216s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 216s /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_base_scanner.py:358: FutureWarning: BaseHaRemoteScanner._discovered_device_timestamps is deprecated and will be removed in a future version of habluetooth, use BaseHaRemoteScanner.discovered_device_timestamps instead 216s assert len(scanner._discovered_device_timestamps) == 0 216s 216s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 216s /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_base_scanner.py:377: FutureWarning: BaseHaRemoteScanner._discovered_device_timestamps is deprecated and will be removed in a future version of habluetooth, use BaseHaRemoteScanner.discovered_device_timestamps instead 216s assert len(scanner._discovered_device_timestamps) == 0 216s 216s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 216s ================= 89 passed, 1 skipped, 645 warnings in 9.84s ================== 216s I: pybuild base:317: cd /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build; python3.13 -m pytest --no-cov --ignore=tests/test_benchmark_base_scanner.py 217s /usr/lib/python3/dist-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset. 217s The event loop scope for asynchronous fixtures will default to the fixture caching scope. Future versions of pytest-asyncio will default the loop scope for asynchronous fixtures to function scope. Set the default fixture loop scope explicitly in order to avoid unexpected behavior in the future. Valid fixture loop scopes are: "function", "class", "module", "package", "session" 217s 217s warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET)) 217s ============================= test session starts ============================== 217s platform linux -- Python 3.13.9, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.13 217s cachedir: .pytest_cache 217s rootdir: /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build 217s configfile: pyproject.toml 217s plugins: typeguard-4.4.2, asyncio-0.25.1, cov-5.0.0 217s asyncio: mode=Mode.STRICT, asyncio_default_fixture_loop_scope=None 217s collecting ... collected 90 items 217s 217s tests/test_base_scanner.py::test_remote_scanner[None] 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 1%] 217s tests/test_base_scanner.py::test_remote_scanner[w] 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 2%] 217s tests/test_base_scanner.py::test_remote_scanner_expires_connectable 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.001624849 217s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.001624849s, check logs on the scanner device for more information 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 3%] 217s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 196.001682174 217s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 196.001682174s, check logs on the scanner device for more information 217s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 901.0016821739999 217s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 901.0016821739999s, check logs on the scanner device for more information 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 4%] 217s tests/test_base_scanner.py::test_base_scanner_connecting_behavior 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 5%] 217s tests/test_base_scanner.py::test_scanner_stops_responding 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.base_scanner:base_scanner.py:210 esp32: Scanner watchdog time_since_last_detection: 120.00167904499999 217s DEBUG habluetooth.base_scanner:base_scanner.py:225 esp32: Bluetooth scanner has gone quiet for 120.00167904499999s, check logs on the scanner device for more information 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 6%] 217s tests/test_base_scanner.py::test_merge_manufacturer_data_history_existing 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 7%] 217s tests/test_base_scanner.py::test_merge_manufacturer_data_history_new 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 8%] 217s tests/test_base_scanner.py::test_filter_apple_data 217s -------------------------------- live log setup -------------------------------- 217s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 217s -------------------------------- live log call --------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner esp32 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner esp32 217s PASSED [ 10%] 217s tests/test_base_scanner.py::test_connection_history_count_in_progress 217s -------------------------------- live log setup -------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 217s PASSED [ 11%] 217s ------------------------------ live log teardown ------------------------------- 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 217s 217s tests/test_base_scanner.py::test_connection_history_failure_count 217s -------------------------------- live log setup -------------------------------- 217s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 217s -------------------------------- live log call --------------------------------- 217s WARNING habluetooth.base_scanner:base_scanner.py:131 Removing a non-existing connecting hci0 (AA:BB:CC:DD:EE:00) 44:44:33:11:23:12 217s PASSED [ 12%] 217s ------------------------------ live log teardown ------------------------------- 217s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 217s 217s tests/test_init.py::test_create_scanner 217s -------------------------------- live log setup -------------------------------- 217s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 217s PASSED [ 13%] 217s tests/test_init.py::test_create_remote_scanner PASSED [ 14%] 217s tests/test_init.py::test__async_on_advertisement PASSED [ 15%] 217s tests/test_init.py::test__async_on_advertisement_first PASSED [ 16%] 218s tests/test_init.py::test__async_on_advertisement_prefers_longest_local_name PASSED [ 17%] 218s tests/test_init.py::test_create_ha_scanner PASSED [ 18%] 218s tests/test_manager.py::test_async_recover_failed_adapters 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 218s PASSED [ 20%] 218s tests/test_manager.py::test_create_manager 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 218s PASSED [ 21%] 218s tests/test_manager.py::test_async_register_disappeared_callback 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s -------------------------------- live log call --------------------------------- 218s ERROR habluetooth.manager:manager.py:425 Error in disappeared callback 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 423, in _async_check_unavailable 218s disappear_callback(address) 218s ~~~~~~~~~~~~~~~~~~^^^^^^^^^ 218s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 188, in _failing_callback 218s raise ValueError("This is a test") 218s ValueError: This is a test 218s PASSED [ 22%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_async_register_allocation_callback 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s -------------------------------- live log call --------------------------------- 218s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 218s callback_(ha_slot_allocations) 218s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 218s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 218s raise ValueError("This is a test") 218s ValueError: This is a test 218s ERROR habluetooth.manager:manager.py:850 Error in allocation callback 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 848, in async_on_allocation_changed 218s callback_(ha_slot_allocations) 218s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^ 218s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 259, in _failing_callback 218s raise ValueError("This is a test") 218s ValueError: This is a test 218s PASSED [ 23%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_async_register_allocation_callback_non_connectable 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner non connectable (AA:BB:CC:DD:EE:FF) 218s PASSED [ 24%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner non connectable (AA:BB:CC:DD:EE:FF) 218s 218s tests/test_manager.py::test_async_register_scanner_registration_callback 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 218s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 218s callback_(HaScannerRegistration(event, scanner)) 218s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 218s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 218s raise ValueError("This is a test") 218s ValueError: This is a test 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 218s ERROR habluetooth.manager:manager.py:867 Error in scanner callback 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/manager.py", line 865, in _async_on_scanner_registration 218s callback_(HaScannerRegistration(event, scanner)) 218s ~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 218s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_manager.py", line 381, in _failing_callback 218s raise ValueError("This is a test") 218s ValueError: This is a test 218s PASSED [ 25%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_async_register_scanner_with_connection_slots 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci3 (AA:BB:CC:DD:EE:33) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci3 (AA:BB:CC:DD:EE:33) 218s PASSED [ 26%] 218s tests/test_manager.py::test_diagnostics 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s PASSED [ 27%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_advertisements_do_not_switch_adapters_for_no_reason 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s PASSED [ 28%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_switching_adapters_based_on_rssi 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:480 wohand_good_signal (44:44:33:11:23:45): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 (AA:BB:CC:DD:EE:11) [connectable] (new rssi:-60 - threshold:16 > old rssi:-100) 218s PASSED [ 30%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_switching_adapters_based_on_zero_rssi 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:480 wohand_good_signal (44:44:33:11:23:45): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 (AA:BB:CC:DD:EE:11) [connectable] (new rssi:-60 - threshold:16 > old rssi:0) 218s PASSED [ 31%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_switching_adapters_based_on_stale 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:463 wohand_poor_signal_hci1 (44:44:33:11:23:41): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 [connectable] (time elapsed:901.0 > stale seconds:900) 218s PASSED [ 32%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_switching_adapters_based_on_stale_with_discovered_interval 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:463 wohand_poor_signal_hci1 (44:44:33:11:23:41): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 (AA:BB:CC:DD:EE:11) [connectable] (time elapsed:16.0 > stale seconds:15) 218s PASSED [ 33%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_switching_adapters_based_on_rssi_connectable_to_non_connectable 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:480 wohand_good_signal (44:44:33:11:23:45): Switching from hci0 (AA:BB:CC:DD:EE:00) [connectable] to hci1 (new rssi:-60 - threshold:16 > old rssi:-100) 218s PASSED [ 34%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_connectable_advertisement_can_be_retrieved_best_path_is_non_connectable 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (AA:BB:CC:DD:EE:11) 218s PASSED [ 35%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (AA:BB:CC:DD:EE:11) 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_switching_adapters_when_one_goes_away 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 218s PASSED [ 36%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_switching_adapters_when_one_stop_scanning 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci2 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci2 218s PASSED [ 37%] 218s ------------------------------ live log teardown ------------------------------- 218s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 218s 218s tests/test_manager.py::test_set_fallback_interval_small 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s PASSED [ 38%] 218s tests/test_manager.py::test_set_fallback_interval_big 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s PASSED [ 40%] 218s tests/test_manager.py::test_subclassing_bluetooth_manager 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s WARNING habluetooth.manager:manager.py:189 TestBluetoothManager2: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 218s PASSED [ 41%] 218s tests/test_models.py::test_model PASSED [ 42%] 218s tests/test_models.py::test_model_from_bleak PASSED [ 43%] 218s tests/test_models.py::test_model_from_scanner PASSED [ 44%] 218s tests/test_models.py::test_construct_service_info_bleak PASSED [ 45%] 218s tests/test_models.py::test_from_device_and_advertisement_data PASSED [ 46%] 218s tests/test_models.py::test_pyobjc_compat PASSED [ 47%] 218s tests/test_models.py::test_as_connectable PASSED [ 48%] 218s tests/test_scanner.py::test_empty_data_no_scanner 218s -------------------------------- live log setup -------------------------------- 218s WARNING habluetooth.manager:manager.py:189 BluetoothManager: does not implement _discover_service_info, subclasses must implement this method to consume discovery data 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s PASSED [ 50%] 218s tests/test_scanner.py::test_dbus_socket_missing_in_container 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 218s await self.scanner.start() 218s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 218s raise effect 218s FileNotFoundError 218s PASSED [ 51%] 218s tests/test_scanner.py::test_dbus_socket_missing 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s DEBUG habluetooth.scanner:scanner.py:462 hci0 (AA:BB:CC:DD:EE:FF): FileNotFoundError while starting bluetooth: 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 218s await self.scanner.start() 218s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 218s raise effect 218s FileNotFoundError 218s PASSED [ 52%] 218s tests/test_scanner.py::test_handle_cancellation 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s PASSED [ 53%] 218s tests/test_scanner.py::test_handle_stop_while_starting 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s DEBUG habluetooth.scanner:scanner.py:452 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth scanner aborted: 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 218s await self.scanner.start() 218s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 149, in _start 218s await asyncio.sleep(1000) 218s File "/usr/lib/python3.13/asyncio/tasks.py", line 718, in sleep 218s return await future 218s ^^^^^^^^^^^^ 218s asyncio.exceptions.CancelledError: Interrupted by interrupt context manager 218s 218s The above exception was the direct cause of the following exception: 218s 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 342, in _async_start_attempt 218s async_interrupt.interrupt(self._start_future, _AbortStartError, None), 218s ~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 218s File "/usr/lib/python3/dist-packages/async_interrupt/__init__.py", line 90, in __aexit__ 218s raise self._exception from exc_val 218s habluetooth.scanner._AbortStartError 218s DEBUG habluetooth.scanner:scanner.py:571 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already stopped 218s PASSED [ 54%] 218s tests/test_scanner.py::test_dbus_broken_pipe_in_container 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 218s await self.scanner.start() 218s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 218s raise effect 218s BrokenPipeError 218s PASSED [ 55%] 218s tests/test_scanner.py::test_dbus_broken_pipe 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s ERROR asyncio:base_events.py:1879 Future exception was never retrieved 218s future: 218s habluetooth.scanner._AbortStartError 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s DEBUG habluetooth.scanner:scanner.py:480 hci0 (AA:BB:CC:DD:EE:FF): DBus connection broken: 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 218s await self.scanner.start() 218s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 218s raise effect 218s BrokenPipeError 218s PASSED [ 56%] 218s tests/test_scanner.py::test_invalid_dbus_message 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s DEBUG habluetooth.scanner:scanner.py:495 hci0 (AA:BB:CC:DD:EE:FF): Invalid DBus message received: 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 218s await self.scanner.start() 218s File "/usr/lib/python3.13/unittest/mock.py", line 2321, in _execute_mock_call 218s raise effect 218s dbus_fast.errors.InvalidMessageError 218s PASSED [ 57%] 218s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.Failed] 218s -------------------------------- live log setup -------------------------------- 218s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 218s -------------------------------- live log call --------------------------------- 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.Failed 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 218s await self.scanner.start() 218s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 218s raise BleakError(error) 218s bleak.exc.BleakError: org.bluez.Error.Failed 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 218s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 218s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 218s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 218s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.Failed 218s Traceback (most recent call last): 218s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 218s await self.scanner.start() 218s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 218s raise BleakError(error) 218s bleak.exc.BleakError: org.bluez.Error.Failed 218s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 218s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 218s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 226s DEBUG bleak_retry_connector.bleak_manager:bleak_manager.py:56 Timed out trying to connect to DBus; will not try again until next restart 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 58%] 226s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.InProgress] 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 226s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.InProgress 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 226s raise BleakError(error) 226s bleak.exc.BleakError: org.bluez.Error.InProgress 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 226s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 226s raise BleakError(error) 226s bleak.exc.BleakError: org.bluez.Error.InProgress 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 60%] 226s tests/test_scanner.py::test_adapter_needs_reset_at_start[org.bluez.Error.NotReady] 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.bluez.Error.NotReady 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 226s raise BleakError(error) 226s bleak.exc.BleakError: org.bluez.Error.NotReady 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.NotReady 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 226s raise BleakError(error) 226s bleak.exc.BleakError: org.bluez.Error.NotReady 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 61%] 226s tests/test_scanner.py::test_adapter_needs_reset_at_start[not found] 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): not found 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 226s raise BleakError(error) 226s bleak.exc.BleakError: not found 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): not found 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 249, in start 226s raise BleakError(error) 226s bleak.exc.BleakError: not found 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 62%] 226s tests/test_scanner.py::test_recovery_from_dbus_restart 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.001045106000007 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 110.0 226s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 110.0s, restarting 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 63%] 226s tests/test_scanner.py::test_adapter_recovery 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.000000000000014 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.00000000000001 226s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.00000000000001s, restarting 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 64%] 226s tests/test_scanner.py::test_adapter_scanner_fails_to_start_first_time 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 10.0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 20.0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 120.0 226s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 120.0s, restarting 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): Failed to start 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 226s raise BleakError("Failed to start") 226s bleak.exc.BleakError: Failed to start 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): Failed to start 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 485, in start 226s raise BleakError("Failed to start") 226s bleak.exc.BleakError: Failed to start 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (3/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 90.00758270700001 226s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 90.00758270700001s, restarting 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 65%] 226s tests/test_scanner.py::test_adapter_fails_to_start_and_takes_a_bit_to_init 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.freedesktop.DBus.Error.UnknownObject 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 599, in start 226s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 226s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 226s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 601, in start 226s raise BleakError("org.bluez.Error.InProgress") 226s bleak.exc.BleakError: org.bluez.Error.InProgress 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 226s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 603, in start 226s raise BleakError("org.bluez.Error.InProgress") 226s bleak.exc.BleakError: org.bluez.Error.InProgress 226s DEBUG habluetooth.scanner:scanner.py:324 hci0 (AA:BB:CC:DD:EE:FF): Falling back to passive scanning mode after active scanning failed (4/4) 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 226s WARNING habluetooth.scanner:scanner.py:411 hci0 (AA:BB:CC:DD:EE:FF): Successful fall-back to passive scanning mode after active scanning failed (4/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 66%] 226s tests/test_scanner.py::test_restart_takes_longer_than_watchdog_time 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (1/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 119.99999999999999 226s DEBUG habluetooth.scanner:scanner.py:517 hci0 (AA:BB:CC:DD:EE:FF): Bluetooth scanner has gone quiet for 119.99999999999999s, restarting 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.base_scanner:base_scanner.py:210 hci0 (AA:BB:CC:DD:EE:FF): Scanner watchdog time_since_last_detection: 119.99999999999999 226s DEBUG habluetooth.scanner:scanner.py:512 hci0 (AA:BB:CC:DD:EE:FF): Scanner is already restarting, deferring restart 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:426 hci0 (AA:BB:CC:DD:EE:FF): TimeoutError while starting bluetooth; attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (2/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s ERROR asyncio:base_events.py:1879 Future exception was never retrieved 226s future: 226s habluetooth.scanner._AbortStartError 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 67%] 226s tests/test_scanner.py::test_setup_and_stop_macos SKIPPED (condition:...) [ 68%] 226s tests/test_scanner.py::test_adapter_init_fails_fallback_to_passive 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (1/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:402 hci0 (AA:BB:CC:DD:EE:FF): Waiting for adapter to initialize; attempt (1/4) 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (1/4): org.freedesktop.DBus.Error.UnknownObject 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 780, in start 226s raise BleakError("org.freedesktop.DBus.Error.UnknownObject") 226s bleak.exc.BleakError: org.freedesktop.DBus.Error.UnknownObject 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (2/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 226s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 226s DEBUG habluetooth.scanner:scanner.py:555 hci0 (AA:BB:CC:DD:EE:FF): adapter stopped responding; executing reset 226s DEBUG habluetooth.scanner:scanner.py:557 hci0 (AA:BB:CC:DD:EE:FF): adapter reset result: True 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (2/4): org.bluez.Error.InProgress 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 782, in start 226s raise BleakError("org.bluez.Error.InProgress") 226s bleak.exc.BleakError: org.bluez.Error.InProgress 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'active', 'adapter': 'hci0'} 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (3/4) 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s DEBUG habluetooth.scanner:scanner.py:586 hci0 (AA:BB:CC:DD:EE:FF): Force stopping bluetooth discovery 226s ERROR bleak_retry_connector.bluez:bluez.py:324 Failed to stop discovery for hci0 because no manager 226s DEBUG habluetooth.scanner:scanner.py:434 hci0 (AA:BB:CC:DD:EE:FF): BleakError while starting bluetooth; attempt: (3/4): org.bluez.Error.InProgress 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/scanner.py", line 344, in _async_start_attempt 226s await self.scanner.start() 226s File "/tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_scanner.py", line 784, in start 226s raise BleakError("org.bluez.Error.InProgress") 226s bleak.exc.BleakError: org.bluez.Error.InProgress 226s DEBUG habluetooth.scanner:scanner.py:324 hci0 (AA:BB:CC:DD:EE:FF): Falling back to passive scanning mode after active scanning failed (4/4) 226s DEBUG habluetooth.scanner:scanner.py:142 Initializing bluetooth scanner with {'detection_callback': >, 'scanning_mode': 'passive', 'adapter': 'hci0', 'bluez': } 226s DEBUG habluetooth.scanner:scanner.py:444 hci0 (AA:BB:CC:DD:EE:FF): Starting bluetooth discovery attempt: (4/4) 226s WARNING habluetooth.scanner:scanner.py:411 hci0 (AA:BB:CC:DD:EE:FF): Successful fall-back to passive scanning mode after active scanning failed (4/4) 226s DEBUG habluetooth.scanner:scanner.py:418 hci0 (AA:BB:CC:DD:EE:FF): Success while starting bluetooth; attempt: (4/4) 226s DEBUG bleak_retry_connector:__init__.py:639 Failed to restore discoveries for hci0 226s DEBUG habluetooth.scanner:scanner.py:573 hci0 (AA:BB:CC:DD:EE:FF): Stopping bluetooth discovery 226s PASSED [ 70%] 226s tests/test_storage.py::test_discovered_device_advertisement_data_to_dict PASSED [ 71%] 226s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict PASSED [ 72%] 226s tests/test_storage.py::test_expire_stale_scanner_discovered_device_advertisement_data 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 226s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_expired 226s PASSED [ 73%] 226s tests/test_storage.py::test_expire_future_discovered_device_advertisement_data 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.storage:storage.py:122 Loaded 1 fresh discovered devices for myscanner 226s WARNING habluetooth.storage:storage.py:107 Discarding timestamp 1765807914.6541836 for CC:DD:EE:FF:AA:BB on scanner all_future as it is the future (now = 1764807914.654196) 226s DEBUG habluetooth.storage:storage.py:122 Loaded 0 fresh discovered devices for all_future 226s PASSED [ 74%] 226s tests/test_storage.py::test_discovered_device_advertisement_data_from_dict_corrupt 226s -------------------------------- live log call --------------------------------- 226s ERROR habluetooth.storage:storage.py:147 Error deserializing discovered_device_advertisement_data, adapter startup will be slow: BLEDevice.__init__() missing 1 required positional argument: 'name' 226s Traceback (most recent call last): 226s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 138, in discovered_device_advertisement_data_from_dict 226s _deserialize_discovered_device_advertisement_datas( 226s ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^ 226s data[DISCOVERED_DEVICE_ADVERTISEMENT_DATAS] 226s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 226s ), 226s ^ 226s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 198, in _deserialize_discovered_device_advertisement_datas 226s _ble_device_from_dict(device_advertisement_data["device"]), 226s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 226s File "/usr/lib/python3/dist-packages/habluetooth/storage.py", line 215, in _ble_device_from_dict 226s return BLEDevice(**device_data) 226s TypeError: BLEDevice.__init__() missing 1 required positional argument: 'name' 226s PASSED [ 75%] 226s tests/test_storage.py::test_backward_compatibility_rssi_in_device_dict PASSED [ 76%] 226s tests/test_wrappers.py::test_test_switch_adapters_when_out_of_slots 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 226s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:02: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:02 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:03: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:03 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:03 - any -> /org/bluez/hci1: Connecting via hci1 (00:00:00:00:00:02) (last rssi: -80) 226s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:03 - any -> /org/bluez/hci1: Connected via hci1 (00:00:00:00:00:02) (last rssi: -80) 226s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 226s PASSED [ 77%] 226s tests/test_wrappers.py::test_release_slot_on_connect_failure 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 226s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 226s PASSED [ 78%] 226s tests/test_wrappers.py::test_release_slot_on_connect_exception 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 226s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 226s PASSED [ 80%] 226s tests/test_wrappers.py::test_switch_adapters_on_failure 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=1) (in_progress=0) (score=-60.0), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80), hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=2) (in_progress=0) (score=-60.0) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connecting via hci1 (00:00:00:00:00:02) (last rssi: -80) 226s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connected via hci1 (00:00:00:00:00:02) (last rssi: -80) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80), hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=2) (in_progress=0) (score=-60.0) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connecting via hci1 (00:00:00:00:00:02) (last rssi: -80) 226s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connected via hci1 (00:00:00:00:00:02) (last rssi: -80) 226s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 226s PASSED [ 81%] 226s tests/test_wrappers.py::test_switch_adapters_on_connecting 226s -------------------------------- live log setup -------------------------------- 226s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 226s -------------------------------- live log call --------------------------------- 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 226s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 226s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 226s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80), hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=1) (score=-60.0) 226s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connecting via hci1 (00:00:00:00:00:02) (last rssi: -80) 226s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci1: Connected via hci1 (00:00:00:00:00:02) (last rssi: -80) 227s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 227s PASSED [ 82%] 227s tests/test_wrappers.py::test_single_adapter_connection_history 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 227s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:11: Looking for backend to connect 227s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:11 - any: Found 1 connection path(s), preferred order: hci0 (AA:BB:CC:DD:EE:00) (RSSI=-60) (failures=0) (in_progress=0) (score=-60) 227s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:11 - any -> /org/bluez/hci0: Connecting via hci0 (AA:BB:CC:DD:EE:00) (last rssi: -60) 227s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:11 - any -> /org/bluez/hci0: Connected via hci0 (AA:BB:CC:DD:EE:00) (last rssi: -60) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 227s PASSED [ 83%] 227s tests/test_wrappers.py::test_passing_subclassed_str_as_address 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 227s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 227s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 227s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 227s PASSED [ 84%] 227s tests/test_wrappers.py::test_find_device_by_address 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 227s PASSED [ 85%] 227s tests/test_wrappers.py::test_discover 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 227s PASSED [ 86%] 227s tests/test_wrappers.py::test_raise_after_shutdown 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 227s PASSED [ 87%] 227s tests/test_wrappers.py::test_wrapped_instance_with_filter 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 227s PASSED [ 88%] 227s ------------------------------ live log teardown ------------------------------- 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 227s 227s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 227s PASSED [ 90%] 227s ------------------------------ live log teardown ------------------------------- 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 227s 227s tests/test_wrappers.py::test_wrapped_instance_with_service_uuids_with_coro_callback 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 227s PASSED [ 91%] 227s ------------------------------ live log teardown ------------------------------- 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 227s 227s tests/test_wrappers.py::test_wrapped_instance_with_broken_callbacks 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 227s PASSED [ 92%] 227s ------------------------------ live log teardown ------------------------------- 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 227s 227s tests/test_wrappers.py::test_wrapped_instance_changes_uuids 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 227s PASSED [ 93%] 227s ------------------------------ live log teardown ------------------------------- 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 227s 227s tests/test_wrappers.py::test_wrapped_instance_changes_filters 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 227s PASSED [ 94%] 227s ------------------------------ live log teardown ------------------------------- 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 227s 227s tests/test_wrappers.py::test_wrapped_instance_unsupported_filter 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (AA:BB:CC:DD:EE:00) 227s -------------------------------- live log call --------------------------------- 227s WARNING habluetooth.wrappers:wrappers.py:120 Only UUIDs filters are supported 227s PASSED [ 95%] 227s ------------------------------ live log teardown ------------------------------- 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (AA:BB:CC:DD:EE:00) 227s 227s tests/test_wrappers.py::test_client_with_services_parameter 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 227s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 227s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 227s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 227s PASSED [ 96%] 227s tests/test_wrappers.py::test_client_with_pair_parameter 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 227s PASSED [ 97%] 227s tests/test_wrappers.py::test_client_services_normalization 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 227s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 227s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 227s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 227s PASSED [ 98%] 227s tests/test_wrappers.py::test_client_with_none_services 227s -------------------------------- live log setup -------------------------------- 227s DEBUG asyncio:selector_events.py:64 Using selector: EpollSelector 227s -------------------------------- live log call --------------------------------- 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:765 Registering scanner hci1 (00:00:00:00:00:02) 227s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 227s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 227s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.wrappers:wrappers.py:279 00:00:00:00:00:01: Looking for backend to connect 227s INFO habluetooth.wrappers:wrappers.py:383 00:00:00:00:00:01 - any: Found 2 connection path(s), preferred order: hci0 (00:00:00:00:00:01) (RSSI=-60) (failures=0) (in_progress=0) (score=-60), hci1 (00:00:00:00:00:02) (RSSI=-80) (failures=0) (in_progress=0) (score=-80) 227s DEBUG habluetooth.wrappers:wrappers.py:305 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connecting via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.wrappers:wrappers.py:326 00:00:00:00:00:01 - any -> /org/bluez/hci0: Connected via hci0 (00:00:00:00:00:01) (last rssi: -60) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci0 (00:00:00:00:00:01) 227s DEBUG habluetooth.manager:manager.py:748 Unregistering scanner hci1 (00:00:00:00:00:02) 227s PASSED [100%] 227s ------------------------------ live log teardown ------------------------------- 227s DEBUG habluetooth.manager:manager.py:322 Stopping bluetooth manager 227s 227s 227s =============================== warnings summary =============================== 227s tests/test_base_scanner.py: 9 warnings 227s tests/test_manager.py: 3 warnings 227s tests/test_wrappers.py: 14 warnings 227s /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/__init__.py:64: DeprecationWarning: Passing additional arguments for BLEDevice is deprecated and has no effect. 227s return BLEDevice(**new) 227s 227s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 227s /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_base_scanner.py:326: FutureWarning: BaseHaRemoteScanner._discovered_device_timestamps is deprecated and will be removed in a future version of habluetooth, use BaseHaRemoteScanner.discovered_device_timestamps instead 227s assert len(scanner._discovered_device_timestamps) == 1 227s 227s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 227s /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_base_scanner.py:358: FutureWarning: BaseHaRemoteScanner._discovered_device_timestamps is deprecated and will be removed in a future version of habluetooth, use BaseHaRemoteScanner.discovered_device_timestamps instead 227s assert len(scanner._discovered_device_timestamps) == 0 227s 227s tests/test_base_scanner.py::test_remote_scanner_expires_non_connectable 227s /tmp/autopkgtest.qGbRkL/autopkgtest_tmp/build/tests/test_base_scanner.py:377: FutureWarning: BaseHaRemoteScanner._discovered_device_timestamps is deprecated and will be removed in a future version of habluetooth, use BaseHaRemoteScanner.discovered_device_timestamps instead 227s assert len(scanner._discovered_device_timestamps) == 0 227s 227s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 227s ================== 89 passed, 1 skipped, 29 warnings in 9.91s ================== 227s autopkgtest [00:25:15]: test pybuild-autopkgtest: -----------------------] 228s autopkgtest [00:25:16]: test pybuild-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 228s pybuild-autopkgtest PASS 228s autopkgtest [00:25:16]: @@@@@@@@@@@@@@@@@@@@ summary 228s pybuild-autopkgtest PASS